[18:07:42.762] INFO: *** Welcome to pxar ***
[18:07:42.762] INFO: *** Today: 2016/08/24
[18:07:42.799] INFO: *** Version: v1.9.0-818-g96727
[18:07:42.799] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//dacParameters35_C15.dat
[18:07:42.844] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//tbmParameters_C0b.dat
[18:07:42.844] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//defaultMaskFile.dat
[18:07:42.862] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C15.dat
[18:07:42.959] INFO: clk: 4
[18:07:42.959] INFO: ctr: 4
[18:07:42.959] INFO: sda: 19
[18:07:42.959] INFO: tin: 9
[18:07:42.959] INFO: level: 15
[18:07:42.959] INFO: triggerdelay: 0
[18:07:42.959] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[18:07:42.959] INFO: Log level: INFO
[18:07:42.978] QUIET: Connection to board DTB_WREKRL opened.
[18:07:42.981] 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:
------------------------------------------------------
[18:07:42.984] INFO: RPC call hashes of host and DTB match: 398089610
[18:07:44.512] INFO: DUT info:
[18:07:44.512] INFO: The DUT currently contains the following objects:
[18:07:44.512] INFO: 2 TBM Cores tbm08c (2 ON)
[18:07:44.512] INFO: TBM Core alpha (0): 7 registers set
[18:07:44.512] INFO: TBM Core beta (1): 7 registers set
[18:07:44.512] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[18:07:44.512] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.512] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.512] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.512] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.512] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.512] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.513] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.513] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.513] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.513] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.513] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.513] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.513] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.513] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.513] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.513] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[18:07:44.916] INFO: enter 'restricted' command line mode
[18:07:44.916] INFO: enter test to run
[18:07:50.569] INFO: test: PixelAlive no parameter change
[18:07:50.569] INFO: running: pixelalive
[18:07:50.578] INFO: ----------------------------------------------------------------------
[18:07:50.578] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:07:50.578] INFO: ----------------------------------------------------------------------
[18:07:50.900] INFO: Expecting 41600 events.
[18:07:55.236] INFO: 41600 events read in total (3617ms).
[18:07:55.400] INFO: Test took 4819ms.
[18:07:55.409] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:07:55.686] INFO: PixTestAlive::aliveTest() done
[18:07:55.686] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 1 0 0 0 0
[18:07:55.716] INFO: enter test to run
[18:08:23.649] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[18:08:23.649] INFO: running: highrate
[18:08:23.649] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[18:08:23.917] INFO: ----------------------------------------------------------------------
[18:08:23.918] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[18:08:23.918] INFO: ----------------------------------------------------------------------
[18:08:23.918] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[18:08:23.918] INFO: edge/corner pixel THR is adjusted
[18:08:23.918] INFO: PixTestHighRate::trimHotPixels: step 0...
[18:08:24.877] INFO: Collecting data for 5 seconds...
[18:08:29.895] INFO: Done with hot pixel readout
[18:08:41.083] INFO: PixTest:: pg_setup set to default.
[18:08:41.084] INFO: 1 hot pixels found in step 0
[18:08:42.092] INFO: Collecting data for 5 seconds...
[18:08:47.109] INFO: Done with hot pixel readout
[18:08:58.292] INFO: PixTest:: pg_setup set to default.
[18:08:58.292] INFO: 0 hot pixels found in step 1
[18:08:58.325] INFO: 0 hot pixels could not be trimmed and have been masked.
[18:08:58.328] INFO: PixTest::trimHotPixels() done
[18:08:58.337] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C0.dat
[18:08:58.349] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C1.dat
[18:08:58.360] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C2.dat
[18:08:58.365] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C3.dat
[18:08:58.370] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C4.dat
[18:08:58.376] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C5.dat
[18:08:58.381] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C6.dat
[18:08:58.386] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C7.dat
[18:08:58.392] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C8.dat
[18:08:58.397] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C9.dat
[18:08:58.402] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C10.dat
[18:08:58.408] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C11.dat
[18:08:58.413] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C12.dat
[18:08:58.418] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C13.dat
[18:08:58.424] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C14.dat
[18:08:58.429] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C15.dat
[18:08:58.435] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//defaultMaskFile.dat
[18:08:58.453] INFO: enter test to run
[18:09:57.544] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[18:09:57.544] INFO: running: highrate
[18:09:57.549] INFO: ----------------------------------------------------------------------
[18:09:57.549] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[18:09:57.549] INFO: ----------------------------------------------------------------------
[18:09:57.549] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[18:09:57.549] INFO: edge/corner pixel THR is adjusted
[18:09:57.549] INFO: PixTestHighRate::trimHotPixels: step 0...
[18:09:58.506] INFO: Collecting data for 1 seconds...
[18:09:59.510] INFO: Done with hot pixel readout
[18:10:03.159] INFO: PixTest:: pg_setup set to default.
[18:10:03.160] INFO: 0 hot pixels found in step 0
[18:10:03.166] INFO: 0 hot pixels could not be trimmed and have been masked.
[18:10:03.265] INFO: PixTest::trimHotPixels() done
[18:10:03.265] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C0.dat
[18:10:03.277] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C1.dat
[18:10:03.283] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C2.dat
[18:10:03.288] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C3.dat
[18:10:03.294] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C4.dat
[18:10:03.299] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C5.dat
[18:10:03.305] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C6.dat
[18:10:03.310] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C7.dat
[18:10:03.316] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C8.dat
[18:10:03.321] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C9.dat
[18:10:03.326] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C10.dat
[18:10:03.332] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C11.dat
[18:10:03.337] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C12.dat
[18:10:03.343] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C13.dat
[18:10:03.348] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C14.dat
[18:10:03.353] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//trimParameters35_C15.dat
[18:10:03.359] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-3-10_FPIXTest-17C-FNAL-160818-1412-150V_2016-08-18_14h12m_1471547542/000_FPIXTest_p17//defaultMaskFile.dat
[18:10:03.369] INFO: enter test to run
[18:10:43.608] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[18:10:43.608] INFO: running: xray
[18:10:43.609] INFO: ----------------------------------------------------------------------
[18:10:43.609] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[18:10:43.610] INFO: ----------------------------------------------------------------------
[18:10:44.574] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[18:10:56.614] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[18:11:25.750] INFO: Resuming triggers.
[18:11:37.788] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[18:12:07.289] INFO: Resuming triggers.
[18:12:19.331] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[18:12:49.359] INFO: Resuming triggers.
[18:13:01.399] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[18:13:31.291] INFO: Resuming triggers.
[18:13:43.332] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[18:14:12.405] INFO: Resuming triggers.
[18:14:24.438] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[18:14:53.522] INFO: Resuming triggers.
[18:15:05.560] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[18:15:34.847] INFO: Resuming triggers.
[18:15:46.879] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[18:16:16.024] INFO: Resuming triggers.
[18:16:20.081] INFO: data taking finished, elapsed time: 100 seconds.
[18:16:30.188] INFO: PixTest:: pg_setup set to default.
[18:16:30.191] INFO: PixTestXray::doPhRun() done
[18:16:30.324] INFO: enter test to run
[18:16:58.358] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[18:16:58.358] INFO: running: xray
[18:16:58.359] INFO: ----------------------------------------------------------------------
[18:16:58.359] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[18:16:58.359] INFO: ----------------------------------------------------------------------
[18:16:59.324] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[18:17:06.683] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[18:17:36.752] INFO: Resuming triggers.
[18:17:44.116] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[18:18:14.116] INFO: Resuming triggers.
[18:18:21.478] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[18:18:51.583] INFO: Resuming triggers.
[18:18:58.947] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[18:19:29.103] INFO: Resuming triggers.
[18:19:36.464] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[18:20:06.627] INFO: Resuming triggers.
[18:20:13.991] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[18:20:44.069] INFO: Resuming triggers.
[18:20:51.432] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[18:21:21.513] INFO: Resuming triggers.
[18:21:28.878] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[18:21:59.710] INFO: Resuming triggers.
[18:22:07.075] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[18:22:37.307] INFO: Resuming triggers.
[18:22:44.673] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[18:23:15.529] INFO: Resuming triggers.
[18:23:22.895] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[18:23:53.432] INFO: Resuming triggers.
[18:24:00.799] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[18:24:32.034] INFO: Resuming triggers.
[18:24:39.401] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[18:25:10.468] INFO: Resuming triggers.
[18:25:15.080] INFO: data taking finished, elapsed time: 100 seconds.
[18:25:34.894] INFO: PixTest:: pg_setup set to default.
[18:25:34.898] INFO: PixTestXray::doPhRun() done
[18:25:35.044] INFO: enter test to run
[18:26:00.114] INFO: test: HighRate no parameter change
[18:26:00.114] INFO: running: highrate
[18:26:00.164] INFO: ----------------------------------------------------------------------
[18:26:00.164] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[18:26:00.165] INFO: ----------------------------------------------------------------------
[18:26:00.327] INFO: Expecting 768 events.
[18:26:01.468] INFO: 768 events read in total (418ms).
[18:26:01.469] INFO: Test took 1276ms.
[18:26:02.272] INFO: Expecting 41600 events.
[18:26:05.324] INFO: 41600 events read in total (2525ms).
[18:26:05.325] INFO: Test took 3848ms.
[18:26:05.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:06.091] INFO: Expecting 41600 events.
[18:26:09.262] INFO: 41600 events read in total (2644ms).
[18:26:09.263] INFO: Test took 3891ms.
[18:26:09.294] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:10.027] INFO: Expecting 41600 events.
[18:26:13.235] INFO: 41600 events read in total (2682ms).
[18:26:13.236] INFO: Test took 3925ms.
[18:26:13.266] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:13.998] INFO: Expecting 41600 events.
[18:26:17.196] INFO: 41600 events read in total (2671ms).
[18:26:17.197] INFO: Test took 3913ms.
[18:26:17.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:17.957] INFO: Expecting 41600 events.
[18:26:21.152] INFO: 41600 events read in total (2668ms).
[18:26:21.153] INFO: Test took 3908ms.
[18:26:21.184] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:21.912] INFO: Expecting 41600 events.
[18:26:25.126] INFO: 41600 events read in total (2687ms).
[18:26:25.127] INFO: Test took 3925ms.
[18:26:25.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:25.888] INFO: Expecting 41600 events.
[18:26:29.097] INFO: 41600 events read in total (2682ms).
[18:26:29.098] INFO: Test took 3924ms.
[18:26:29.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:29.858] INFO: Expecting 41600 events.
[18:26:33.072] INFO: 41600 events read in total (2687ms).
[18:26:33.073] INFO: Test took 3929ms.
[18:26:33.104] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:33.836] INFO: Expecting 41600 events.
[18:26:37.057] INFO: 41600 events read in total (2694ms).
[18:26:37.058] INFO: Test took 3937ms.
[18:26:37.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:37.821] INFO: Expecting 41600 events.
[18:26:41.027] INFO: 41600 events read in total (2679ms).
[18:26:41.028] INFO: Test took 3922ms.
[18:26:41.059] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:41.789] INFO: Expecting 41600 events.
[18:26:44.986] INFO: 41600 events read in total (2670ms).
[18:26:44.987] INFO: Test took 3912ms.
[18:26:45.017] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:45.748] INFO: Expecting 41600 events.
[18:26:48.968] INFO: 41600 events read in total (2693ms).
[18:26:48.969] INFO: Test took 3935ms.
[18:26:48.000] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:49.728] INFO: Expecting 41600 events.
[18:26:52.944] INFO: 41600 events read in total (2689ms).
[18:26:52.945] INFO: Test took 3928ms.
[18:26:52.976] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:53.707] INFO: Expecting 41600 events.
[18:26:56.915] INFO: 41600 events read in total (2681ms).
[18:26:56.916] INFO: Test took 3924ms.
[18:26:56.947] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:57.680] INFO: Expecting 41600 events.
[18:27:00.886] INFO: 41600 events read in total (2680ms).
[18:27:00.887] INFO: Test took 3923ms.
[18:27:00.918] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:01.649] INFO: Expecting 41600 events.
[18:27:04.881] INFO: 41600 events read in total (2705ms).
[18:27:04.882] INFO: Test took 3946ms.
[18:27:04.913] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:05.643] INFO: Expecting 41600 events.
[18:27:08.852] INFO: 41600 events read in total (2682ms).
[18:27:08.853] INFO: Test took 3924ms.
[18:27:08.883] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:09.612] INFO: Expecting 41600 events.
[18:27:12.823] INFO: 41600 events read in total (2684ms).
[18:27:12.824] INFO: Test took 3923ms.
[18:27:12.854] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:13.582] INFO: Expecting 41600 events.
[18:27:16.787] INFO: 41600 events read in total (2679ms).
[18:27:16.788] INFO: Test took 3916ms.
[18:27:16.818] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:17.550] INFO: Expecting 41600 events.
[18:27:20.662] INFO: 41600 events read in total (2585ms).
[18:27:20.663] INFO: Test took 3827ms.
[18:27:20.693] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:21.061] INFO: enter test to run
[18:27:23.801] INFO: test: HighRate no parameter change
[18:27:23.801] INFO: running: highrate
[18:27:23.802] INFO: ----------------------------------------------------------------------
[18:27:23.802] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:27:23.802] INFO: ----------------------------------------------------------------------
[18:27:24.415] INFO: Expecting 208000 events.
[18:27:36.128] INFO: 208000 events read in total (11187ms).
[18:27:36.131] INFO: Test took 12322ms.
[18:27:36.261] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:36.507] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 1 0 0 0 0
[18:27:36.507] INFO: number of red-efficiency pixels: 65 33 73 97 105 115 113 75 89 117 93 87 73 49 13 29
[18:27:36.507] INFO: number of X-ray hits detected: 59266 37142 58565 90425 99645 106505 100451 72772 67111 86806 84993 74153 73856 44847 17824 19467
[18:27:36.507] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:27:36.507] INFO: number of Vcal hits detected: 207935 207966 207924 207902 207894 207873 207886 207925 207911 207780 207906 207864 207926 207951 207987 207971
[18:27:36.507] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0
[18:27:36.507] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 99.9 100.0 99.9 100.0 100.0 100.0 100.0
[18:27:36.508] INFO: X-ray hit rate [MHz/cm2]: 17.4 10.9 17.2 26.5 29.2 31.2 29.4 21.3 19.7 25.4 24.9 21.7 21.6 13.1 5.2 5.7
[18:27:36.508] INFO: PixTestHighRate::doXPixelAlive() done
[18:27:36.558] INFO: PixTest:: pg_setup set to default.
[18:27:36.570] INFO: enter test to run
[18:27:58.193] INFO: test: HighRate no parameter change
[18:27:58.193] INFO: running: highrate
[18:27:58.194] INFO: ----------------------------------------------------------------------
[18:27:58.194] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:27:58.194] INFO: ----------------------------------------------------------------------
[18:27:58.809] INFO: Expecting 208000 events.
[18:28:11.959] INFO: 208000 events read in total (12624ms).
[18:28:11.963] INFO: Test took 13759ms.
[18:28:12.225] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:28:12.512] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 1 0 0 0 0
[18:28:12.512] INFO: number of red-efficiency pixels: 167 94 222 271 260 344 422 195 204 318 294 255 191 138 43 47
[18:28:12.512] INFO: number of X-ray hits detected: 120360 75533 119724 184585 201383 216028 205206 146753 136342 175283 172934 149978 149359 91677 36350 39870
[18:28:12.512] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:28:12.512] INFO: number of Vcal hits detected: 207825 207904 207762 207715 207731 207621 207541 207792 207788 207566 207690 207684 207801 207855 207955 207952
[18:28:12.512] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[18:28:12.512] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.8 99.9 99.8 99.9 99.9 100.0 100.0
[18:28:12.512] INFO: X-ray hit rate [MHz/cm2]: 35.3 22.1 35.1 54.1 59.0 63.3 60.1 43.0 40.0 51.4 50.7 44.0 43.8 26.9 10.7 11.7
[18:28:12.512] INFO: PixTestHighRate::doXPixelAlive() done
[18:28:12.562] INFO: PixTest:: pg_setup set to default.
[18:28:12.579] INFO: enter test to run
[18:28:27.753] INFO: test: HighRate no parameter change
[18:28:27.753] INFO: running: highrate
[18:28:27.754] INFO: ----------------------------------------------------------------------
[18:28:27.754] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:28:27.754] INFO: ----------------------------------------------------------------------
[18:28:28.372] INFO: Expecting 208000 events.
[18:28:43.093] INFO: 208000 events read in total (14194ms).
[18:28:43.099] INFO: Test took 15335ms.
[18:28:43.481] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:28:43.811] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 1 0 0 0 0
[18:28:43.811] INFO: number of red-efficiency pixels: 403 170 496 632 673 849 939 487 455 762 664 528 404 353 79 71
[18:28:43.811] INFO: number of X-ray hits detected: 184411 114634 182387 280468 305711 329780 311168 224083 208029 267680 262702 229445 228526 140294 56040 61057
[18:28:43.811] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:28:43.811] INFO: number of Vcal hits detected: 207519 207819 207380 207305 207240 206976 206841 207427 207491 207009 207234 207351 207572 207605 207918 207929
[18:28:43.811] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.7 99.7 99.6 99.5 99.8 99.8 99.6 99.7 99.8 99.8 99.8 100.0 100.0
[18:28:43.811] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.7 99.6 99.5 99.4 99.7 99.8 99.5 99.6 99.7 99.8 99.8 100.0 100.0
[18:28:43.811] INFO: X-ray hit rate [MHz/cm2]: 54.1 33.6 53.5 82.2 89.6 96.7 91.2 65.7 61.0 78.5 77.0 67.3 67.0 41.1 16.4 17.9
[18:28:43.811] INFO: PixTestHighRate::doXPixelAlive() done
[18:28:43.857] INFO: PixTest:: pg_setup set to default.
[18:28:43.869] INFO: enter test to run
[18:28:49.121] INFO: test: exit no parameter change
[18:28:49.409] QUIET: Connection to board 33 closed.
[18:28:49.410] INFO: pXar: this is the end, my friend