[12:38:15.544] INFO: *** Welcome to pxar ***
[12:38:15.544] INFO: *** Today: 2016/06/13
[12:38:15.561] INFO: *** Version: v1.9.0-814-g7497
[12:38:15.561] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//dacParameters35_C15.dat
[12:38:15.562] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:38:15.562] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//defaultMaskFile.dat
[12:38:15.562] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C15.dat
[12:38:15.637] INFO: clk: 4
[12:38:15.637] INFO: ctr: 4
[12:38:15.637] INFO: sda: 19
[12:38:15.637] INFO: tin: 9
[12:38:15.637] INFO: level: 15
[12:38:15.637] INFO: triggerdelay: 0
[12:38:15.637] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:38:15.637] INFO: Log level: INFO
[12:38:15.656] QUIET: Connection to board DTB_WREKRL opened.
[12:38:15.659] 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:38:15.662] INFO: RPC call hashes of host and DTB match: 398089610
[12:38:17.203] INFO: DUT info:
[12:38:17.203] INFO: The DUT currently contains the following objects:
[12:38:17.203] INFO: 2 TBM Cores tbm08c (2 ON)
[12:38:17.203] INFO: TBM Core alpha (0): 7 registers set
[12:38:17.203] INFO: TBM Core beta (1): 7 registers set
[12:38:17.203] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:38:17.203] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.203] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.203] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.203] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.204] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.204] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.204] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.204] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.204] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.204] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.204] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.204] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.204] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.204] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.204] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.204] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:38:17.668] INFO: enter 'restricted' command line mode
[12:38:17.669] INFO: enter test to run
[12:38:29.157] INFO: test: PixelAlive no parameter change
[12:38:29.158] INFO: running: pixelalive
[12:38:29.230] INFO: ----------------------------------------------------------------------
[12:38:29.230] 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:38:29.230] INFO: ----------------------------------------------------------------------
[12:38:29.554] INFO: Expecting 41600 events.
[12:38:33.890] INFO: 41600 events read in total (3618ms).
[12:38:34.056] INFO: Test took 4822ms.
[12:38:34.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:38:34.669] INFO: PixTestAlive::aliveTest() done
[12:38:34.669] INFO: number of dead pixels (per ROC): 0 0 0 2 1 0 0 0 0 0 0 0 0 0 0 0
[12:38:34.821] INFO: enter test to run
[12:38:59.213] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:38:59.213] INFO: running: highrate
[12:38:59.225] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:38:59.494] INFO: ----------------------------------------------------------------------
[12:38:59.494] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:38:59.494] INFO: ----------------------------------------------------------------------
[12:38:59.494] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:38:59.494] INFO: edge/corner pixel THR is adjusted
[12:38:59.494] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:39:00.454] INFO: Collecting data for 5 seconds...
[12:39:05.469] INFO: Done with hot pixel readout
[12:39:18.575] INFO: PixTest:: pg_setup set to default.









[12:39:18.606] INFO: 474 hot pixels found in step 0
[12:39:19.607] INFO: Collecting data for 5 seconds...
[12:39:24.623] INFO: Done with hot pixel readout
[12:39:37.689] INFO: PixTest:: pg_setup set to default.
















[12:39:37.691] INFO: 441 hot pixels found in step 1
[12:39:38.689] INFO: Collecting data for 5 seconds...
[12:39:43.705] INFO: Done with hot pixel readout
[12:39:56.880] INFO: PixTest:: pg_setup set to default.
























[12:39:56.882] INFO: 403 hot pixels found in step 2
[12:39:57.883] INFO: Collecting data for 5 seconds...
[12:40:02.899] INFO: Done with hot pixel readout
[12:40:16.031] INFO: PixTest:: pg_setup set to default.




































[12:40:16.033] INFO: 413 hot pixels found in step 3
[12:40:17.034] INFO: Collecting data for 5 seconds...
[12:40:22.050] INFO: Done with hot pixel readout
[12:40:35.145] INFO: PixTest:: pg_setup set to default.




















































[12:40:35.147] INFO: 341 hot pixels found in step 4
[12:40:36.148] INFO: Collecting data for 5 seconds...
[12:40:41.163] INFO: Done with hot pixel readout
[12:40:54.272] INFO: PixTest:: pg_setup set to default.

















































[12:40:54.274] INFO: 293 hot pixels found in step 5
[12:40:55.274] INFO: Collecting data for 5 seconds...
[12:41:00.290] INFO: Done with hot pixel readout
[12:41:13.452] INFO: PixTest:: pg_setup set to default.




















































[12:41:13.455] INFO: 273 hot pixels found in step 6
[12:41:14.455] INFO: Collecting data for 5 seconds...
[12:41:19.471] INFO: Done with hot pixel readout
[12:41:32.606] INFO: PixTest:: pg_setup set to default.























































[12:41:32.608] INFO: 252 hot pixels found in step 7
[12:41:33.609] INFO: Collecting data for 5 seconds...
[12:41:38.624] INFO: Done with hot pixel readout
[12:41:51.752] INFO: PixTest:: pg_setup set to default.



































































[12:41:51.754] INFO: 257 hot pixels found in step 8
[12:41:52.756] INFO: Collecting data for 5 seconds...
[12:41:57.772] INFO: Done with hot pixel readout
[12:42:10.869] INFO: PixTest:: pg_setup set to default.






































































[12:42:10.871] INFO: 241 hot pixels found in step 9
[12:42:11.871] INFO: Collecting data for 5 seconds...
[12:42:16.888] INFO: Done with hot pixel readout
[12:42:29.987] INFO: PixTest:: pg_setup set to default.






























































[12:42:29.988] INFO: 207 hot pixels found in step 10
[12:42:30.989] INFO: Collecting data for 5 seconds...
[12:42:36.005] INFO: Done with hot pixel readout
[12:42:49.104] INFO: PixTest:: pg_setup set to default.































































[12:42:49.106] INFO: 217 hot pixels found in step 11
[12:42:50.107] INFO: Collecting data for 5 seconds...
[12:42:55.123] INFO: Done with hot pixel readout
[12:43:08.230] INFO: PixTest:: pg_setup set to default.


































































[12:43:08.232] INFO: 184 hot pixels found in step 12
[12:43:09.232] INFO: Collecting data for 5 seconds...
[12:43:14.248] INFO: Done with hot pixel readout
[12:43:27.360] INFO: PixTest:: pg_setup set to default.








































































[12:43:27.363] INFO: 192 hot pixels found in step 13
[12:43:28.364] INFO: Collecting data for 5 seconds...
[12:43:33.379] INFO: Done with hot pixel readout
[12:43:46.488] INFO: PixTest:: pg_setup set to default.








































































[12:43:46.491] INFO: 177 hot pixels found in step 14
[12:43:46.536] INFO: 177 hot pixels could not be trimmed and have been masked.
[12:43:46.546] INFO: PixTest::trimHotPixels() done
[12:43:46.546] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C0.dat
[12:43:46.552] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C1.dat
[12:43:46.557] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C2.dat
[12:43:46.563] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C3.dat
[12:43:46.568] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C4.dat
[12:43:46.573] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C5.dat
[12:43:46.579] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C6.dat
[12:43:46.584] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C7.dat
[12:43:46.589] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C8.dat
[12:43:46.594] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C9.dat
[12:43:46.600] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C10.dat
[12:43:46.605] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C11.dat
[12:43:46.610] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C12.dat
[12:43:46.615] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C13.dat
[12:43:46.620] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C14.dat
[12:43:46.626] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C15.dat
[12:43:46.631] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//defaultMaskFile.dat
[12:43:46.642] INFO: enter test to run
[12:44:58.523] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:44:58.523] INFO: running: highrate
[12:44:58.527] INFO: ----------------------------------------------------------------------
[12:44:58.527] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:44:58.527] INFO: ----------------------------------------------------------------------
[12:44:58.527] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:44:58.527] INFO: edge/corner pixel THR is adjusted
[12:44:58.528] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:44:59.485] INFO: Collecting data for 1 seconds...
[12:45:00.488] INFO: Done with hot pixel readout
[12:45:05.310] INFO: PixTest:: pg_setup set to default.
[12:45:05.311] INFO: 0 hot pixels found in step 0
[12:45:05.316] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:45:05.376] INFO: PixTest::trimHotPixels() done
[12:45:05.376] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C0.dat
[12:45:05.388] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C1.dat
[12:45:05.394] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C2.dat
[12:45:05.399] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C3.dat
[12:45:05.405] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C4.dat
[12:45:05.410] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C5.dat
[12:45:05.415] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C6.dat
[12:45:05.420] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C7.dat
[12:45:05.425] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C8.dat
[12:45:05.431] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C9.dat
[12:45:05.436] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C10.dat
[12:45:05.441] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C11.dat
[12:45:05.446] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C12.dat
[12:45:05.451] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C13.dat
[12:45:05.456] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C14.dat
[12:45:05.461] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//trimParameters35_C15.dat
[12:45:05.467] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-H-NT_FPIXTest-17C-FNAL-160608-1416_2016-06-08_14h16m_1465413417/000_FPIXTest_p17//defaultMaskFile.dat
[12:45:05.477] INFO: enter test to run
[12:46:35.282] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:46:35.282] INFO: running: xray
[12:46:35.283] INFO: ----------------------------------------------------------------------
[12:46:35.283] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:46:35.283] INFO: ----------------------------------------------------------------------
[12:46:36.264] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:46:46.202] INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[12:47:15.687] INFO: Resuming triggers.
[12:47:25.630] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[12:47:55.220] INFO: Resuming triggers.
[12:48:05.160] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[12:48:34.746] INFO: Resuming triggers.
[12:48:44.686] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[12:49:14.223] INFO: Resuming triggers.
[12:49:24.163] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[12:49:53.680] INFO: Resuming triggers.
[12:50:03.619] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[12:50:33.280] INFO: Resuming triggers.
[12:50:43.222] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[12:51:12.743] INFO: Resuming triggers.
[12:51:22.682] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[12:51:52.258] INFO: Resuming triggers.
[12:52:02.194] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[12:52:31.751] INFO: Resuming triggers.
[12:52:41.691] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[12:53:11.291] INFO: Resuming triggers.
[12:53:12.205] INFO: data taking finished, elapsed time: 100 seconds.
[12:53:15.288] INFO: PixTest:: pg_setup set to default.
[12:53:15.291] INFO: PixTestXray::doPhRun() done
[12:53:15.432] INFO: enter test to run
[13:05:26.114] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:05:26.114] INFO: running: xray
[13:05:26.115] INFO: ----------------------------------------------------------------------
[13:05:26.115] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:05:26.115] INFO: ----------------------------------------------------------------------
[13:05:27.092] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:05:32.420] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[13:06:02.540] INFO: Resuming triggers.
[13:06:07.870] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[13:06:37.983] INFO: Resuming triggers.
[13:06:43.312] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[13:07:13.428] INFO: Resuming triggers.
[13:07:18.757] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[13:07:48.798] INFO: Resuming triggers.
[13:07:54.125] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[13:08:24.277] INFO: Resuming triggers.
[13:08:29.609] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[13:08:59.722] INFO: Resuming triggers.
[13:09:05.056] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[13:09:35.157] INFO: Resuming triggers.
[13:09:40.486] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[13:10:10.588] INFO: Resuming triggers.
[13:10:15.913] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:10:46.017] INFO: Resuming triggers.
[13:10:51.346] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[13:11:21.410] INFO: Resuming triggers.
[13:11:26.735] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[13:11:56.820] INFO: Resuming triggers.
[13:12:02.144] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[13:12:32.170] INFO: Resuming triggers.
[13:12:37.498] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[13:13:07.635] INFO: Resuming triggers.
[13:13:12.962] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[13:13:43.048] INFO: Resuming triggers.
[13:13:48.379] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[13:14:18.460] INFO: Resuming triggers.
[13:14:23.788] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[13:14:54.002] INFO: Resuming triggers.
[13:14:59.329] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[13:15:29.474] INFO: Resuming triggers.
[13:15:34.802] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[13:16:04.882] INFO: Resuming triggers.
[13:16:09.283] INFO: data taking finished, elapsed time: 100 seconds.
[13:16:34.319] INFO: PixTest:: pg_setup set to default.
[13:16:34.322] INFO: PixTestXray::doPhRun() done
[13:16:34.473] INFO: enter test to run
[13:19:10.645] INFO: test: HighRate no parameter change
[13:19:10.645] INFO: running: highrate
[13:19:10.646] INFO: ----------------------------------------------------------------------
[13:19:10.646] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:19:10.646] INFO: ----------------------------------------------------------------------
[13:19:10.787] INFO: Expecting 768 events.
[13:19:11.919] INFO: 768 events read in total (416ms).
[13:19:11.920] INFO: Test took 1268ms.
[13:19:12.723] INFO: Expecting 41600 events.
[13:19:15.967] INFO: 41600 events read in total (2717ms).
[13:19:15.968] INFO: Test took 4039ms.
[13:19:16.015] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:16.718] INFO: Expecting 41600 events.
[13:19:20.048] INFO: 41600 events read in total (2803ms).
[13:19:20.049] INFO: Test took 4012ms.
[13:19:20.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:20.795] INFO: Expecting 41600 events.
[13:19:24.144] INFO: 41600 events read in total (2822ms).
[13:19:24.145] INFO: Test took 4028ms.
[13:19:24.191] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:24.894] INFO: Expecting 41600 events.
[13:19:28.245] INFO: 41600 events read in total (2824ms).
[13:19:28.246] INFO: Test took 4032ms.
[13:19:28.292] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:28.992] INFO: Expecting 41600 events.
[13:19:32.350] INFO: 41600 events read in total (2831ms).
[13:19:32.351] INFO: Test took 4037ms.
[13:19:32.397] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:33.099] INFO: Expecting 41600 events.
[13:19:36.465] INFO: 41600 events read in total (2839ms).
[13:19:36.466] INFO: Test took 4047ms.
[13:19:36.512] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:37.212] INFO: Expecting 41600 events.
[13:19:40.579] INFO: 41600 events read in total (2840ms).
[13:19:40.580] INFO: Test took 4046ms.
[13:19:40.626] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:41.324] INFO: Expecting 41600 events.
[13:19:44.687] INFO: 41600 events read in total (2836ms).
[13:19:44.688] INFO: Test took 4040ms.
[13:19:44.734] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:45.435] INFO: Expecting 41600 events.
[13:19:48.802] INFO: 41600 events read in total (2840ms).
[13:19:48.803] INFO: Test took 4046ms.
[13:19:48.849] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:49.550] INFO: Expecting 41600 events.
[13:19:52.909] INFO: 41600 events read in total (2833ms).
[13:19:52.910] INFO: Test took 4037ms.
[13:19:52.956] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:53.660] INFO: Expecting 41600 events.
[13:19:57.026] INFO: 41600 events read in total (2839ms).
[13:19:57.027] INFO: Test took 4049ms.
[13:19:57.073] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:57.776] INFO: Expecting 41600 events.
[13:20:01.151] INFO: 41600 events read in total (2848ms).
[13:20:01.152] INFO: Test took 4057ms.
[13:20:01.198] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:01.900] INFO: Expecting 41600 events.
[13:20:05.254] INFO: 41600 events read in total (2827ms).
[13:20:05.255] INFO: Test took 4035ms.
[13:20:05.301] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:05.002] INFO: Expecting 41600 events.
[13:20:09.361] INFO: 41600 events read in total (2832ms).
[13:20:09.362] INFO: Test took 4040ms.
[13:20:09.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:10.112] INFO: Expecting 41600 events.
[13:20:13.475] INFO: 41600 events read in total (2836ms).
[13:20:13.477] INFO: Test took 4046ms.
[13:20:13.523] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:14.223] INFO: Expecting 41600 events.
[13:20:17.587] INFO: 41600 events read in total (2837ms).
[13:20:17.589] INFO: Test took 4043ms.
[13:20:17.634] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:18.333] INFO: Expecting 41600 events.
[13:20:21.692] INFO: 41600 events read in total (2832ms).
[13:20:21.693] INFO: Test took 4037ms.
[13:20:21.740] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:22.440] INFO: Expecting 41600 events.
[13:20:25.789] INFO: 41600 events read in total (2823ms).
[13:20:25.790] INFO: Test took 4028ms.
[13:20:25.836] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:26.538] INFO: Expecting 41600 events.
[13:20:29.867] INFO: 41600 events read in total (2802ms).
[13:20:29.868] INFO: Test took 4009ms.
[13:20:29.913] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:30.618] INFO: Expecting 41600 events.
[13:20:33.749] INFO: 41600 events read in total (2604ms).
[13:20:33.750] INFO: Test took 3813ms.
[13:20:33.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:34.140] INFO: enter test to run
[13:21:30.780] INFO: test: HighRate no parameter change
[13:21:30.780] INFO: running: highrate
[13:21:30.781] INFO: ----------------------------------------------------------------------
[13:21:30.781] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:21:30.781] INFO: ----------------------------------------------------------------------
[13:21:31.398] INFO: Expecting 208000 events.
[13:21:44.009] INFO: 208000 events read in total (12085ms).
[13:21:44.013] INFO: Test took 13222ms.
[13:21:44.220] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:44.493] INFO: number of dead pixels (per ROC): 0 0 1 3 1 0 0 0 0 0 0 0 0 0 0 0
[13:21:44.493] INFO: number of red-efficiency pixels: 109 74 177 216 269 230 256 195 142 168 186 146 163 66 36 44
[13:21:44.493] INFO: number of X-ray hits detected: 87080 60628 96709 155947 174484 173692 171244 124071 110277 141759 138038 122001 122286 69998 29266 34324
[13:21:44.493] 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:21:44.493] INFO: number of Vcal hits detected: 207886 207924 207768 207633 207671 207756 207733 207800 207854 207820 207805 207853 207833 207933 207964 207956
[13:21:44.493] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[13:21:44.493] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[13:21:44.493] INFO: X-ray hit rate [MHz/cm2]: 25.5 17.8 28.3 45.7 51.1 50.9 50.2 36.4 32.3 41.6 40.5 35.8 35.8 20.5 8.6 10.1
[13:21:44.493] INFO: PixTestHighRate::doXPixelAlive() done
[13:21:44.538] INFO: PixTest:: pg_setup set to default.
[13:21:44.551] INFO: enter test to run
[13:25:53.954] INFO: test: HighRate no parameter change
[13:25:53.954] INFO: running: highrate
[13:25:53.955] INFO: ----------------------------------------------------------------------
[13:25:53.955] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:25:53.955] INFO: ----------------------------------------------------------------------
[13:25:54.571] INFO: Expecting 208000 events.
[13:26:09.693] INFO: 208000 events read in total (14595ms).
[13:26:09.699] INFO: Test took 15734ms.
[13:26:10.103] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:10.444] INFO: number of dead pixels (per ROC): 0 0 0 3 1 0 0 0 0 0 0 0 0 0 0 0
[13:26:10.444] INFO: number of red-efficiency pixels: 292 234 594 776 1006 971 946 717 488 638 689 539 543 241 78 71
[13:26:10.444] INFO: number of X-ray hits detected: 176858 124650 196494 317317 354693 351232 347394 255502 224706 288523 281676 247869 249901 142443 60274 69451
[13:26:10.444] 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:26:10.444] INFO: number of Vcal hits detected: 207670 207756 207178 206956 206711 206816 206874 207149 207458 207297 207229 207404 207402 207740 207921 207924
[13:26:10.444] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.7 99.6 99.5 99.5 99.5 99.6 99.8 99.7 99.7 99.7 99.7 99.9 100.0 100.0
[13:26:10.444] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.5 99.4 99.4 99.5 99.6 99.7 99.7 99.6 99.7 99.7 99.9 100.0 100.0
[13:26:10.444] INFO: X-ray hit rate [MHz/cm2]: 51.8 36.5 57.6 93.0 104.0 102.9 101.8 74.9 65.9 84.6 82.6 72.7 73.2 41.8 17.7 20.4
[13:26:10.444] INFO: PixTestHighRate::doXPixelAlive() done
[13:26:10.489] INFO: PixTest:: pg_setup set to default.
[13:26:10.505] INFO: enter test to run
[13:26:57.202] INFO: test: HighRate no parameter change
[13:26:57.202] INFO: running: highrate
[13:26:57.203] INFO: ----------------------------------------------------------------------
[13:26:57.204] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:26:57.204] INFO: ----------------------------------------------------------------------
[13:26:57.824] INFO: Expecting 208000 events.
[13:27:15.984] INFO: 208000 events read in total (17633ms).
[13:27:15.994] INFO: Test took 18780ms.
[13:27:16.615] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:17.026] INFO: number of dead pixels (per ROC): 0 0 1 3 1 0 0 0 0 0 0 0 0 0 0 0
[13:27:17.026] INFO: number of red-efficiency pixels: 643 554 1262 1773 2354 2262 2107 1588 1114 1524 1603 1325 1287 599 155 128
[13:27:17.026] INFO: number of X-ray hits detected: 267302 186967 296576 474952 533538 531009 524339 382957 339020 436188 423293 373972 375375 214138 91543 105685
[13:27:17.026] 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:27:17.026] INFO: number of Vcal hits detected: 207146 207343 205592 205143 203864 204010 204424 205458 206478 205853 205718 206126 206289 207234 207835 207868
[13:27:17.026] INFO: Vcal hit fiducial efficiency (%): 99.6 99.7 99.0 98.8 98.2 98.3 98.5 98.9 99.4 99.1 99.0 99.2 99.3 99.7 99.9 99.9
[13:27:17.026] INFO: Vcal hit overall efficiency (%): 99.6 99.7 98.8 98.6 98.0 98.1 98.3 98.8 99.3 99.0 98.9 99.1 99.2 99.6 99.9 99.9
[13:27:17.026] INFO: X-ray hit rate [MHz/cm2]: 78.3 54.8 86.9 139.2 156.4 155.6 153.7 112.2 99.4 127.8 124.1 109.6 110.0 62.8 26.8 31.0
[13:27:17.026] INFO: PixTestHighRate::doXPixelAlive() done
[13:27:17.073] INFO: PixTest:: pg_setup set to default.
[13:27:17.086] INFO: enter test to run
[13:28:02.249] INFO: test: exit no parameter change
[13:28:02.606] QUIET: Connection to board 33 closed.
[13:28:02.607] INFO: pXar: this is the end, my friend