[12:15:37.629] INFO: *** Welcome to pxar ***
[12:15:37.629] INFO: *** Today: 2016/06/09
[12:15:37.743] INFO: *** Version: v1.9.0-814-g7497
[12:15:37.743] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//dacParameters35_C15.dat
[12:15:37.777] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:15:37.777] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//defaultMaskFile.dat
[12:15:37.781] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C15.dat
[12:15:37.873] INFO: clk: 4
[12:15:37.873] INFO: ctr: 4
[12:15:37.873] INFO: sda: 19
[12:15:37.873] INFO: tin: 9
[12:15:37.873] INFO: level: 15
[12:15:37.873] INFO: triggerdelay: 0
[12:15:37.873] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:15:37.873] INFO: Log level: INFO
[12:15:37.892] QUIET: Connection to board DTB_WREKRL opened.
[12:15:37.895] 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:
------------------------------------------------------
[12:15:37.898] INFO: RPC call hashes of host and DTB match: 398089610
[12:15:39.427] INFO: DUT info:
[12:15:39.427] INFO: The DUT currently contains the following objects:
[12:15:39.427] INFO: 2 TBM Cores tbm08c (2 ON)
[12:15:39.428] INFO: TBM Core alpha (0): 7 registers set
[12:15:39.428] INFO: TBM Core beta (1): 7 registers set
[12:15:39.428] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:15:39.428] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.428] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:15:39.832] INFO: enter 'restricted' command line mode
[12:15:39.832] INFO: enter test to run
[12:15:55.254] INFO: test: PixelAlive no parameter change
[12:15:55.254] INFO: running: pixelalive
[12:15:55.263] INFO: ----------------------------------------------------------------------
[12:15:55.263] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:15:55.263] INFO: ----------------------------------------------------------------------
[12:15:55.581] INFO: Expecting 41600 events.
[12:15:59.912] INFO: 41600 events read in total (3613ms).
[12:16:00.077] INFO: Test took 4811ms.
[12:16:00.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:16:00.348] INFO: PixTestAlive::aliveTest() done
[12:16:00.348] INFO: number of dead pixels (per ROC): 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:16:00.378] INFO: enter test to run
[12:16:31.846] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:16:31.846] INFO: running: highrate
[12:16:31.846] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:16:32.069] INFO: ----------------------------------------------------------------------
[12:16:32.069] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:16:32.069] INFO: ----------------------------------------------------------------------
[12:16:32.069] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:16:32.069] INFO: edge/corner pixel THR is adjusted
[12:16:32.069] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:16:33.029] INFO: Collecting data for 5 seconds...
[12:16:38.048] INFO: Done with hot pixel readout
[12:16:49.510] INFO: PixTest:: pg_setup set to default.
[12:16:49.511] INFO: 1 hot pixels found in step 0
[12:16:50.522] INFO: Collecting data for 5 seconds...
[12:16:55.539] INFO: Done with hot pixel readout
[12:17:07.023] INFO: PixTest:: pg_setup set to default.
[12:17:07.023] INFO: 1 hot pixels found in step 1
[12:17:08.017] INFO: Collecting data for 5 seconds...
[12:17:13.034] INFO: Done with hot pixel readout
[12:17:24.505] INFO: PixTest:: pg_setup set to default.
[12:17:24.506] INFO: 2 hot pixels found in step 2
[12:17:25.497] INFO: Collecting data for 5 seconds...
[12:17:30.515] INFO: Done with hot pixel readout
[12:17:42.013] INFO: PixTest:: pg_setup set to default.
[12:17:42.014] INFO: 0 hot pixels found in step 3
[12:17:42.048] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:17:42.052] INFO: PixTest::trimHotPixels() done
[12:17:42.052] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C0.dat
[12:17:42.057] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C1.dat
[12:17:42.063] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C2.dat
[12:17:42.070] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C3.dat
[12:17:42.075] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C4.dat
[12:17:42.080] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C5.dat
[12:17:42.085] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C6.dat
[12:17:42.091] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C7.dat
[12:17:42.096] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C8.dat
[12:17:42.101] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C9.dat
[12:17:42.106] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C10.dat
[12:17:42.111] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C11.dat
[12:17:42.117] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C12.dat
[12:17:42.122] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C13.dat
[12:17:42.127] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C14.dat
[12:17:42.132] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C15.dat
[12:17:42.137] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//defaultMaskFile.dat
[12:17:42.154] INFO: enter test to run
[12:18:16.309] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:18:16.309] INFO: running: highrate
[12:18:16.314] INFO: ----------------------------------------------------------------------
[12:18:16.314] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:18:16.314] INFO: ----------------------------------------------------------------------
[12:18:16.314] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:18:16.314] INFO: edge/corner pixel THR is adjusted
[12:18:16.314] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:18:17.272] INFO: Collecting data for 1 seconds...
[12:18:18.276] INFO: Done with hot pixel readout
[12:18:22.188] INFO: PixTest:: pg_setup set to default.
[12:18:22.189] INFO: 0 hot pixels found in step 0
[12:18:22.194] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:18:22.288] INFO: PixTest::trimHotPixels() done
[12:18:22.288] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C0.dat
[12:18:22.295] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C1.dat
[12:18:22.301] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C2.dat
[12:18:22.306] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C3.dat
[12:18:22.311] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C4.dat
[12:18:22.317] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C5.dat
[12:18:22.323] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C6.dat
[12:18:22.328] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C7.dat
[12:18:22.333] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C8.dat
[12:18:22.338] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C9.dat
[12:18:22.344] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C10.dat
[12:18:22.350] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C11.dat
[12:18:22.355] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C12.dat
[12:18:22.360] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C13.dat
[12:18:22.365] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C14.dat
[12:18:22.371] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//trimParameters35_C15.dat
[12:18:22.376] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-1-05_FPIXTest-17C-FNAL-160606-1109_2016-06-06_11h09m_1465229386/000_FPIXTest_p17//defaultMaskFile.dat
[12:18:22.385] INFO: enter test to run
[12:18:55.269] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:18:55.269] INFO: running: xray
[12:18:55.270] INFO: ----------------------------------------------------------------------
[12:18:55.270] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:18:55.270] INFO: ----------------------------------------------------------------------
[12:18:56.234] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:19:07.930] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:19:37.072] INFO: Resuming triggers.
[12:19:48.767] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:20:18.138] INFO: Resuming triggers.
[12:20:29.834] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:20:59.479] INFO: Resuming triggers.
[12:21:11.174] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[12:21:40.706] INFO: Resuming triggers.
[12:21:52.403] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[12:22:21.701] INFO: Resuming triggers.
[12:22:33.399] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[12:23:03.313] INFO: Resuming triggers.
[12:23:15.014] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[12:23:44.877] INFO: Resuming triggers.
[12:23:56.573] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[12:24:25.972] INFO: Resuming triggers.
[12:24:32.771] INFO: data taking finished, elapsed time: 100 seconds.
[12:24:50.041] INFO: PixTest:: pg_setup set to default.
[12:24:50.044] INFO: PixTestXray::doPhRun() done
[12:24:50.205] INFO: enter test to run
[12:25:23.314] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:25:23.314] INFO: running: xray
[12:25:23.315] INFO: ----------------------------------------------------------------------
[12:25:23.315] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:25:23.316] INFO: ----------------------------------------------------------------------
[12:25:24.286] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:25:31.277] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[12:26:01.361] INFO: Resuming triggers.
[12:26:08.353] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[12:26:38.447] INFO: Resuming triggers.
[12:26:45.440] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[12:27:15.611] INFO: Resuming triggers.
[12:27:22.601] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[12:27:52.798] INFO: Resuming triggers.
[12:27:59.796] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:28:29.962] INFO: Resuming triggers.
[12:28:36.950] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[12:29:07.195] INFO: Resuming triggers.
[12:29:14.189] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[12:29:44.391] INFO: Resuming triggers.
[12:29:51.380] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[12:30:21.482] INFO: Resuming triggers.
[12:30:28.474] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[12:30:58.562] INFO: Resuming triggers.
[12:31:05.556] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[12:31:35.644] INFO: Resuming triggers.
[12:31:42.635] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[12:32:12.827] INFO: Resuming triggers.
[12:32:19.821] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[12:32:51.028] INFO: Resuming triggers.
[12:32:58.020] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[12:33:28.900] INFO: Resuming triggers.
[12:33:35.893] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[12:34:06.808] INFO: Resuming triggers.
[12:34:09.266] INFO: data taking finished, elapsed time: 100 seconds.
[12:34:20.937] INFO: PixTest:: pg_setup set to default.
[12:34:20.940] INFO: PixTestXray::doPhRun() done
[12:34:21.090] INFO: enter test to run
[12:35:05.366] INFO: test: HighRate no parameter change
[12:35:05.366] INFO: running: highrate
[12:35:05.367] INFO: ----------------------------------------------------------------------
[12:35:05.367] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:35:05.367] INFO: ----------------------------------------------------------------------
[12:35:05.507] INFO: Expecting 768 events.
[12:35:06.641] INFO: 768 events read in total (418ms).
[12:35:06.641] INFO: Test took 1268ms.
[12:35:07.444] INFO: Expecting 41600 events.
[12:35:10.549] INFO: 41600 events read in total (2578ms).
[12:35:10.550] INFO: Test took 3902ms.
[12:35:10.582] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:11.307] INFO: Expecting 41600 events.
[12:35:14.627] INFO: 41600 events read in total (2793ms).
[12:35:14.628] INFO: Test took 4029ms.
[12:35:14.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:15.388] INFO: Expecting 41600 events.
[12:35:18.676] INFO: 41600 events read in total (2761ms).
[12:35:18.677] INFO: Test took 3999ms.
[12:35:18.710] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:19.434] INFO: Expecting 41600 events.
[12:35:22.751] INFO: 41600 events read in total (2790ms).
[12:35:22.752] INFO: Test took 4025ms.
[12:35:22.794] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:23.505] INFO: Expecting 41600 events.
[12:35:26.825] INFO: 41600 events read in total (2793ms).
[12:35:26.826] INFO: Test took 4007ms.
[12:35:26.860] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:27.582] INFO: Expecting 41600 events.
[12:35:30.857] INFO: 41600 events read in total (2749ms).
[12:35:30.858] INFO: Test took 3981ms.
[12:35:30.891] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:31.613] INFO: Expecting 41600 events.
[12:35:34.853] INFO: 41600 events read in total (2714ms).
[12:35:34.854] INFO: Test took 3946ms.
[12:35:34.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:35.610] INFO: Expecting 41600 events.
[12:35:38.835] INFO: 41600 events read in total (2698ms).
[12:35:38.836] INFO: Test took 3932ms.
[12:35:38.868] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:39.592] INFO: Expecting 41600 events.
[12:35:42.832] INFO: 41600 events read in total (2713ms).
[12:35:42.833] INFO: Test took 3948ms.
[12:35:42.866] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:43.592] INFO: Expecting 41600 events.
[12:35:46.817] INFO: 41600 events read in total (2698ms).
[12:35:46.818] INFO: Test took 3935ms.
[12:35:46.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:47.577] INFO: Expecting 41600 events.
[12:35:50.813] INFO: 41600 events read in total (2710ms).
[12:35:50.814] INFO: Test took 3948ms.
[12:35:50.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:51.574] INFO: Expecting 41600 events.
[12:35:54.808] INFO: 41600 events read in total (2707ms).
[12:35:54.809] INFO: Test took 3944ms.
[12:35:54.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:55.567] INFO: Expecting 41600 events.
[12:35:58.807] INFO: 41600 events read in total (2713ms).
[12:35:58.808] INFO: Test took 3950ms.
[12:35:58.840] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:59.568] INFO: Expecting 41600 events.
[12:36:02.800] INFO: 41600 events read in total (2705ms).
[12:36:02.801] INFO: Test took 3944ms.
[12:36:02.833] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:03.559] INFO: Expecting 41600 events.
[12:36:06.795] INFO: 41600 events read in total (2709ms).
[12:36:06.796] INFO: Test took 3944ms.
[12:36:06.828] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:07.552] INFO: Expecting 41600 events.
[12:36:10.784] INFO: 41600 events read in total (2705ms).
[12:36:10.785] INFO: Test took 3939ms.
[12:36:10.818] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:11.547] INFO: Expecting 41600 events.
[12:36:14.777] INFO: 41600 events read in total (2703ms).
[12:36:14.778] INFO: Test took 3942ms.
[12:36:14.809] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:15.536] INFO: Expecting 41600 events.
[12:36:18.772] INFO: 41600 events read in total (2709ms).
[12:36:18.773] INFO: Test took 3945ms.
[12:36:18.805] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:19.532] INFO: Expecting 41600 events.
[12:36:22.743] INFO: 41600 events read in total (2684ms).
[12:36:22.744] INFO: Test took 3920ms.
[12:36:22.776] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:23.501] INFO: Expecting 41600 events.
[12:36:26.583] INFO: 41600 events read in total (2555ms).
[12:36:26.584] INFO: Test took 3791ms.
[12:36:26.615] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:26.981] INFO: enter test to run
[12:36:31.525] INFO: test: HighRate no parameter change
[12:36:31.525] INFO: running: highrate
[12:36:31.527] INFO: ----------------------------------------------------------------------
[12:36:31.527] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:36:31.527] INFO: ----------------------------------------------------------------------
[12:36:32.140] INFO: Expecting 208000 events.
[12:36:43.969] INFO: 208000 events read in total (11302ms).
[12:36:43.972] INFO: Test took 12439ms.
[12:36:44.108] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:44.357] INFO: number of dead pixels (per ROC): 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:36:44.357] INFO: number of red-efficiency pixels: 85 55 79 135 116 133 118 63 78 113 120 106 79 63 26 25
[12:36:44.357] INFO: number of X-ray hits detected: 68179 45331 61132 95810 99850 107013 100367 74740 77387 90679 91908 78201 83875 48490 22207 23782
[12:36:44.357] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:36:44.357] INFO: number of Vcal hits detected: 207911 207944 207822 207861 207883 207863 207880 207937 207918 207882 207878 207894 207921 207936 207974 207975
[12:36:44.357] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[12:36:44.357] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[12:36:44.357] INFO: X-ray hit rate [MHz/cm2]: 20.0 13.3 17.9 28.1 29.3 31.4 29.4 21.9 22.7 26.6 26.9 22.9 24.6 14.2 6.5 7.0
[12:36:44.357] INFO: PixTestHighRate::doXPixelAlive() done
[12:36:44.404] INFO: PixTest:: pg_setup set to default.
[12:36:44.414] INFO: enter test to run
[12:37:22.101] INFO: test: HighRate no parameter change
[12:37:22.101] INFO: running: highrate
[12:37:22.102] INFO: ----------------------------------------------------------------------
[12:37:22.102] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:37:22.102] INFO: ----------------------------------------------------------------------
[12:37:22.717] INFO: Expecting 208000 events.
[12:37:36.069] INFO: 208000 events read in total (12825ms).
[12:37:36.074] INFO: Test took 13963ms.
[12:37:36.347] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:36.639] INFO: number of dead pixels (per ROC): 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:37:36.639] INFO: number of red-efficiency pixels: 223 123 204 420 409 374 287 226 238 356 385 275 245 125 51 51
[12:37:36.639] INFO: number of X-ray hits detected: 139534 92970 125515 197696 205175 218717 206264 153641 160013 187234 189320 158762 172083 99989 45137 49492
[12:37:36.639] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:37:36.639] INFO: number of Vcal hits detected: 207772 207871 207684 207547 207562 207601 207695 207770 207757 207621 207601 207713 207742 207870 207949 207949
[12:37:36.639] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[12:37:36.639] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[12:37:36.639] INFO: X-ray hit rate [MHz/cm2]: 40.9 27.3 36.8 57.9 60.1 64.1 60.5 45.0 46.9 54.9 55.5 46.5 50.4 29.3 13.2 14.5
[12:37:36.639] INFO: PixTestHighRate::doXPixelAlive() done
[12:37:36.688] INFO: PixTest:: pg_setup set to default.
[12:37:36.706] INFO: enter test to run
[12:37:57.597] INFO: test: HighRate no parameter change
[12:37:57.597] INFO: running: highrate
[12:37:57.598] INFO: ----------------------------------------------------------------------
[12:37:57.598] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:37:57.598] INFO: ----------------------------------------------------------------------
[12:37:58.216] INFO: Expecting 208000 events.
[12:38:13.325] INFO: 208000 events read in total (14583ms).
[12:38:13.332] INFO: Test took 15724ms.
[12:38:13.743] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:38:14.080] INFO: number of dead pixels (per ROC): 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:38:14.080] INFO: number of red-efficiency pixels: 418 238 450 928 941 835 735 408 506 809 840 603 514 252 78 78
[12:38:14.080] INFO: number of X-ray hits detected: 210233 140413 188626 298911 308475 329071 309652 232567 240067 282739 284381 239111 258379 151362 68191 74768
[12:38:14.080] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:38:14.080] INFO: number of Vcal hits detected: 207520 207745 207372 206880 206841 206999 207150 207540 207427 207033 207011 207291 207450 207728 207922 207918
[12:38:14.080] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.5 99.5 99.6 99.6 99.8 99.8 99.6 99.6 99.7 99.8 99.9 100.0 100.0
[12:38:14.080] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.5 99.4 99.5 99.6 99.8 99.7 99.5 99.5 99.7 99.7 99.9 100.0 100.0
[12:38:14.080] INFO: X-ray hit rate [MHz/cm2]: 61.6 41.2 55.3 87.6 90.4 96.5 90.8 68.2 70.4 82.9 83.4 70.1 75.7 44.4 20.0 21.9
[12:38:14.080] INFO: PixTestHighRate::doXPixelAlive() done
[12:38:14.130] INFO: PixTest:: pg_setup set to default.
[12:38:14.148] INFO: enter test to run
[12:38:18.284] INFO: test: exit no parameter change
[12:38:18.613] QUIET: Connection to board 33 closed.
[12:38:18.614] INFO: pXar: this is the end, my friend