[13:14:23.879] INFO: *** Welcome to pxar ***
[13:14:23.879] INFO: *** Today: 2016/04/29
[13:14:24.018] INFO: *** Version: v1.9.0-796-gef167-dirty
[13:14:24.018] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//dacParameters35_C15.dat
[13:14:24.118] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:14:24.118] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//defaultMaskFile.dat
[13:14:24.126] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C15.dat
[13:14:24.289] INFO: clk: 4
[13:14:24.289] INFO: ctr: 4
[13:14:24.289] INFO: sda: 19
[13:14:24.289] INFO: tin: 9
[13:14:24.289] INFO: level: 15
[13:14:24.289] INFO: triggerdelay: 0
[13:14:24.289] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[13:14:24.289] INFO: Log level: INFO
[13:14:24.323] QUIET: Connection to board DTB_WREKRL opened.
[13:14:24.326] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[13:14:24.329] INFO: RPC call hashes of host and DTB match: 398089610
[13:14:25.877] INFO: DUT info:
[13:14:25.877] INFO: The DUT currently contains the following objects:
[13:14:25.877] INFO: 2 TBM Cores tbm08c (2 ON)
[13:14:25.877] INFO: TBM Core alpha (0): 7 registers set
[13:14:25.877] INFO: TBM Core beta (1): 7 registers set
[13:14:25.877] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:14:25.877] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.877] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.877] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.877] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.877] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.877] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.877] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.877] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.877] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.877] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.877] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.878] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.878] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.878] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.878] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:25.878] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:26.301] INFO: enter 'restricted' command line mode
[13:14:26.301] INFO: enter test to run
[13:14:34.336] INFO: test: PixelAlive no parameter change
[13:14:34.336] INFO: running: pixelalive
[13:14:34.384] INFO: ----------------------------------------------------------------------
[13:14:34.384] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:14:34.384] INFO: ----------------------------------------------------------------------
[13:14:34.702] INFO: Expecting 41600 events.
[13:14:39.043] INFO: 41600 events read in total (3622ms).
[13:14:39.210] INFO: Test took 4824ms.
[13:14:39.223] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:39.491] INFO: PixTestAlive::aliveTest() done
[13:14:39.491] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[13:14:39.521] INFO: enter test to run
[13:15:11.367] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:15:11.367] INFO: running: highrate
[13:15:11.368] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:15:11.601] INFO: ----------------------------------------------------------------------
[13:15:11.601] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:15:11.601] INFO: ----------------------------------------------------------------------
[13:15:11.601] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:15:11.601] INFO: edge/corner pixel THR is adjusted
[13:15:11.601] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:15:12.569] INFO: Collecting data for 5 seconds...
[13:15:17.585] INFO: Done with hot pixel readout
[13:15:29.071] INFO: PixTest:: pg_setup set to default.
[13:15:29.072] INFO: 0 hot pixels found in step 0
[13:15:29.084] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:15:29.094] INFO: PixTest::trimHotPixels() done
[13:15:29.137] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C0.dat
[13:15:29.142] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C1.dat
[13:15:29.148] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C2.dat
[13:15:29.153] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C3.dat
[13:15:29.158] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C4.dat
[13:15:29.163] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C5.dat
[13:15:29.169] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C6.dat
[13:15:29.174] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C7.dat
[13:15:29.179] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C8.dat
[13:15:29.184] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C9.dat
[13:15:29.189] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C10.dat
[13:15:29.195] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C11.dat
[13:15:29.200] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C12.dat
[13:15:29.205] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C13.dat
[13:15:29.210] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C14.dat
[13:15:29.215] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C15.dat
[13:15:29.221] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//defaultMaskFile.dat
[13:15:29.231] INFO: enter test to run
[13:15:59.991] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:15:59.991] INFO: running: highrate
[13:15:59.995] INFO: ----------------------------------------------------------------------
[13:15:59.995] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:15:59.995] INFO: ----------------------------------------------------------------------
[13:15:59.995] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:15:59.995] INFO: edge/corner pixel THR is adjusted
[13:15:59.995] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:16:00.952] INFO: Collecting data for 1 seconds...
[13:16:01.955] INFO: Done with hot pixel readout
[13:16:05.829] INFO: PixTest:: pg_setup set to default.
[13:16:05.829] INFO: 0 hot pixels found in step 0
[13:16:05.835] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:16:05.930] INFO: PixTest::trimHotPixels() done
[13:16:05.930] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C0.dat
[13:16:05.942] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C1.dat
[13:16:05.949] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C2.dat
[13:16:05.955] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C3.dat
[13:16:05.960] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C4.dat
[13:16:05.965] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C5.dat
[13:16:05.970] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C6.dat
[13:16:05.975] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C7.dat
[13:16:05.981] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C8.dat
[13:16:05.986] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C9.dat
[13:16:05.991] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C10.dat
[13:16:05.996] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C11.dat
[13:16:05.001] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C12.dat
[13:16:06.006] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C13.dat
[13:16:06.012] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C14.dat
[13:16:06.017] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//trimParameters35_C15.dat
[13:16:06.022] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-50_FPIXTest-17C-FNAL-160425-1325_2016-04-25_13h25m_1461608735/000_FPIXTest_p17//defaultMaskFile.dat
[13:16:06.031] INFO: enter test to run
[13:16:34.231] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:16:34.231] INFO: running: xray
[13:16:34.232] INFO: ----------------------------------------------------------------------
[13:16:34.232] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:16:34.232] INFO: ----------------------------------------------------------------------
[13:16:35.212] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:16:46.963] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:17:16.166] INFO: Resuming triggers.
[13:17:27.918] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:17:54.671] INFO: Resuming triggers.
[13:18:06.423] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[13:18:35.680] INFO: Resuming triggers.
[13:18:47.432] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[13:19:16.760] INFO: Resuming triggers.
[13:19:28.511] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[13:19:57.786] INFO: Resuming triggers.
[13:20:09.538] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[13:20:38.818] INFO: Resuming triggers.
[13:20:50.572] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:21:19.844] INFO: Resuming triggers.
[13:21:31.598] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[13:22:00.903] INFO: Resuming triggers.
[13:22:07.206] INFO: data taking finished, elapsed time: 100 seconds.
[13:22:23.148] INFO: PixTest:: pg_setup set to default.
[13:22:23.151] INFO: PixTestXray::doPhRun() done
[13:22:23.286] INFO: enter test to run
[13:22:54.060] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:22:54.060] INFO: running: xray
[13:22:54.061] INFO: ----------------------------------------------------------------------
[13:22:54.061] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:22:54.061] INFO: ----------------------------------------------------------------------
[13:22:55.025] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:23:01.981] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:23:31.989] INFO: Resuming triggers.
[13:23:38.943] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[13:24:09.045] INFO: Resuming triggers.
[13:24:16.002] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[13:24:45.968] INFO: Resuming triggers.
[13:24:52.922] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[13:25:22.922] INFO: Resuming triggers.
[13:25:29.877] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:25:59.888] INFO: Resuming triggers.
[13:26:06.846] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[13:26:36.856] INFO: Resuming triggers.
[13:26:43.811] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[13:27:13.877] INFO: Resuming triggers.
[13:27:20.829] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[13:27:47.971] INFO: Resuming triggers.
[13:27:54.926] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[13:28:25.258] INFO: Resuming triggers.
[13:28:32.208] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[13:29:02.601] INFO: Resuming triggers.
[13:29:09.552] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[13:29:39.487] INFO: Resuming triggers.
[13:29:46.437] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[13:30:16.421] INFO: Resuming triggers.
[13:30:23.374] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[13:30:53.399] INFO: Resuming triggers.
[13:31:00.349] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[13:31:30.413] INFO: Resuming triggers.
[13:31:33.394] INFO: data taking finished, elapsed time: 100 seconds.
[13:31:46.609] INFO: PixTest:: pg_setup set to default.
[13:31:46.612] INFO: PixTestXray::doPhRun() done
[13:31:46.760] INFO: enter test to run
[13:32:14.296] INFO: test: HighRate no parameter change
[13:32:14.296] INFO: running: highrate
[13:32:14.297] INFO: ----------------------------------------------------------------------
[13:32:14.297] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:32:14.297] INFO: ----------------------------------------------------------------------
[13:32:14.438] INFO: Expecting 768 events.
[13:32:15.571] INFO: 768 events read in total (418ms).
[13:32:15.572] INFO: Test took 1269ms.
[13:32:16.375] INFO: Expecting 41600 events.
[13:32:19.488] INFO: 41600 events read in total (2586ms).
[13:32:19.489] INFO: Test took 3911ms.
[13:32:19.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:20.252] INFO: Expecting 41600 events.
[13:32:23.449] INFO: 41600 events read in total (2670ms).
[13:32:23.449] INFO: Test took 3911ms.
[13:32:23.481] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:24.209] INFO: Expecting 41600 events.
[13:32:27.411] INFO: 41600 events read in total (2675ms).
[13:32:27.412] INFO: Test took 3914ms.
[13:32:27.444] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:28.175] INFO: Expecting 41600 events.
[13:32:31.381] INFO: 41600 events read in total (2679ms).
[13:32:31.382] INFO: Test took 3920ms.
[13:32:31.414] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:32.140] INFO: Expecting 41600 events.
[13:32:35.365] INFO: 41600 events read in total (2698ms).
[13:32:35.366] INFO: Test took 3934ms.
[13:32:35.398] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:36.124] INFO: Expecting 41600 events.
[13:32:39.349] INFO: 41600 events read in total (2698ms).
[13:32:39.350] INFO: Test took 3933ms.
[13:32:39.382] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:40.110] INFO: Expecting 41600 events.
[13:32:43.324] INFO: 41600 events read in total (2687ms).
[13:32:43.325] INFO: Test took 3926ms.
[13:32:43.357] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:44.087] INFO: Expecting 41600 events.
[13:32:47.318] INFO: 41600 events read in total (2705ms).
[13:32:47.319] INFO: Test took 3944ms.
[13:32:47.350] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:48.078] INFO: Expecting 41600 events.
[13:32:51.301] INFO: 41600 events read in total (2696ms).
[13:32:51.302] INFO: Test took 3935ms.
[13:32:51.335] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:52.063] INFO: Expecting 41600 events.
[13:32:55.287] INFO: 41600 events read in total (2697ms).
[13:32:55.287] INFO: Test took 3936ms.
[13:32:55.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:56.048] INFO: Expecting 41600 events.
[13:32:59.273] INFO: 41600 events read in total (2698ms).
[13:32:59.274] INFO: Test took 3937ms.
[13:32:59.305] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:00.036] INFO: Expecting 41600 events.
[13:33:03.266] INFO: 41600 events read in total (2703ms).
[13:33:03.267] INFO: Test took 3943ms.
[13:33:03.300] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:04.029] INFO: Expecting 41600 events.
[13:33:07.236] INFO: 41600 events read in total (2681ms).
[13:33:07.237] INFO: Test took 3920ms.
[13:33:07.268] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:07.995] INFO: Expecting 41600 events.
[13:33:11.224] INFO: 41600 events read in total (2703ms).
[13:33:11.225] INFO: Test took 3939ms.
[13:33:11.257] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:11.987] INFO: Expecting 41600 events.
[13:33:15.221] INFO: 41600 events read in total (2707ms).
[13:33:15.222] INFO: Test took 3947ms.
[13:33:15.254] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:15.983] INFO: Expecting 41600 events.
[13:33:19.203] INFO: 41600 events read in total (2693ms).
[13:33:19.204] INFO: Test took 3932ms.
[13:33:19.236] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:19.964] INFO: Expecting 41600 events.
[13:33:23.187] INFO: 41600 events read in total (2697ms).
[13:33:23.188] INFO: Test took 3934ms.
[13:33:23.220] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:23.950] INFO: Expecting 41600 events.
[13:33:27.180] INFO: 41600 events read in total (2703ms).
[13:33:27.181] INFO: Test took 3943ms.
[13:33:27.213] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:27.940] INFO: Expecting 41600 events.
[13:33:31.139] INFO: 41600 events read in total (2672ms).
[13:33:31.140] INFO: Test took 3908ms.
[13:33:31.171] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:31.900] INFO: Expecting 41600 events.
[13:33:34.955] INFO: 41600 events read in total (2528ms).
[13:33:34.955] INFO: Test took 3767ms.
[13:33:34.987] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:35.353] INFO: enter test to run
[13:33:40.919] INFO: test: HighRate no parameter change
[13:33:40.919] INFO: running: highrate
[13:33:40.920] INFO: ----------------------------------------------------------------------
[13:33:40.920] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:33:40.920] INFO: ----------------------------------------------------------------------
[13:33:41.531] INFO: Expecting 208000 events.
[13:33:53.293] INFO: 208000 events read in total (11235ms).
[13:33:53.295] INFO: Test took 12368ms.
[13:33:53.434] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:53.682] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[13:33:53.682] INFO: number of red-efficiency pixels: 61 37 72 121 114 144 144 87 63 100 92 93 92 51 34 29
[13:33:53.682] INFO: number of X-ray hits detected: 41666 39591 63862 104399 107965 116236 111255 78614 70368 85387 85261 76650 79641 46446 23624 25608
[13:33:53.682] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:33:53.682] INFO: number of Vcal hits detected: 207939 207963 207926 207877 207882 207854 207851 207913 207937 207898 207904 207858 207908 207948 207966 207971
[13:33:53.682] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[13:33:53.682] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0
[13:33:53.682] INFO: X-ray hit rate [MHz/cm2]: 12.2 11.6 18.7 30.6 31.6 34.1 32.6 23.0 20.6 25.0 25.0 22.5 23.3 13.6 6.9 7.5
[13:33:53.682] INFO: PixTestHighRate::doXPixelAlive() done
[13:33:53.730] INFO: PixTest:: pg_setup set to default.
[13:33:53.744] INFO: enter test to run
[13:34:13.599] INFO: test: HighRate no parameter change
[13:34:13.599] INFO: running: highrate
[13:34:13.600] INFO: ----------------------------------------------------------------------
[13:34:13.600] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:34:13.600] INFO: ----------------------------------------------------------------------
[13:34:14.210] INFO: Expecting 208000 events.
[13:34:27.583] INFO: 208000 events read in total (12846ms).
[13:34:27.588] INFO: Test took 13980ms.
[13:34:27.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:28.156] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[13:34:28.156] INFO: number of red-efficiency pixels: 116 94 181 409 346 384 414 239 213 295 311 211 220 173 71 59
[13:34:28.156] INFO: number of X-ray hits detected: 86726 82023 131668 214856 223038 240228 231787 164068 145135 177129 178088 158043 164763 96665 49273 53917
[13:34:28.156] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:34:28.156] INFO: number of Vcal hits detected: 207884 207902 207808 207561 207643 207580 207557 207748 207776 207690 207669 207733 207770 207821 207927 207940
[13:34:28.156] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[13:34:28.156] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[13:34:28.156] INFO: X-ray hit rate [MHz/cm2]: 25.4 24.0 38.6 63.0 65.4 70.4 67.9 48.1 42.5 51.9 52.2 46.3 48.3 28.3 14.4 15.8
[13:34:28.156] INFO: PixTestHighRate::doXPixelAlive() done
[13:34:28.201] INFO: PixTest:: pg_setup set to default.
[13:34:28.212] INFO: enter test to run
[13:34:50.327] INFO: test: HighRate no parameter change
[13:34:50.327] INFO: running: highrate
[13:34:50.328] INFO: ----------------------------------------------------------------------
[13:34:50.328] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:34:50.328] INFO: ----------------------------------------------------------------------
[13:34:50.948] INFO: Expecting 208000 events.
[13:35:06.109] INFO: 208000 events read in total (14634ms).
[13:35:06.116] INFO: Test took 15778ms.
[13:35:06.532] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:06.871] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[13:35:06.871] INFO: number of red-efficiency pixels: 204 219 438 923 795 944 952 575 389 719 721 494 467 317 117 95
[13:35:06.871] INFO: number of X-ray hits detected: 133362 126434 202500 330323 341657 368248 354196 252355 224265 271092 274089 241892 252800 148333 75361 82405
[13:35:06.871] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:35:06.871] INFO: number of Vcal hits detected: 207786 207765 207492 206910 207054 206873 206884 207354 207584 207185 207170 207412 207493 207649 207878 207904
[13:35:06.871] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.5 99.6 99.5 99.5 99.7 99.8 99.6 99.6 99.8 99.8 99.8 99.9 100.0
[13:35:06.871] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.5 99.5 99.5 99.5 99.7 99.8 99.6 99.6 99.7 99.8 99.8 99.9 100.0
[13:35:06.871] INFO: X-ray hit rate [MHz/cm2]: 39.1 37.1 59.4 96.8 100.1 107.9 103.8 74.0 65.7 79.5 80.3 70.9 74.1 43.5 22.1 24.2
[13:35:06.871] INFO: PixTestHighRate::doXPixelAlive() done
[13:35:06.918] INFO: PixTest:: pg_setup set to default.
[13:35:06.934] INFO: enter test to run
[13:35:09.551] INFO: test: exit no parameter change
[13:35:09.834] QUIET: Connection to board 33 closed.
[13:35:09.835] INFO: pXar: this is the end, my friend