[14:36:00.702] INFO: *** Welcome to pxar ***
[14:36:00.702] INFO: *** Today: 2016/09/13
[14:36:01.523] INFO: *** Version: v1.9.0-818-g96727
[14:36:01.523] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//dacParameters35_C15.dat
[14:36:01.558] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:36:01.558] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//defaultMaskFile.dat
[14:36:01.561] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C15.dat
[14:36:01.735] INFO: clk: 4
[14:36:01.735] INFO: ctr: 4
[14:36:01.735] INFO: sda: 19
[14:36:01.735] INFO: tin: 9
[14:36:01.735] INFO: level: 15
[14:36:01.735] INFO: triggerdelay: 0
[14:36:01.753] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:36:01.753] INFO: Log level: INFO
[14:36:01.778] QUIET: Connection to board DTB_WREKRL opened.
[14:36:01.782] 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:
------------------------------------------------------
[14:36:01.784] INFO: RPC call hashes of host and DTB match: 398089610
[14:36:03.321] INFO: DUT info:
[14:36:03.321] INFO: The DUT currently contains the following objects:
[14:36:03.321] INFO: 2 TBM Cores tbm08c (2 ON)
[14:36:03.321] INFO: TBM Core alpha (0): 7 registers set
[14:36:03.321] INFO: TBM Core beta (1): 7 registers set
[14:36:03.321] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:36:03.321] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.321] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.321] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.321] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.321] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.322] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.322] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.322] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.322] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.322] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.322] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.322] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.322] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.322] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.322] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.322] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:36:03.777] INFO: enter 'restricted' command line mode
[14:36:03.777] INFO: enter test to run
[14:36:18.074] INFO: test: PixelAlive no parameter change
[14:36:18.074] INFO: running: pixelalive
[14:36:18.099] INFO: ----------------------------------------------------------------------
[14:36:18.099] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:36:18.099] INFO: ----------------------------------------------------------------------
[14:36:18.418] INFO: Expecting 41600 events.
[14:36:22.742] INFO: 41600 events read in total (3606ms).
[14:36:22.907] INFO: Test took 4806ms.
[14:36:22.922] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:23.270] INFO: PixTestAlive::aliveTest() done
[14:36:23.270] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[14:36:23.301] INFO: enter test to run
[14:36:44.802] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:36:44.802] INFO: running: highrate
[14:36:44.802] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:36:46.748] INFO: ----------------------------------------------------------------------
[14:36:46.748] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:36:46.748] INFO: ----------------------------------------------------------------------
[14:36:46.748] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:36:46.748] INFO: edge/corner pixel THR is adjusted
[14:36:46.748] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:36:47.721] INFO: Collecting data for 5 seconds...
[14:36:52.739] INFO: Done with hot pixel readout
[14:37:04.451] INFO: PixTest:: pg_setup set to default.
[14:37:04.451] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:37:04.452] INFO: 52 hot pixels found in step 0
[14:37:05.443] INFO: Collecting data for 5 seconds...
[14:37:10.462] INFO: Done with hot pixel readout
[14:37:22.072] INFO: PixTest:: pg_setup set to default.
[14:37:22.072] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:37:22.072] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:37:22.073] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:37:22.073] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:37:22.073] INFO: 56 hot pixels found in step 1
[14:37:23.072] INFO: Collecting data for 5 seconds...
[14:37:28.089] INFO: Done with hot pixel readout
[14:37:39.357] INFO: PixTest:: pg_setup set to default.
[14:37:39.358] INFO: 46 hot pixels found in step 2
[14:37:40.352] INFO: Collecting data for 5 seconds...
[14:37:45.370] INFO: Done with hot pixel readout
[14:37:56.740] INFO: PixTest:: pg_setup set to default.
[14:37:56.740] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:37:56.741] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:37:56.741] INFO: 47 hot pixels found in step 3
[14:37:57.736] INFO: Collecting data for 5 seconds...
[14:38:02.750] INFO: Done with hot pixel readout
[14:38:13.940] INFO: PixTest:: pg_setup set to default.
[14:38:13.940] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:38:13.941] INFO: 48 hot pixels found in step 4
[14:38:14.935] INFO: Collecting data for 5 seconds...
[14:38:19.951] INFO: Done with hot pixel readout
[14:38:31.168] INFO: PixTest:: pg_setup set to default.
[14:38:31.168] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:38:31.168] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:38:31.168] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:38:31.168] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:38:31.169] INFO: 46 hot pixels found in step 5
[14:38:32.164] INFO: Collecting data for 5 seconds...
[14:38:37.180] INFO: Done with hot pixel readout
[14:38:48.486] INFO: PixTest:: pg_setup set to default.
[14:38:48.486] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:38:48.486] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:38:48.486] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:38:48.486] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:38:48.486] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:38:48.487] INFO: 44 hot pixels found in step 6
[14:38:49.480] INFO: Collecting data for 5 seconds...
[14:38:54.497] INFO: Done with hot pixel readout
[14:39:05.866] INFO: PixTest:: pg_setup set to default.
[14:39:05.866] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:05.866] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:05.866] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:05.866] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:05.866] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:05.866] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:05.867] INFO: 38 hot pixels found in step 7
[14:39:06.862] INFO: Collecting data for 5 seconds...
[14:39:11.879] INFO: Done with hot pixel readout
[14:39:23.326] INFO: PixTest:: pg_setup set to default.
[14:39:23.326] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:23.326] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:23.326] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:23.326] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:23.326] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:23.326] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:23.327] INFO: 46 hot pixels found in step 8
[14:39:24.321] INFO: Collecting data for 5 seconds...
[14:39:29.338] INFO: Done with hot pixel readout
[14:39:40.751] INFO: PixTest:: pg_setup set to default.
[14:39:40.751] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:40.751] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:40.751] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:40.751] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:40.751] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:40.751] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:40.751] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:40.751] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:40.752] INFO: 32 hot pixels found in step 9
[14:39:41.746] INFO: Collecting data for 5 seconds...
[14:39:46.763] INFO: Done with hot pixel readout
[14:39:58.304] INFO: PixTest:: pg_setup set to default.
[14:39:58.304] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:58.304] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:58.304] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:58.304] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:58.304] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:58.304] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:58.304] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:58.304] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:39:58.305] INFO: 31 hot pixels found in step 10
[14:39:59.307] INFO: Collecting data for 5 seconds...
[14:40:04.324] INFO: Done with hot pixel readout
[14:40:15.698] INFO: PixTest:: pg_setup set to default.
[14:40:15.698] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:15.698] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:15.698] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:15.698] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:15.698] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:15.698] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:15.699] INFO: 27 hot pixels found in step 11
[14:40:16.692] INFO: Collecting data for 5 seconds...
[14:40:21.710] INFO: Done with hot pixel readout
[14:40:33.178] INFO: PixTest:: pg_setup set to default.
[14:40:33.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:33.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:33.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:33.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:33.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:33.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:33.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:33.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:33.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:33.180] INFO: 36 hot pixels found in step 12
[14:40:34.174] INFO: Collecting data for 5 seconds...
[14:40:39.192] INFO: Done with hot pixel readout
[14:40:50.581] INFO: PixTest:: pg_setup set to default.
[14:40:50.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:50.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:50.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:50.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:50.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:50.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:40:50.582] INFO: 24 hot pixels found in step 13
[14:40:51.580] INFO: Collecting data for 5 seconds...
[14:40:56.597] INFO: Done with hot pixel readout
[14:41:08.055] INFO: PixTest:: pg_setup set to default.
[14:41:08.055] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:41:08.055] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:41:08.055] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:41:08.055] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:41:08.055] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:41:08.055] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:41:08.055] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:41:08.055] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:41:08.055] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:41:08.055] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:41:08.055] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:41:08.056] INFO: 26 hot pixels found in step 14
[14:41:08.094] INFO: 26 hot pixels could not be trimmed and have been masked.
[14:41:08.098] INFO: PixTest::trimHotPixels() done
[14:41:08.098] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C0.dat
[14:41:08.103] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C1.dat
[14:41:08.110] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C2.dat
[14:41:08.116] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C3.dat
[14:41:08.121] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C4.dat
[14:41:08.126] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C5.dat
[14:41:08.132] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C6.dat
[14:41:08.137] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C7.dat
[14:41:08.143] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C8.dat
[14:41:08.165] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C9.dat
[14:41:08.171] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C10.dat
[14:41:08.177] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C11.dat
[14:41:08.186] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C12.dat
[14:41:08.191] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C13.dat
[14:41:08.197] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C14.dat
[14:41:08.202] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C15.dat
[14:41:08.207] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//defaultMaskFile.dat
[14:41:08.219] INFO: enter test to run
[14:41:38.672] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:41:38.672] INFO: running: highrate
[14:41:38.677] INFO: ----------------------------------------------------------------------
[14:41:38.677] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:41:38.677] INFO: ----------------------------------------------------------------------
[14:41:38.677] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:41:38.677] INFO: edge/corner pixel THR is adjusted
[14:41:38.677] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:41:39.634] INFO: Collecting data for 1 seconds...
[14:41:40.638] INFO: Done with hot pixel readout
[14:41:44.669] INFO: PixTest:: pg_setup set to default.
[14:41:44.670] INFO: 0 hot pixels found in step 0
[14:41:44.675] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:41:44.760] INFO: PixTest::trimHotPixels() done
[14:41:44.760] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C0.dat
[14:41:44.772] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C1.dat
[14:41:44.779] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C2.dat
[14:41:44.784] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C3.dat
[14:41:44.789] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C4.dat
[14:41:44.794] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C5.dat
[14:41:44.800] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C6.dat
[14:41:44.805] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C7.dat
[14:41:44.810] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C8.dat
[14:41:44.815] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C9.dat
[14:41:44.821] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C10.dat
[14:41:44.826] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C11.dat
[14:41:44.832] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C12.dat
[14:41:44.837] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C13.dat
[14:41:44.842] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C14.dat
[14:41:44.848] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//trimParameters35_C15.dat
[14:41:44.853] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-04_FPIXTest-17C-FNAL-160908-1209-300V_2016-09-08_12h09m_1473354553/000_FPIXTest_p17//defaultMaskFile.dat
[14:41:44.863] INFO: enter test to run
[14:42:01.416] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:42:01.416] INFO: running: xray
[14:42:01.417] INFO: ----------------------------------------------------------------------
[14:42:01.417] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:42:01.417] INFO: ----------------------------------------------------------------------
[14:42:02.382] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:42:13.663] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:42:15.036] WARNING: Channel 0 ROC 2: Readback start marker after 2 readouts!
[14:42:15.036] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (5) != Token Chain Length (8)
[14:42:15.039] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:15.039] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a4 80b1 43c8 43c8 43c8 43c8 43c8 43c8 1b 2a65 43c8 454 2249 43c8 8a 2048 50d 2a40 e000 c000
[14:42:15.039] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09e 8000 4388 4388 4388 4388 4388 34c 282e 4388 10a 2247 350 2688 4388 4388 e000 c000
[14:42:15.039] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09f 8040 438a 438a 438a 438a d8 204a 4388 15 2449 220 204d 611 224e 438a 761 268c 4389 438a e000 c000
[14:42:15.039] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a0 80b1 4388 294 2445 4388 4388 4388 99 2662 140 244d 4388 462 2064 4388 69a 202d 4389 852 264f 4388 e000 c000
[14:42:15.039] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a1 80c0 43c8 43c8 43cb 540 2845 43c8 43ca 35c 202d 43c9 43c8 80d 2644 43c9 4cb 224c e000 c000
[14:42:15.039] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a2 8000 43c8 43c8 43c9 43c9 24d 2265 43c8 43c8 115 2249 43c8 2a1 2865 43c9 110 2041 e000 c000
[14:42:15.039] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a3 8040 43c8 21 224c 43c9 43c8 43c9 34b 2640 43c9 14a 2666 43c9 43c8 9d 2849 43c9 e000 c000
[14:42:15.039] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:15.039] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a4 80b1 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:42:15.040] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19e 8000 4388 4388 64c 242f 4389 5d 2a42 438a 43 224d 4388 438a 4388 4388 e000 c000
[14:42:15.040] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19f 8040 438a 438a 48a 2447 4388 4389 71d 2864 438a 4389 438a 438a e000 c000
[14:42:15.040] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a0 80b1 4389 162 2460 4389 30a 2a2f 6c4 202e 4389 4388 d 222a 4389 51b 224e 4389 4389 4389 e000 c000
[14:42:15.040] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a1 80c0 43c8 43c8 7fd 43c9 43c8 19 202f 43c9 43c9 e000 c000
[14:42:15.040] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a2 8000 43c8 43c8 43c8 43c9 43c8 43c9 43c9 43c9 e000 c000
[14:42:15.040] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a3 8040 43c8 718 244a 43c9 309 222f 43c8 43c8 501 244a 43c8 43c8 458 2048 43c8 820 2428 43c9 e000 c000
[14:42:15.040] WARNING: Channel 0 ROC 2: Readback start marker after 14 readouts!
[14:42:15.040] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:15.040] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b2 8000 43c8 455 2240 43c8 43c9 43c9 43c8 43c8 43c8 43c9 d3 2462 34c 284f e000 c000
[14:42:15.040] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ac 80b1 43c8 43c8 43c8 43c8 289 2a62 43c8 4b 244a 43c8 52 264e 14a 224a 43c8 43c8 e000 c000
[14:42:15.040] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ad 80c0 43c8 43c8 43c8 43c8 21 2068 1d 284c 40 2649 43c8 43c8 44a 224e 43ca 43c8 e000 c000
[14:42:15.040] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ae 8000 43c8 43c8 43c8 43c8 94 2a4a 43c8 43c8 43c8 43c8 208 284f e000 c000
[14:42:15.040] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0af 8040 43ca 43ca 43ca 43ca 43c8 cd 2843 843 2a65 43ca 6cb 2062 753 204b 43c9 43ca 242 2a2a e000 c000
[14:42:15.040] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b0 80b1 43c8 253 284f 43c8 43c8 43c8 43c8 43c8 43c9 860 2042 43c8 e000 c000
[14:42:15.040] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b1 80c0 43c8 43c8 43c8 43c8 43ca 312 284b 43c9 43c8 43c9 e000 c000
[14:42:16.198] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[14:42:16.198] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (1) != Token Chain Length (8)
[14:42:16.198] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06a 8000 43c8 43c8 43c8 43c8 9b 2a6d 43c8 43c8 43c8 43c8 e000 c000
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a064 80b1 4388 553 2664 4388 455 2847 755 2a60 4388 4388 489 284d 4388 4388 61b 2a66 4388 80c 2226 4388 e000 c000
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a065 80c0 4388 4388 4388 4388 55a 286a 4388 11c 2662 145 244d 4388 41a 2840 4388 4388 e000 c000
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a066 8000 4388 83 2a42 4388 555 2843 4388 4388 4388 4388 4388 4388 293 2249 e000 c000
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== b067 8040 43c8 7ff 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a068 80b1 43c8 14d 202d 43c8 43c8 43c8 6d1 2246 43c8 43c8 43c8 2d9 2443 43c8 21b 2a4d e000 c000
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a069 80c0 43c8 418 2a4f 43c8 43c8 43c8 43c8 43c8 348 264f 43c8 752 2644 43c8 e000 c000
[14:42:16.198] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16a 8000 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a164 80b1 4388 4388 4388 4388 4388 13 282c 4388 4388 4388 e000 c000
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a165 80c0 4388 4388 4388 720 284a 4388 4388 4388 d2 202d d2 2243 4388 4388 e000 c000
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a166 8000 4388 4388 4388 4388 4388 4388 4388 6d0 2440 4388 e000 c000
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== b167 8040 43c8 e001 c00f
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a168 80b1 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:42:16.198] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a169 80c0 43c8 43c8 43c8 43c8 19 242d 43c8 43c8 43c8 43c8 e000 c000
[14:42:17.395] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[14:42:17.395] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[14:42:17.395] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:17.395] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a030 80b1 4388 4388 4388 4388 511 2a4f 4388 41a 204d 4388 349 206f 401 2242 4389 4388 e000 c000
[14:42:17.395] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02a 8000 43c8 43c8 43c8 254 2040 355 2262 43c8 43c8 35b 2642 509 264f 43c8 43c8 43c8 e000 c000
[14:42:17.395] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02b 8040 43c8 69d 2462 43c8 43c8 43c8 43c8 43c8 21 204a 43c8 43c8 e000 c000
[14:42:17.396] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02c 80b1 43c8 21c 262f 359 2842 43c8 43c8 4d4 2a2c 43c8 43c8 43c8 43c8 43c8 549 2642 e000 c000
[14:42:17.396] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02d 80c3 bf8 245a 44e 4388 4388 6cd 224c 4388 4388 280 2444 4388 438a 4d2 244c 4388 e000 c000
[14:42:17.396] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02e 8000 4388 4388 4388 4388 4388 4388 da 2240 4388 450 284c 4388 e000 c000
[14:42:17.396] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02f 8040 438a 438a 438a 438a 815 2263 4388 438a 803 2a4d 4389 438a e000 c000
[14:42:17.396] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:17.396] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a130 80b1 4389 4389 4389 4388 4389 60d 2242 4389 4389 4389 e000 c000
[14:42:17.396] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12a 8000 43c8 49b 2460 43c8 412 2845 43c8 11c 2a26 43c8 43c8 43c8 43c8 43c8 1d 2442 1d 204e e000 c000
[14:42:17.396] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12b 8040 43c8 43c8 43c8 43c8 151 2a44 43c8 50 2a42 703 204f 43c8 43c8 43c8 e000 c000
[14:42:17.396] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12c 80b1 43c8 100 2a46 43c8 43c8 43c8 43c8 43c8 262 2489 43c8 309 2467 43c8 e000 c000
[14:42:17.396] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12d 80c1 9fc 4388 438a 320 2a44 4388 4388 4388 4388 4388 e000 c000
[14:42:17.396] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12e 8000 4388 4388 4389 438a 754 224c 4388 438a 4388 452 2244 4388 e000 c000
[14:42:17.396] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12f 8040 438a 438a 4388 4389 438a 408 202f 4389 438a 438a e000 c000
[14:42:18.525] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (243) != TBM ID (234)
[14:42:18.526] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (243) != TBM ID (232)
[14:42:18.526] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[14:42:18.526] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (235) != TBM ID (244)
[14:42:18.526] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (233) != TBM ID (244)
[14:42:18.526] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f7 8040 4388 5d 284f 4388 4388 4388 4388 4388 4388 88 2841 4388 e000 c000
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f1 80c0 43c8 dc 2842 43c8 43c8 43c8 43ca 43c9 705 2240 43c8 43c9 95 2a6e e000 c000
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f2 8000 43c8 93 242d 43c8 43c9 815 2849 43c9 43c8 43c8 692 2a4a 43c8 44a 264e 710 2a26 43c9 e000 c000
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ea 80a5 4388 31a 2248 4389 2d8 2624 4388 4389 699 2a48 4389 a 2446 554 2a65 4389 4388 4389 4c3 2862 e000 c000
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f4 80b1 4388 4388 4388 4388 4388 4388 93 2646 751 2644 4388 449 244b 4388 e000 c000
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f5 80c0 4388 511 2248 4388 112 284c 4388 718 282d 4388 4388 508 2045 4388 4388 4388 e000 c000
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f6 8000 4388 4388 4388 4388 4388 4388 61 2462 4c3 2a42 4388 6d5 240a 4388 351 2249 e000 c000
[14:42:18.526] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f7 8040 4388 4388 4388 4388 559 2a4d 4388 4388 4388 4388 e000 c000
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f1 80c0 43c8 43c8 14c 2642 43c8 43c9 43c9 43c8 43c9 43c9 e000 c000
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f2 8000 43c8 43c8 43c8 43c9 688 2243 43c8 43c9 348 2842 414 2040 43c9 43c9 e000 c000
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e8 8008 15c 4389 4388 4388 4388 4388 2 242d 4388 4389 e000 c000
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f4 80b1 4388 4388 262 2a45 55a 2446 4388 858 2668 4388 4388 10a 244a 10a 2649 4388 4388 4388 e000 c000
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f5 80c0 4388 4388 4388 6c8 2820 4388 4388 4388 4388 4388 e000 c000
[14:42:18.526] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f6 8000 4388 4388 4388 2d9 262e 4388 749 264d 4388 4388 4388 4388 e000 c000
[14:42:22.902] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[14:42:22.902] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:22.902] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b3 8040 43c8 43c9 43c8 495 202c 43c9 43c9 254 2647 43c9 24c 2241 24c 262f 43c8 43c9 20d 2442 4cb 2a4f e000 c000
[14:42:22.902] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ad 80c0 4388 4388 4388 4388 4388 4388 438a 4388 302 284c 555 2249 e000 c000
[14:42:22.902] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ae 8000 4388 4d0 284f 4388 4388 758 2240 4388 4c 2842 4388 691 224d 4388 4388 842 222f 4388 e000 c000
[14:42:22.902] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0af 8040 438a 438a 438a 438a 640 2440 4388 438a 4389 438a e000 c000
[14:42:22.902] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b0 80b1 4388 654 2842 75a 2845 4388 140 242d 4388 852 2648 4388 4388 d4 2642 203 284f 1481 3862 43c8 75a 2a3f 39 274b 22f 43c8 208 242f e000 c000
[14:42:22.902] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b1 80c0 43c8 14d 262e 43c8 43c8 43c8 43ca 43c9 d0 2a24 43c8 64b 2662 43c9 e000 c000
[14:42:22.902] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b2 8000 43c8 94 2822 93 264d 43c8 43c9 43c9 43c8 4c0 2649 43c8 43c8 70a 2044 70d 222b 43c9 e000 c000
[14:42:29.835] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[14:42:29.835] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:29.835] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a157 8040 43c8 15c 2a4f 492 2849 43c8 43c8 43c8 c5 2468 43c8 64d 2843 43c8 258 2247 43c8 43c8 e000 c000
[14:42:29.835] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a151 80c0 4388 29b 2a6c 4388 349 2a4f 4388 4389 4389 4388 4389 4389 e000 c000
[14:42:29.835] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a152 8000 4388 4388 4388 750 242c 849 244d 4389 251 222f 4388 4389 4389 4389 e000 c000
[14:42:29.835] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a153 8040 4388 4389 511 2a46 4388 4388 4388 160 224c 4388 254 2645 4388 4389 e000 c000
[14:42:29.835] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a154 80b1 4388 4388 100 2448 4388 359 264c 609 2045 43c8 43c8 43c8 43c8 e000 c000
[14:42:29.835] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a155 80c0 43c8 43c8 43c8 43c8 51c 202d 43c8 43c8 211 244f 43c8 51a 2265 43c8 e000 c000
[14:42:29.835] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a156 8000 43c8 43c8 53 2041 43c8 43c8 493 2649 43c8 43c8 43c8 43c8 e000 c000
[14:42:30.955] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[14:42:30.956] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[14:42:30.956] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01d 80c0 43c8 43c8 43c8 43c8 43c8 43c8 68c 202e 43ca 43c8 352 284d e000 c000
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a017 8040 4388 4388 4388 4cb 244c 4388 4388 35a 202f 4388 348 244d 4388 4388 345 2446 e000 c000
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a018 80b1 4388 4388 4388 4388 4388 4388 758 2a40 80b 2462 4388 210 2224 50c 2a2f 610 204b 4388 e000 c000
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a019 80c0 4388 4388 4388 4388 4388 110 2643 4388 4388 4388 e000 c000
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01a 8000 4388 4388 da 222c 4388 4388 61a 2062 43c8 43c8 43c8 7ff e000 c000
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01b 8040 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01c 80b1 43c8 159 2028 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:42:30.956] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11d 80c0 43c8 43c8 43ca 510 2841 43c8 43c8 31b 2049 43c8 1b 2046 358 244b 43c8 43c8 e000 c000
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a117 8040 4388 d9 2240 4388 4388 c9 2646 4388 4388 4388 4388 4388 e000 c000
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a118 80b1 4388 4388 4388 4d1 2249 4388 4388 4388 4388 4388 e000 c000
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a119 80c0 4388 49b 2665 4388 241 2841 4388 4388 19 2040 4388 4388 4388 4388 e000 c000
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11a 8000 4388 4388 4388 649 2a6f 4388 484 202f 43c8 43c8 43c8 e001 c0c0
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11b 8040 43c8 43c8 43c8 43c8 71d 244e 43c8 43c8 43c8 43c8 e000 c000
[14:42:30.956] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11c 80b1 43c8 2dd 2642 43c8 30c 2a2e 4cc 2040 43c8 cd 2245 43c8 43c8 43c8 2 2846 43c8 43c8 e000 c000
[14:42:33.214] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[14:42:33.215] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[14:42:33.215] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a9 80c0 43c8 43c8 43c8 43c8 28b 2a6b 491 2263 43c8 43c8 43c8 648 2448 43c8 29d 2a62 e000 c000
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a3 8040 4388 4389 54 262d 4388 4389 4389 4389 d 2446 4388 4389 44a 2245 e000 c000
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a4 80b1 4388 111 2844 4388 4388 418 2a28 4388 15 244b 618 226c 4388 4388 89 2840 514 204e 622 204f 4388 4388 e000 c000
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a5 80c0 4388 4388 11d 244b 4388 4388 4388 4388 48c 2a40 4388 4388 e000 c000
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a6 8000 4388 22 264a 4388 4388 dd 2845 43c8 43c9 21f7 ff8 43c8 29c 2843 74d 2648 43c8 e000 c000
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a7 8040 43c8 43c8 43c8 4cb 224c 43c8 43c8 43c8 43c8 408 202e 43c8 1a 202e 41 2a42 e000 c000
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a8 80b1 43c8 43c8 212 2227 450 264a 43c8 43c8 43c8 349 2041 43c8 34c 204f 70c 2a40 43c8 43c8 560 202f e000 c000
[14:42:33.215] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a9 80c0 43c8 712 264b 43c8 2c9 284c 43c8 48a 2a45 43c8 444 2022 648 2448 43c8 34c 2846 43c8 43c8 43c8 e000 c000
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a3 8040 4388 4389 4388 4388 b 2445 4388 4388 415 224f 4388 4389 e000 c000
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a4 80b1 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a5 80c0 4388 288 226f 695 204d 4388 4388 483 244e 4388 d4 2a8f 4388 4388 4388 4388 e000 c000
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a6 8000 4388 4388 4388 4388 29c 282f 43c8 43c8 43c9 ec0 272a e000 c000
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a7 8040 43c8 10b 286a 614 2047 608 264b 43c8 43c8 44b 264b 489 2464 43c8 43c8 43c8 94 2047 43c8 43c8 e000 c000
[14:42:33.215] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a8 80b1 43c8 6c9 2642 43c8 289 2649 75c 2224 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:42:34.366] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[14:42:34.366] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[14:42:34.369] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06f 8040 43ca 43ca 92 2829 d8 2827 821 224f 43ca 43ca 43c8 43ca 409 244a 43c9 43ca 152 284e e000 c000
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a069 80c0 4388 4388 4388 442 2646 61c 2044 4388 4388 4388 409 2a48 660 2245 4388 82 282f 24d 2640 294 2a4a 755 204f 4388 e000 c000
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06a 8000 4388 4388 151 262f 4388 4388 4388 4388 4388 4388 e000 c000
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06b 8040 4388 4388 4388 4388 4388 4388 2d5 282b 4388 4388 e000 c000
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06c 80b1 4388 4388 4388 4388 43c8 43c8 43c8 51 e07f c012
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06d 80c0 43c8 54 2a6e 4d8 2047 43c8 43c8 49a 244a 43c8 81d 244f 43c8 43c8 db 2643 43ca 43c8 1a 204f 11b 266a 34c 2861 e000 c000
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06e 8000 43c8 59 2a4e 43c8 43c8 43c8 43c8 614 2846 84c 2665 43c8 43c8 43c8 50c 2829 e000 c000
[14:42:34.369] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16f 8040 43ca 43ca 43c8 43c9 43ca 43c9 43ca 43ca e000 c000
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a169 80c0 4388 4388 4388 81b 2445 4388 4388 4388 4388 4388 e000 c000
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16a 8000 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16b 8040 4388 212 2446 4c8 2424 510 224d 4388 4388 4388 4388 4388 151 2426 292 2062 4388 4388 e000 c000
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16c 80b1 4388 4388 350 2a44 43c8 43c8 43c8 80e 2b7e 43c8 43c8 e000 c000
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16d 80c0 43c8 218 2648 290 2442 29a 2a64 43c8 43ca 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:42:34.369] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16e 8000 43c8 43c8 43c9 43ca 43c8 dd 224b 11d 204c 24d 222c 550 2046 43ca 43c8 43c8 e000 c000
[14:42:35.518] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[14:42:35.518] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[14:42:35.518] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a035 80c0 43c8 43c8 43c8 48b 204f 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02f 8040 438a 438a 418 2a25 438a 438a 4c3 226f 4388 455 2465 75b 204f 438a 290 204d 65c 2a41 4389 348 2227 438a 52 2229 e000 c000
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a030 80b1 4388 4388 1a 282b 4388 4388 4388 4388 4389 4388 e000 c000
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a031 80c0 4388 4388 4388 710 262e 4388 438a d8 2641 2d8 2a63 4389 358 204c 4388 4389 e000 c000
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a032 8000 4388 4388 43c9 43c9 419 2a47 558 20d1 84a 244c 24f 43c8 43c8 c 2640 43c9 295 262e e000 c000
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a033 8040 43c8 4d 2460 251 2261 43c9 43c8 43c9 43c9 43c9 43c8 43c9 e000 c000
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a034 80b1 43c8 43c8 153 284a 43c8 70d 2242 43c8 43c8 43c8 114 2842 290 2844 43c8 43c8 61b 2648 e000 c000
[14:42:35.518] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a135 80c0 43c8 815 286a 43c8 43c8 43c8 5c 2646 43c8 43c8 43c8 43c8 e000 c000
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12f 8040 438a 20b 2a6e 438a 4388 4389 60c 2049 438a 4389 438a 438a 14 2440 650 2226 e000 c000
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a130 80b1 4389 4389 4389 60a 282c 819 244c 4388 4389 4389 4389 4389 e000 c000
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a131 80c0 4388 4388 1a 224a 691 2245 4388 4389 4389 4388 4 2a25 4389 4389 e000 c000
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a132 8000 4388 4388 84d 264c 43c8 43c9 2640 614 43c9 43c9 e000 c000
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a133 8040 43c8 43c9 43c8 43c8 43c8 43c8 43c8 43c9 e000 c000
[14:42:35.518] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a134 80b1 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[14:42:42.790] INFO: Resuming triggers.
[14:42:54.078] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[14:43:22.901] INFO: Resuming triggers.
[14:43:34.188] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[14:44:02.132] INFO: Resuming triggers.
[14:44:13.414] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[14:44:41.093] INFO: Resuming triggers.
[14:44:52.378] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[14:45:20.511] INFO: Resuming triggers.
[14:45:31.795] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[14:45:59.338] INFO: Resuming triggers.
[14:46:10.623] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[14:46:38.502] INFO: Resuming triggers.
[14:46:49.788] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[14:47:18.071] INFO: Resuming triggers.
[14:47:28.128] INFO: data taking finished, elapsed time: 100 seconds.
[14:47:53.084] INFO: PixTest:: pg_setup set to default.
[14:47:53.087] INFO: PixTestXray::doPhRun() done
[14:47:53.337] INFO: enter test to run
[14:49:18.789] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:49:18.789] INFO: running: xray
[14:49:18.790] INFO: ----------------------------------------------------------------------
[14:49:18.790] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:49:18.790] INFO: ----------------------------------------------------------------------
[14:49:19.767] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:49:26.285] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:49:56.550] INFO: Resuming triggers.
[14:50:03.070] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[14:50:32.478] INFO: Resuming triggers.
[14:50:38.996] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[14:51:08.573] INFO: Resuming triggers.
[14:51:15.091] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[14:51:44.943] INFO: Resuming triggers.
[14:51:51.466] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[14:52:21.090] INFO: Resuming triggers.
[14:52:27.609] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[14:52:57.330] INFO: Resuming triggers.
[14:53:03.852] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[14:53:33.557] INFO: Resuming triggers.
[14:53:40.077] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[14:54:09.749] INFO: Resuming triggers.
[14:54:16.266] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[14:54:46.074] INFO: Resuming triggers.
[14:54:52.593] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[14:55:22.225] INFO: Resuming triggers.
[14:55:28.744] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[14:55:58.471] INFO: Resuming triggers.
[14:56:04.994] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[14:56:34.675] INFO: Resuming triggers.
[14:56:41.194] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[14:57:10.987] INFO: Resuming triggers.
[14:57:17.506] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[14:57:46.923] INFO: Resuming triggers.
[14:57:53.445] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[14:58:22.738] INFO: Resuming triggers.
[14:58:25.280] INFO: data taking finished, elapsed time: 100 seconds.
[14:58:36.885] INFO: PixTest:: pg_setup set to default.
[14:58:36.888] INFO: PixTestXray::doPhRun() done
[14:58:37.062] INFO: enter test to run
[14:59:49.600] INFO: test: HighRate no parameter change
[14:59:49.600] INFO: running: highrate
[14:59:49.622] INFO: ----------------------------------------------------------------------
[14:59:49.622] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:59:49.622] INFO: ----------------------------------------------------------------------
[14:59:49.821] INFO: Expecting 768 events.
[14:59:50.980] INFO: 768 events read in total (443ms).
[14:59:50.980] INFO: Test took 1321ms.
[14:59:51.783] INFO: Expecting 41600 events.
[14:59:54.807] INFO: 41600 events read in total (2498ms).
[14:59:54.808] INFO: Test took 3797ms.
[14:59:54.843] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:55.566] INFO: Expecting 41600 events.
[14:59:58.680] INFO: 41600 events read in total (2587ms).
[14:59:58.681] INFO: Test took 3821ms.
[14:59:58.717] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:59.442] INFO: Expecting 41600 events.
[15:00:02.617] INFO: 41600 events read in total (2648ms).
[15:00:02.618] INFO: Test took 3882ms.
[15:00:02.654] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:03.366] INFO: Expecting 41600 events.
[15:00:06.510] INFO: 41600 events read in total (2617ms).
[15:00:06.511] INFO: Test took 3838ms.
[15:00:06.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:07.259] INFO: Expecting 41600 events.
[15:00:10.437] INFO: 41600 events read in total (2651ms).
[15:00:10.438] INFO: Test took 3874ms.
[15:00:10.474] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:11.196] INFO: Expecting 41600 events.
[15:00:14.275] INFO: 41600 events read in total (2552ms).
[15:00:14.276] INFO: Test took 3784ms.
[15:00:14.313] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:15.035] INFO: Expecting 41600 events.
[15:00:18.191] INFO: 41600 events read in total (2629ms).
[15:00:18.192] INFO: Test took 3861ms.
[15:00:18.228] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:18.944] INFO: Expecting 41600 events.
[15:00:22.189] INFO: 41600 events read in total (2718ms).
[15:00:22.190] INFO: Test took 3941ms.
[15:00:22.226] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:22.945] INFO: Expecting 41600 events.
[15:00:26.097] INFO: 41600 events read in total (2625ms).
[15:00:26.098] INFO: Test took 3853ms.
[15:00:26.139] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:26.847] INFO: Expecting 41600 events.
[15:00:30.054] INFO: 41600 events read in total (2680ms).
[15:00:30.055] INFO: Test took 3895ms.
[15:00:30.091] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:30.812] INFO: Expecting 41600 events.
[15:00:33.989] INFO: 41600 events read in total (2650ms).
[15:00:33.990] INFO: Test took 3881ms.
[15:00:34.025] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:34.745] INFO: Expecting 41600 events.
[15:00:37.889] INFO: 41600 events read in total (2618ms).
[15:00:37.890] INFO: Test took 3846ms.
[15:00:37.927] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:38.638] INFO: Expecting 41600 events.
[15:00:41.836] INFO: 41600 events read in total (2671ms).
[15:00:41.837] INFO: Test took 3892ms.
[15:00:41.873] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:42.596] INFO: Expecting 41600 events.
[15:00:45.819] INFO: 41600 events read in total (2696ms).
[15:00:45.820] INFO: Test took 3929ms.
[15:00:45.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:46.575] INFO: Expecting 41600 events.
[15:00:49.756] INFO: 41600 events read in total (2654ms).
[15:00:49.757] INFO: Test took 3882ms.
[15:00:49.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:50.513] INFO: Expecting 41600 events.
[15:00:53.637] INFO: 41600 events read in total (2597ms).
[15:00:53.638] INFO: Test took 3826ms.
[15:00:53.674] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:54.388] INFO: Expecting 41600 events.
[15:00:57.568] INFO: 41600 events read in total (2653ms).
[15:00:57.569] INFO: Test took 3876ms.
[15:00:57.606] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:58.326] INFO: Expecting 41600 events.
[15:01:01.563] INFO: 41600 events read in total (2710ms).
[15:01:01.564] INFO: Test took 3938ms.
[15:01:01.600] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:02.321] INFO: Expecting 41600 events.
[15:01:05.433] INFO: 41600 events read in total (2585ms).
[15:01:05.434] INFO: Test took 3816ms.
[15:01:05.470] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:06.190] INFO: Expecting 41600 events.
[15:01:09.174] INFO: 41600 events read in total (2458ms).
[15:01:09.175] INFO: Test took 3686ms.
[15:01:09.210] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:09.561] INFO: enter test to run
[15:01:44.503] INFO: test: HighRate no parameter change
[15:01:44.504] INFO: running: highrate
[15:01:44.505] INFO: ----------------------------------------------------------------------
[15:01:44.505] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:01:44.505] INFO: ----------------------------------------------------------------------
[15:01:45.128] INFO: Expecting 208000 events.
[15:01:56.854] INFO: 208000 events read in total (11199ms).
[15:01:56.857] INFO: Test took 12344ms.
[15:01:57.016] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:57.272] INFO: number of dead pixels (per ROC): 0 0 0 0 0 160 0 0 1 0 0 0 0 0 0 0
[15:01:57.272] INFO: number of red-efficiency pixels: 87 57 74 142 169 324 174 90 115 110 142 116 135 58 19 26
[15:01:57.272] INFO: number of X-ray hits detected: 75977 46230 70002 111099 121186 120682 120240 81475 77406 103536 98933 85812 89543 53783 21394 22556
[15:01:57.272] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:01:57.272] INFO: number of Vcal hits detected: 207913 207943 207924 207854 207823 199828 207821 207906 207835 207889 207856 207881 207864 207940 207981 207973
[15:01:57.272] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[15:01:57.272] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 96.1 99.9 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[15:01:57.272] INFO: X-ray hit rate [MHz/cm2]: 22.3 13.6 20.5 32.6 35.5 35.4 35.2 23.9 22.7 30.3 29.0 25.2 26.2 15.8 6.3 6.6
[15:01:57.272] INFO: PixTestHighRate::doXPixelAlive() done
[15:01:57.321] INFO: PixTest:: pg_setup set to default.
[15:01:57.332] INFO: enter test to run
[15:02:14.712] INFO: test: HighRate no parameter change
[15:02:14.712] INFO: running: highrate
[15:02:14.713] INFO: ----------------------------------------------------------------------
[15:02:14.713] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:02:14.713] INFO: ----------------------------------------------------------------------
[15:02:15.337] INFO: Expecting 208000 events.
[15:02:29.009] INFO: 208000 events read in total (13145ms).
[15:02:29.015] INFO: Test took 14293ms.
[15:02:29.329] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:29.636] INFO: number of dead pixels (per ROC): 0 0 0 0 0 160 0 0 1 0 0 0 0 0 0 0
[15:02:29.636] INFO: number of red-efficiency pixels: 263 176 283 498 713 716 689 304 290 344 465 348 374 167 65 45
[15:02:29.636] INFO: number of X-ray hits detected: 157627 95595 145507 232655 251578 248833 248288 170093 160650 215030 205710 177844 185943 111660 45058 47728
[15:02:29.636] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:02:29.636] INFO: number of Vcal hits detected: 207717 207813 207697 207461 207170 199382 207215 207668 207647 207639 207501 207620 207596 207826 207934 207953
[15:02:29.636] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.6 99.7 99.7 99.9 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[15:02:29.636] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.7 99.6 95.9 99.6 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[15:02:29.636] INFO: X-ray hit rate [MHz/cm2]: 46.2 28.0 42.6 68.2 73.7 72.9 72.8 49.9 47.1 63.0 60.3 52.1 54.5 32.7 13.2 14.0
[15:02:29.636] INFO: PixTestHighRate::doXPixelAlive() done
[15:02:29.681] INFO: PixTest:: pg_setup set to default.
[15:02:29.691] INFO: enter test to run
[15:02:53.055] INFO: test: HighRate no parameter change
[15:02:53.055] INFO: running: highrate
[15:02:53.056] INFO: ----------------------------------------------------------------------
[15:02:53.056] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:02:53.056] INFO: ----------------------------------------------------------------------
[15:02:53.678] INFO: Expecting 208000 events.
[15:03:08.901] INFO: 208000 events read in total (14697ms).
[15:03:08.909] INFO: Test took 15844ms.
[15:03:09.381] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:09.738] INFO: number of dead pixels (per ROC): 0 0 0 0 0 160 0 0 1 0 0 0 0 0 0 0
[15:03:09.738] INFO: number of red-efficiency pixels: 555 383 602 1107 1598 1366 1500 655 654 829 1014 846 876 402 91 86
[15:03:09.738] INFO: number of X-ray hits detected: 237951 144850 219923 351025 380540 377225 376222 256725 242985 325874 310458 268745 282885 168952 67704 72298
[15:03:09.738] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:03:09.738] INFO: number of Vcal hits detected: 207304 207567 207240 206576 205719 198312 205860 207204 207180 207040 206725 206954 206939 207543 207907 207913
[15:03:09.738] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.7 99.4 99.0 99.3 99.1 99.7 99.7 99.6 99.4 99.5 99.5 99.8 100.0 100.0
[15:03:09.738] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.6 99.3 98.9 95.3 99.0 99.6 99.6 99.5 99.4 99.5 99.5 99.8 100.0 100.0
[15:03:09.738] INFO: X-ray hit rate [MHz/cm2]: 69.7 42.5 64.5 102.9 111.5 110.6 110.3 75.2 71.2 95.5 91.0 78.8 82.9 49.5 19.8 21.2
[15:03:09.738] INFO: PixTestHighRate::doXPixelAlive() done
[15:03:09.789] INFO: PixTest:: pg_setup set to default.
[15:03:09.801] INFO: enter test to run
[15:03:23.919] INFO: test: exit no parameter change
[15:03:24.220] QUIET: Connection to board 33 closed.
[15:03:24.251] INFO: pXar: this is the end, my friend