[10:20:05.840] INFO: *** Welcome to pxar ***
[10:20:05.840] INFO: *** Today: 2016/06/07
[10:20:05.858] INFO: *** Version: v1.9.0-814-g7497
[10:20:05.858] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//dacParameters35_C15.dat
[10:20:05.859] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:20:05.859] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//defaultMaskFile.dat
[10:20:05.859] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C15.dat
[10:20:05.929] INFO: clk: 4
[10:20:05.929] INFO: ctr: 4
[10:20:05.929] INFO: sda: 19
[10:20:05.929] INFO: tin: 9
[10:20:05.929] INFO: level: 15
[10:20:05.929] INFO: triggerdelay: 0
[10:20:05.929] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:20:05.929] INFO: Log level: INFO
[10:20:05.948] QUIET: Connection to board DTB_WREK4U opened.
[10:20:05.951] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[10:20:05.954] INFO: RPC call hashes of host and DTB match: 398089610
[10:20:07.486] INFO: DUT info:
[10:20:07.486] INFO: The DUT currently contains the following objects:
[10:20:07.486] INFO: 2 TBM Cores tbm08c (2 ON)
[10:20:07.486] INFO: TBM Core alpha (0): 7 registers set
[10:20:07.486] INFO: TBM Core beta (1): 7 registers set
[10:20:07.486] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:20:07.486] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.486] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.486] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.486] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.486] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.486] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.486] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.486] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.487] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.487] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.487] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.487] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.487] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.487] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.487] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.487] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:07.890] INFO: enter 'restricted' command line mode
[10:20:07.890] INFO: enter test to run
[10:20:16.947] INFO: test: PixelAlive no parameter change
[10:20:16.947] INFO: running: pixelalive
[10:20:16.956] INFO: ----------------------------------------------------------------------
[10:20:16.956] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:20:16.956] INFO: ----------------------------------------------------------------------
[10:20:17.273] INFO: Expecting 41600 events.
[10:20:21.561] INFO: 41600 events read in total (3569ms).
[10:20:21.728] INFO: Test took 4770ms.
[10:20:21.741] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:21.002] INFO: PixTestAlive::aliveTest() done
[10:20:21.002] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0
[10:20:22.035] INFO: enter test to run
[10:20:57.722] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:20:57.722] INFO: running: highrate
[10:20:57.723] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:20:57.878] INFO: ----------------------------------------------------------------------
[10:20:57.878] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:20:57.878] INFO: ----------------------------------------------------------------------
[10:20:57.878] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:20:57.878] INFO: edge/corner pixel THR is adjusted
[10:20:57.879] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:20:58.838] INFO: Collecting data for 5 seconds...
[10:21:03.854] INFO: Done with hot pixel readout
[10:21:14.921] INFO: PixTest:: pg_setup set to default.
[10:21:14.922] INFO: 0 hot pixels found in step 0
[10:21:14.934] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:21:14.937] INFO: PixTest::trimHotPixels() done
[10:21:14.956] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C0.dat
[10:21:14.962] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C1.dat
[10:21:14.967] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C2.dat
[10:21:14.972] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C3.dat
[10:21:14.977] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C4.dat
[10:21:14.982] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C5.dat
[10:21:14.987] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C6.dat
[10:21:14.993] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C7.dat
[10:21:14.998] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C8.dat
[10:21:14.003] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C9.dat
[10:21:15.008] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C10.dat
[10:21:15.013] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C11.dat
[10:21:15.018] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C12.dat
[10:21:15.024] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C13.dat
[10:21:15.029] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C14.dat
[10:21:15.034] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C15.dat
[10:21:15.040] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//defaultMaskFile.dat
[10:21:15.050] INFO: enter test to run
[10:22:44.217] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:22:44.218] INFO: running: highrate
[10:22:44.222] INFO: ----------------------------------------------------------------------
[10:22:44.222] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:22:44.222] INFO: ----------------------------------------------------------------------
[10:22:44.222] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:22:44.222] INFO: edge/corner pixel THR is adjusted
[10:22:44.222] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:22:45.180] INFO: Collecting data for 1 seconds...
[10:22:46.183] INFO: Done with hot pixel readout
[10:22:50.041] INFO: PixTest:: pg_setup set to default.
[10:22:50.042] INFO: 0 hot pixels found in step 0
[10:22:50.048] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:22:50.144] INFO: PixTest::trimHotPixels() done
[10:22:50.145] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C0.dat
[10:22:50.156] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C1.dat
[10:22:50.162] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C2.dat
[10:22:50.167] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C3.dat
[10:22:50.172] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C4.dat
[10:22:50.177] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C5.dat
[10:22:50.182] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C6.dat
[10:22:50.188] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C7.dat
[10:22:50.193] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C8.dat
[10:22:50.198] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C9.dat
[10:22:50.203] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C10.dat
[10:22:50.208] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C11.dat
[10:22:50.213] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C12.dat
[10:22:50.218] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C13.dat
[10:22:50.223] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C14.dat
[10:22:50.228] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//trimParameters35_C15.dat
[10:22:50.233] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-3-35_FPIXTest-17C-FNAL-160601-0922_2016-06-01_09h23m_1464790980/000_FPIXTest_p17//defaultMaskFile.dat
[10:22:50.243] INFO: enter test to run
[10:24:14.977] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:24:14.977] INFO: running: xray
[10:24:14.978] INFO: ----------------------------------------------------------------------
[10:24:14.978] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:24:14.978] INFO: ----------------------------------------------------------------------
[10:24:15.942] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:24:27.685] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:24:57.121] INFO: Resuming triggers.
[10:25:08.866] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:25:39.015] INFO: Resuming triggers.
[10:25:50.757] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[10:26:20.823] INFO: Resuming triggers.
[10:26:32.570] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:27:02.626] INFO: Resuming triggers.
[10:27:14.373] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[10:27:43.410] INFO: Resuming triggers.
[10:27:55.156] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[10:28:24.782] INFO: Resuming triggers.
[10:28:36.529] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[10:29:05.992] INFO: Resuming triggers.
[10:29:17.740] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[10:29:47.100] INFO: Resuming triggers.
[10:29:53.487] INFO: data taking finished, elapsed time: 100 seconds.
[10:30:09.622] INFO: PixTest:: pg_setup set to default.
[10:30:09.625] INFO: PixTestXray::doPhRun() done
[10:30:09.761] INFO: enter test to run
[10:30:43.318] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:30:43.318] INFO: running: xray
[10:30:43.319] INFO: ----------------------------------------------------------------------
[10:30:43.319] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:30:43.319] INFO: ----------------------------------------------------------------------
[10:30:44.283] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:30:51.285] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:31:21.499] INFO: Resuming triggers.
[10:31:28.501] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:31:58.628] INFO: Resuming triggers.
[10:32:05.632] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[10:32:35.776] INFO: Resuming triggers.
[10:32:42.782] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[10:33:12.974] INFO: Resuming triggers.
[10:33:19.975] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:33:50.128] INFO: Resuming triggers.
[10:33:57.128] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[10:34:27.301] INFO: Resuming triggers.
[10:34:34.307] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[10:35:04.509] INFO: Resuming triggers.
[10:35:11.508] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[10:35:42.465] INFO: Resuming triggers.
[10:35:49.463] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[10:36:19.965] INFO: Resuming triggers.
[10:36:26.961] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:36:58.031] INFO: Resuming triggers.
[10:37:05.028] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[10:37:36.169] INFO: Resuming triggers.
[10:37:43.170] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[10:38:14.464] INFO: Resuming triggers.
[10:38:21.463] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[10:38:51.638] INFO: Resuming triggers.
[10:38:58.637] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[10:39:29.059] INFO: Resuming triggers.
[10:39:31.395] INFO: data taking finished, elapsed time: 100 seconds.
[10:39:41.757] INFO: PixTest:: pg_setup set to default.
[10:39:41.760] INFO: PixTestXray::doPhRun() done
[10:39:41.910] INFO: enter test to run
[10:40:10.618] INFO: test: HighRate no parameter change
[10:40:10.618] INFO: running: highrate
[10:40:10.619] INFO: ----------------------------------------------------------------------
[10:40:10.619] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:40:10.619] INFO: ----------------------------------------------------------------------
[10:40:10.759] INFO: Expecting 768 events.
[10:40:11.891] INFO: 768 events read in total (417ms).
[10:40:11.891] INFO: Test took 1267ms.
[10:40:12.694] INFO: Expecting 41600 events.
[10:40:15.745] INFO: 41600 events read in total (2524ms).
[10:40:15.746] INFO: Test took 3846ms.
[10:40:15.778] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:16.507] INFO: Expecting 41600 events.
[10:40:19.685] INFO: 41600 events read in total (2651ms).
[10:40:19.686] INFO: Test took 3892ms.
[10:40:19.717] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:20.447] INFO: Expecting 41600 events.
[10:40:23.646] INFO: 41600 events read in total (2672ms).
[10:40:23.647] INFO: Test took 3914ms.
[10:40:23.678] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:24.407] INFO: Expecting 41600 events.
[10:40:27.627] INFO: 41600 events read in total (2693ms).
[10:40:27.628] INFO: Test took 3932ms.
[10:40:27.660] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:28.386] INFO: Expecting 41600 events.
[10:40:31.603] INFO: 41600 events read in total (2690ms).
[10:40:31.604] INFO: Test took 3927ms.
[10:40:31.636] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:32.366] INFO: Expecting 41600 events.
[10:40:35.585] INFO: 41600 events read in total (2692ms).
[10:40:35.586] INFO: Test took 3934ms.
[10:40:35.618] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:36.345] INFO: Expecting 41600 events.
[10:40:39.559] INFO: 41600 events read in total (2687ms).
[10:40:39.560] INFO: Test took 3924ms.
[10:40:39.593] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:40.322] INFO: Expecting 41600 events.
[10:40:43.541] INFO: 41600 events read in total (2693ms).
[10:40:43.542] INFO: Test took 3931ms.
[10:40:43.574] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:44.301] INFO: Expecting 41600 events.
[10:40:47.524] INFO: 41600 events read in total (2697ms).
[10:40:47.525] INFO: Test took 3934ms.
[10:40:47.558] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:48.282] INFO: Expecting 41600 events.
[10:40:51.511] INFO: 41600 events read in total (2702ms).
[10:40:51.512] INFO: Test took 3936ms.
[10:40:51.544] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:52.273] INFO: Expecting 41600 events.
[10:40:55.505] INFO: 41600 events read in total (2705ms).
[10:40:55.506] INFO: Test took 3943ms.
[10:40:55.539] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:56.265] INFO: Expecting 41600 events.
[10:40:59.491] INFO: 41600 events read in total (2699ms).
[10:40:59.492] INFO: Test took 3934ms.
[10:40:59.524] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:00.253] INFO: Expecting 41600 events.
[10:41:03.472] INFO: 41600 events read in total (2692ms).
[10:41:03.473] INFO: Test took 3931ms.
[10:41:03.505] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:04.232] INFO: Expecting 41600 events.
[10:41:07.459] INFO: 41600 events read in total (2700ms).
[10:41:07.460] INFO: Test took 3938ms.
[10:41:07.492] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:08.221] INFO: Expecting 41600 events.
[10:41:11.453] INFO: 41600 events read in total (2705ms).
[10:41:11.454] INFO: Test took 3945ms.
[10:41:11.487] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:12.213] INFO: Expecting 41600 events.
[10:41:15.436] INFO: 41600 events read in total (2696ms).
[10:41:15.437] INFO: Test took 3931ms.
[10:41:15.469] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:16.196] INFO: Expecting 41600 events.
[10:41:19.422] INFO: 41600 events read in total (2699ms).
[10:41:19.423] INFO: Test took 3936ms.
[10:41:19.456] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:20.183] INFO: Expecting 41600 events.
[10:41:23.405] INFO: 41600 events read in total (2695ms).
[10:41:23.406] INFO: Test took 3932ms.
[10:41:23.437] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:24.164] INFO: Expecting 41600 events.
[10:41:27.355] INFO: 41600 events read in total (2664ms).
[10:41:27.356] INFO: Test took 3900ms.
[10:41:27.388] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:28.117] INFO: Expecting 41600 events.
[10:41:31.176] INFO: 41600 events read in total (2532ms).
[10:41:31.177] INFO: Test took 3772ms.
[10:41:31.208] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:31.570] INFO: enter test to run
[10:41:36.217] INFO: test: HighRate no parameter change
[10:41:36.217] INFO: running: highrate
[10:41:36.218] INFO: ----------------------------------------------------------------------
[10:41:36.218] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:41:36.218] INFO: ----------------------------------------------------------------------
[10:41:36.832] INFO: Expecting 208000 events.
[10:41:48.569] INFO: 208000 events read in total (11211ms).
[10:41:48.572] INFO: Test took 12347ms.
[10:41:48.705] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:48.954] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0
[10:41:48.955] INFO: number of red-efficiency pixels: 78 46 58 97 99 125 112 76 88 99 93 90 102 58 22 22
[10:41:48.955] INFO: number of X-ray hits detected: 62477 41919 60168 97434 104661 102216 99446 73425 73085 95360 97997 79607 82215 48072 19747 21241
[10:41:48.955] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:41:48.955] INFO: number of Vcal hits detected: 207922 207954 207939 207900 207898 207872 207886 207922 207912 207900 207903 207907 207847 207942 207978 207977
[10:41:48.955] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[10:41:48.955] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0
[10:41:48.955] INFO: X-ray hit rate [MHz/cm2]: 18.3 12.3 17.6 28.6 30.7 30.0 29.1 21.5 21.4 28.0 28.7 23.3 24.1 14.1 5.8 6.2
[10:41:48.955] INFO: PixTestHighRate::doXPixelAlive() done
[10:41:48.004] INFO: PixTest:: pg_setup set to default.
[10:41:49.019] INFO: enter test to run
[10:42:15.081] INFO: test: HighRate no parameter change
[10:42:15.081] INFO: running: highrate
[10:42:15.082] INFO: ----------------------------------------------------------------------
[10:42:15.082] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:42:15.082] INFO: ----------------------------------------------------------------------
[10:42:15.698] INFO: Expecting 208000 events.
[10:42:29.008] INFO: 208000 events read in total (12783ms).
[10:42:29.013] INFO: Test took 13921ms.
[10:42:29.284] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:42:29.576] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0
[10:42:29.576] INFO: number of red-efficiency pixels: 203 117 183 303 317 312 294 210 293 391 303 305 249 172 60 55
[10:42:29.576] INFO: number of X-ray hits detected: 129076 86064 123241 199005 213007 208644 201386 150289 149465 195757 198942 163812 167511 98194 41276 43249
[10:42:29.576] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:42:29.576] INFO: number of Vcal hits detected: 207786 207877 207808 207685 207671 207671 207691 207780 207689 207575 207678 207683 207693 207815 207936 207945
[10:42:29.576] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[10:42:29.576] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[10:42:29.576] INFO: X-ray hit rate [MHz/cm2]: 37.8 25.2 36.1 58.3 62.4 61.2 59.0 44.1 43.8 57.4 58.3 48.0 49.1 28.8 12.1 12.7
[10:42:29.576] INFO: PixTestHighRate::doXPixelAlive() done
[10:42:29.621] INFO: PixTest:: pg_setup set to default.
[10:42:29.636] INFO: enter test to run
[10:42:50.097] INFO: test: HighRate no parameter change
[10:42:50.097] INFO: running: highrate
[10:42:50.098] INFO: ----------------------------------------------------------------------
[10:42:50.098] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:42:50.098] INFO: ----------------------------------------------------------------------
[10:42:50.715] INFO: Expecting 208000 events.
[10:43:05.805] INFO: 208000 events read in total (14563ms).
[10:43:05.812] INFO: Test took 15705ms.
[10:43:06.225] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:06.564] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0
[10:43:06.564] INFO: number of red-efficiency pixels: 415 243 402 707 774 791 650 474 579 879 706 692 508 364 89 73
[10:43:06.564] INFO: number of X-ray hits detected: 195214 130682 187087 305269 324340 316315 307441 228831 228139 297456 302930 249895 255387 149992 62527 66574
[10:43:06.564] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:43:06.564] INFO: number of Vcal hits detected: 207505 207740 207542 207175 207128 207103 207256 207448 207335 206958 207180 207214 207383 207591 207906 207926
[10:43:06.564] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.6 99.7 99.8 99.7 99.5 99.6 99.7 99.8 99.8 100.0 100.0
[10:43:06.564] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.6 99.6 99.7 99.7 99.5 99.6 99.6 99.7 99.8 100.0 100.0
[10:43:06.564] INFO: X-ray hit rate [MHz/cm2]: 57.2 38.3 54.8 89.5 95.1 92.7 90.1 67.1 66.9 87.2 88.8 73.2 74.9 44.0 18.3 19.5
[10:43:06.564] INFO: PixTestHighRate::doXPixelAlive() done
[10:43:06.614] INFO: PixTest:: pg_setup set to default.
[10:43:06.634] INFO: enter test to run
[10:43:18.168] INFO: test: exit no parameter change
[10:43:18.453] QUIET: Connection to board 32 closed.
[10:43:18.454] INFO: pXar: this is the end, my friend