[14:02:27.977] INFO: *** Welcome to pxar ***
[14:02:27.977] INFO: *** Today: 2016/04/11
[14:02:28.007] INFO: *** Version: v1.9.0-796-gef167-dirty
[14:02:28.007] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//dacParameters35_C15.dat
[14:02:28.046] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:02:28.046] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//defaultMaskFile.dat
[14:02:28.047] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C15.dat
[14:02:28.149] INFO: clk: 4
[14:02:28.149] INFO: ctr: 4
[14:02:28.149] INFO: sda: 19
[14:02:28.149] INFO: tin: 9
[14:02:28.149] INFO: level: 15
[14:02:28.149] INFO: triggerdelay: 0
[14:02:28.149] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[14:02:28.149] INFO: Log level: INFO
[14:02:28.166] QUIET: Connection to board DTB_WREKRL opened.
[14:02:28.170] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[14:02:28.173] INFO: RPC call hashes of host and DTB match: 398089610
[14:02:29.705] INFO: DUT info:
[14:02:29.705] INFO: The DUT currently contains the following objects:
[14:02:29.705] INFO: 2 TBM Cores tbm08c (2 ON)
[14:02:29.705] INFO: TBM Core alpha (0): 7 registers set
[14:02:29.705] INFO: TBM Core beta (1): 7 registers set
[14:02:29.705] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:02:29.705] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:29.705] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:02:30.109] INFO: enter 'restricted' command line mode
[14:02:30.109] INFO: enter test to run
[14:02:35.199] INFO: test: PixelAlive no parameter change
[14:02:35.199] INFO: running: pixelalive
[14:02:35.208] INFO: ----------------------------------------------------------------------
[14:02:35.208] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:02:35.208] INFO: ----------------------------------------------------------------------
[14:02:35.529] INFO: Expecting 41600 events.
[14:02:39.858] INFO: 41600 events read in total (3610ms).
[14:02:40.027] INFO: Test took 4817ms.
[14:02:40.038] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:40.302] INFO: PixTestAlive::aliveTest() done
[14:02:40.302] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 4 1 0 0 0 0 0 0
[14:02:40.335] INFO: enter test to run
[14:03:08.246] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:03:08.247] INFO: running: highrate
[14:03:08.247] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:03:08.479] INFO: ----------------------------------------------------------------------
[14:03:08.479] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:03:08.479] INFO: ----------------------------------------------------------------------
[14:03:08.479] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:03:08.479] INFO: edge/corner pixel THR is adjusted
[14:03:08.479] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:03:09.438] INFO: Collecting data for 5 seconds...
[14:03:14.455] INFO: Done with hot pixel readout
[14:03:26.253] INFO: PixTest:: pg_setup set to default.
[14:03:26.254] INFO: 2 hot pixels found in step 0
[14:03:27.248] INFO: Collecting data for 5 seconds...
[14:03:32.265] INFO: Done with hot pixel readout
[14:03:43.986] INFO: PixTest:: pg_setup set to default.
[14:03:43.987] INFO: 1 hot pixels found in step 1
[14:03:44.981] INFO: Collecting data for 5 seconds...
[14:03:49.998] INFO: Done with hot pixel readout
[14:04:01.781] INFO: PixTest:: pg_setup set to default.
[14:04:01.781] INFO: 0 hot pixels found in step 2
[14:04:01.818] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:04:01.822] INFO: PixTest::trimHotPixels() done
[14:04:01.822] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C0.dat
[14:04:01.827] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C1.dat
[14:04:01.833] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C2.dat
[14:04:01.856] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C3.dat
[14:04:01.867] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C4.dat
[14:04:01.872] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C5.dat
[14:04:01.877] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C6.dat
[14:04:01.882] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C7.dat
[14:04:01.887] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C8.dat
[14:04:01.893] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C9.dat
[14:04:01.898] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C10.dat
[14:04:01.903] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C11.dat
[14:04:01.908] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C12.dat
[14:04:01.913] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C13.dat
[14:04:01.919] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C14.dat
[14:04:01.924] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C15.dat
[14:04:01.929] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//defaultMaskFile.dat
[14:04:01.939] INFO: enter test to run
[14:05:04.118] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:05:04.118] INFO: running: highrate
[14:05:04.122] INFO: ----------------------------------------------------------------------
[14:05:04.122] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:05:04.122] INFO: ----------------------------------------------------------------------
[14:05:04.122] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:05:04.122] INFO: edge/corner pixel THR is adjusted
[14:05:04.122] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:05:05.080] INFO: Collecting data for 1 seconds...
[14:05:06.083] INFO: Done with hot pixel readout
[14:05:10.210] INFO: PixTest:: pg_setup set to default.
[14:05:10.210] INFO: 0 hot pixels found in step 0
[14:05:10.215] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:05:10.302] INFO: PixTest::trimHotPixels() done
[14:05:10.302] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C0.dat
[14:05:10.314] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C1.dat
[14:05:10.319] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C2.dat
[14:05:10.324] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C3.dat
[14:05:10.329] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C4.dat
[14:05:10.335] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C5.dat
[14:05:10.340] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C6.dat
[14:05:10.345] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C7.dat
[14:05:10.350] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C8.dat
[14:05:10.355] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C9.dat
[14:05:10.360] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C10.dat
[14:05:10.366] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C11.dat
[14:05:10.371] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C12.dat
[14:05:10.376] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C13.dat
[14:05:10.381] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C14.dat
[14:05:10.386] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C15.dat
[14:05:10.391] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-3-04_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//defaultMaskFile.dat
[14:05:10.401] INFO: enter test to run
[14:05:34.005] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:05:34.006] INFO: running: xray
[14:05:35.007] INFO: ----------------------------------------------------------------------
[14:05:35.007] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:05:35.007] INFO: ----------------------------------------------------------------------
[14:05:35.971] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:05:47.491] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:06:16.743] INFO: Resuming triggers.
[14:06:28.267] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[14:06:57.662] INFO: Resuming triggers.
[14:07:09.188] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:07:38.486] INFO: Resuming triggers.
[14:07:50.009] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[14:08:16.346] INFO: Resuming triggers.
[14:08:27.869] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[14:08:57.222] INFO: Resuming triggers.
[14:09:08.748] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[14:09:38.050] INFO: Resuming triggers.
[14:09:49.575] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[14:10:18.815] INFO: Resuming triggers.
[14:10:30.339] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[14:10:59.556] INFO: Resuming triggers.
[14:11:07.697] INFO: data taking finished, elapsed time: 100 seconds.
[14:11:28.525] INFO: PixTest:: pg_setup set to default.
[14:11:28.528] INFO: PixTestXray::doPhRun() done
[14:11:28.664] INFO: enter test to run
[14:11:49.075] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:11:49.075] INFO: running: xray
[14:11:49.076] INFO: ----------------------------------------------------------------------
[14:11:49.076] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:11:49.076] INFO: ----------------------------------------------------------------------
[14:11:50.039] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:11:56.520] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:12:26.404] INFO: Resuming triggers.
[14:12:32.886] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[14:13:02.983] INFO: Resuming triggers.
[14:13:09.466] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[14:13:39.573] INFO: Resuming triggers.
[14:13:46.050] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[14:14:16.135] INFO: Resuming triggers.
[14:14:22.616] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[14:14:52.756] INFO: Resuming triggers.
[14:14:59.236] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[14:15:29.371] INFO: Resuming triggers.
[14:15:35.850] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[14:16:05.946] INFO: Resuming triggers.
[14:16:12.424] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[14:16:42.790] INFO: Resuming triggers.
[14:16:49.269] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[14:17:19.396] INFO: Resuming triggers.
[14:17:25.874] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[14:17:54.847] INFO: Resuming triggers.
[14:18:01.323] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[14:18:28.271] INFO: Resuming triggers.
[14:18:34.747] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[14:19:04.810] INFO: Resuming triggers.
[14:19:11.288] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[14:19:41.401] INFO: Resuming triggers.
[14:19:47.879] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[14:20:17.942] INFO: Resuming triggers.
[14:20:24.422] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[14:20:54.538] INFO: Resuming triggers.
[14:20:57.687] INFO: data taking finished, elapsed time: 100 seconds.
[14:21:12.549] INFO: PixTest:: pg_setup set to default.
[14:21:12.553] INFO: PixTestXray::doPhRun() done
[14:21:12.700] INFO: enter test to run
[14:21:50.670] INFO: test: -HighRate no parameter change
[14:21:50.670] INFO: running: -highrate
[14:21:50.688] INFO: command ->-highrate<- not known, ignored
[14:21:50.688] INFO: enter test to run
[14:22:04.326] INFO: test: HighRate no parameter change
[14:22:04.326] INFO: running: highrate
[14:22:04.327] INFO: ----------------------------------------------------------------------
[14:22:04.327] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:22:04.327] INFO: ----------------------------------------------------------------------
[14:22:04.468] INFO: Expecting 768 events.
[14:22:05.601] INFO: 768 events read in total (418ms).
[14:22:05.602] INFO: Test took 1269ms.
[14:22:06.405] INFO: Expecting 41600 events.
[14:22:09.479] INFO: 41600 events read in total (2547ms).
[14:22:09.480] INFO: Test took 3872ms.
[14:22:09.513] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:10.241] INFO: Expecting 41600 events.
[14:22:13.432] INFO: 41600 events read in total (2664ms).
[14:22:13.433] INFO: Test took 3903ms.
[14:22:13.467] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:14.193] INFO: Expecting 41600 events.
[14:22:17.425] INFO: 41600 events read in total (2705ms).
[14:22:17.426] INFO: Test took 3942ms.
[14:22:17.460] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:18.183] INFO: Expecting 41600 events.
[14:22:21.423] INFO: 41600 events read in total (2713ms).
[14:22:21.424] INFO: Test took 3946ms.
[14:22:21.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:22.181] INFO: Expecting 41600 events.
[14:22:25.419] INFO: 41600 events read in total (2712ms).
[14:22:25.420] INFO: Test took 3944ms.
[14:22:25.454] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:26.178] INFO: Expecting 41600 events.
[14:22:29.414] INFO: 41600 events read in total (2709ms).
[14:22:29.415] INFO: Test took 3942ms.
[14:22:29.448] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:30.171] INFO: Expecting 41600 events.
[14:22:33.410] INFO: 41600 events read in total (2712ms).
[14:22:33.411] INFO: Test took 3944ms.
[14:22:33.445] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:34.168] INFO: Expecting 41600 events.
[14:22:37.423] INFO: 41600 events read in total (2728ms).
[14:22:37.424] INFO: Test took 3961ms.
[14:22:37.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:38.184] INFO: Expecting 41600 events.
[14:22:41.427] INFO: 41600 events read in total (2716ms).
[14:22:41.428] INFO: Test took 3952ms.
[14:22:41.461] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:42.184] INFO: Expecting 41600 events.
[14:22:45.426] INFO: 41600 events read in total (2715ms).
[14:22:45.427] INFO: Test took 3947ms.
[14:22:45.461] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:46.187] INFO: Expecting 41600 events.
[14:22:49.429] INFO: 41600 events read in total (2715ms).
[14:22:49.430] INFO: Test took 3951ms.
[14:22:49.463] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:50.185] INFO: Expecting 41600 events.
[14:22:53.418] INFO: 41600 events read in total (2706ms).
[14:22:53.419] INFO: Test took 3937ms.
[14:22:53.452] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:54.179] INFO: Expecting 41600 events.
[14:22:57.410] INFO: 41600 events read in total (2704ms).
[14:22:57.411] INFO: Test took 3940ms.
[14:22:57.445] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:58.169] INFO: Expecting 41600 events.
[14:23:01.417] INFO: 41600 events read in total (2721ms).
[14:23:01.418] INFO: Test took 3956ms.
[14:23:01.451] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:02.173] INFO: Expecting 41600 events.
[14:23:05.419] INFO: 41600 events read in total (2719ms).
[14:23:05.420] INFO: Test took 3952ms.
[14:23:05.453] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:06.180] INFO: Expecting 41600 events.
[14:23:09.422] INFO: 41600 events read in total (2715ms).
[14:23:09.423] INFO: Test took 3952ms.
[14:23:09.456] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:10.181] INFO: Expecting 41600 events.
[14:23:13.408] INFO: 41600 events read in total (2700ms).
[14:23:13.408] INFO: Test took 3935ms.
[14:23:13.442] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:14.166] INFO: Expecting 41600 events.
[14:23:17.407] INFO: 41600 events read in total (2714ms).
[14:23:17.408] INFO: Test took 3947ms.
[14:23:17.441] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:18.169] INFO: Expecting 41600 events.
[14:23:21.401] INFO: 41600 events read in total (2705ms).
[14:23:21.402] INFO: Test took 3942ms.
[14:23:21.436] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:22.161] INFO: Expecting 41600 events.
[14:23:25.262] INFO: 41600 events read in total (2574ms).
[14:23:25.263] INFO: Test took 3808ms.
[14:23:25.297] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:25.659] INFO: enter test to run
[14:25:23.709] INFO: test: HighRate no parameter change
[14:25:23.709] INFO: running: highrate
[14:25:23.710] INFO: ----------------------------------------------------------------------
[14:25:23.710] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:25:23.710] INFO: ----------------------------------------------------------------------
[14:25:24.323] INFO: Expecting 208000 events.
[14:25:36.177] INFO: 208000 events read in total (11327ms).
[14:25:36.180] INFO: Test took 12462ms.
[14:25:36.324] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:36.575] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 4 1 0 0 0 0 0 0
[14:25:36.575] INFO: number of red-efficiency pixels: 85 62 97 146 111 124 133 107 99 105 107 97 94 64 20 28
[14:25:36.575] INFO: number of X-ray hits detected: 65043 44641 66104 103863 109431 110146 111219 84704 83664 98355 94024 82852 85706 51077 22413 24213
[14:25:36.575] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:25:36.575] INFO: number of Vcal hits detected: 207915 207935 207902 207850 207887 207875 207865 207888 207703 207845 207892 207897 207905 207936 207978 207972
[14:25:36.575] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[14:25:36.575] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[14:25:36.575] INFO: X-ray hit rate [MHz/cm2]: 19.1 13.1 19.4 30.4 32.1 32.3 32.6 24.8 24.5 28.8 27.6 24.3 25.1 15.0 6.6 7.1
[14:25:36.575] INFO: PixTestHighRate::doXPixelAlive() done
[14:25:36.620] INFO: PixTest:: pg_setup set to default.
[14:25:36.633] INFO: enter test to run
[14:25:55.212] INFO: test: HighRate no parameter change
[14:25:55.212] INFO: running: highrate
[14:25:55.213] INFO: ----------------------------------------------------------------------
[14:25:55.214] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:25:55.214] INFO: ----------------------------------------------------------------------
[14:25:55.830] INFO: Expecting 208000 events.
[14:26:09.677] INFO: 208000 events read in total (13320ms).
[14:26:09.683] INFO: Test took 14461ms.
[14:26:09.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:10.301] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 4 1 0 0 7 0 0 0
[14:26:10.301] INFO: number of red-efficiency pixels: 242 139 278 495 416 464 545 373 332 330 307 299 289 178 54 63
[14:26:10.301] INFO: number of X-ray hits detected: 143635 98954 145205 230584 241174 244283 245795 186032 184656 216458 206900 183525 189342 113618 48994 54143
[14:26:10.301] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:26:10.301] INFO: number of Vcal hits detected: 207742 207858 207690 207456 207548 207501 207411 207587 207459 207610 207681 207684 206746 207813 207944 207937
[14:26:10.301] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.9 99.8 99.9 99.9 99.6 99.9 100.0 100.0
[14:26:10.301] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.7 99.8 99.8 99.7 99.8 99.7 99.8 99.8 99.8 99.4 99.9 100.0 100.0
[14:26:10.301] INFO: X-ray hit rate [MHz/cm2]: 42.1 29.0 42.6 67.6 70.7 71.6 72.0 54.5 54.1 63.4 60.6 53.8 55.5 33.3 14.4 15.9
[14:26:10.301] INFO: PixTestHighRate::doXPixelAlive() done
[14:26:10.346] INFO: PixTest:: pg_setup set to default.
[14:26:10.363] INFO: enter test to run
[14:26:38.164] INFO: test: HighRate no parameter change
[14:26:38.164] INFO: running: highrate
[14:26:38.165] INFO: ----------------------------------------------------------------------
[14:26:38.165] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:26:38.165] INFO: ----------------------------------------------------------------------
[14:26:38.782] INFO: Expecting 208000 events.
[14:26:54.550] INFO: 208000 events read in total (15241ms).
[14:26:54.558] INFO: Test took 16382ms.
[14:26:55.017] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:55.368] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 4 1 0 0 24 0 0 0
[14:26:55.368] INFO: number of red-efficiency pixels: 412 321 671 1215 978 921 1337 837 771 705 668 619 677 398 103 134
[14:26:55.368] INFO: number of X-ray hits detected: 214254 148471 216131 342570 360352 361721 366094 277344 276800 322572 309774 274266 281742 170495 73292 80919
[14:26:55.368] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:26:55.368] INFO: number of Vcal hits detected: 207546 207645 207086 206481 206835 206871 206222 206953 206930 207141 207223 207275 204714 207531 207891 207863
[14:26:55.368] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.6 99.3 99.5 99.5 99.2 99.5 99.6 99.6 99.7 99.7 99.0 99.8 99.9 99.9
[14:26:55.368] INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.6 99.3 99.4 99.5 99.1 99.5 99.5 99.6 99.6 99.7 98.4 99.8 99.9 99.9
[14:26:55.368] INFO: X-ray hit rate [MHz/cm2]: 62.8 43.5 63.3 100.4 105.6 106.0 107.3 81.3 81.1 94.5 90.8 80.4 82.6 50.0 21.5 23.7
[14:26:55.368] INFO: PixTestHighRate::doXPixelAlive() done
[14:26:55.423] INFO: PixTest:: pg_setup set to default.
[14:26:55.438] INFO: enter test to run
[14:26:58.820] INFO: test: exit no parameter change
[14:26:59.127] QUIET: Connection to board 33 closed.
[14:26:59.128] INFO: pXar: this is the end, my friend