[11:02:05.914] INFO: *** Welcome to pxar ***
[11:02:05.914] INFO: *** Today: 2016/03/25
[11:02:05.917] INFO: *** Version: prod-12-dirty
[11:02:05.917] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//dacParameters35_C15.dat
[11:02:05.918] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:02:05.919] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//defaultMaskFile.dat
[11:02:05.919] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C15.dat
[11:02:05.991] INFO: clk: 4
[11:02:05.991] INFO: ctr: 4
[11:02:05.991] INFO: sda: 19
[11:02:05.991] INFO: tin: 9
[11:02:05.991] INFO: level: 15
[11:02:05.991] INFO: triggerdelay: 0
[11:02:05.991] QUIET: Instanciating API for pxar prod-12
[11:02:05.991] INFO: Log level: INFO
[11:02:06.009] QUIET: Connection to board DTB_WREKRL opened.
[11:02:06.013] 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:
------------------------------------------------------
[11:02:06.016] INFO: RPC call hashes of host and DTB match: 398089610
[11:02:07.547] INFO: DUT info:
[11:02:07.547] INFO: The DUT currently contains the following objects:
[11:02:07.547] INFO: 2 TBM Cores tbm08c (2 ON)
[11:02:07.547] INFO: TBM Core alpha (0): 7 registers set
[11:02:07.547] INFO: TBM Core beta (1): 7 registers set
[11:02:07.547] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:02:07.547] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.547] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.547] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.548] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.548] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.548] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.548] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.548] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.548] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.548] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.548] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.548] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.548] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.548] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.548] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.548] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:07.951] INFO: enter 'restricted' command line mode
[11:02:07.951] INFO: enter test to run
[11:02:45.513] INFO: test: PixelAlive no parameter change
[11:02:45.513] INFO: running: pixelalive
[11:02:45.523] INFO: ----------------------------------------------------------------------
[11:02:45.523] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:02:45.523] INFO: ----------------------------------------------------------------------
[11:02:45.837] INFO: Expecting 41600 events.
[11:02:50.173] INFO: 41600 events read in total (3618ms).
[11:02:50.341] INFO: Test took 4816ms.
[11:02:50.354] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:50.615] INFO: PixTestAlive::aliveTest() done
[11:02:50.615] INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 0 0 3 0 0 0 1 1 0 0
[11:02:50.649] INFO: enter test to run
[11:03:34.784] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=150;maskuntrimmable=0<-
[11:03:34.784] INFO: running: highrate
[11:03:34.785] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:03:34.935] INFO: ----------------------------------------------------------------------
[11:03:34.935] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:03:34.935] INFO: ----------------------------------------------------------------------
[11:03:34.935] INFO: THR = 150, corresponding to ~ 80 MHz/cm2
[11:03:34.935] INFO: edge/corner pixel THR is adjusted
[11:03:34.935] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:03:35.894] INFO: Collecting data for 5 seconds...
[11:03:40.910] INFO: Done with hot pixel readout
[11:03:51.789] INFO: PixTest:: pg_setup set to default.
[11:03:51.790] INFO: 0 hot pixels found in step 0
[11:03:51.834] INFO: PixTest::trimHotPixels() done
[11:03:51.868] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C0.dat
[11:03:51.875] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C1.dat
[11:03:51.882] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C2.dat
[11:03:51.889] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C3.dat
[11:03:51.896] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C4.dat
[11:03:51.903] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C5.dat
[11:03:51.910] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C6.dat
[11:03:51.917] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C7.dat
[11:03:51.924] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C8.dat
[11:03:51.931] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C9.dat
[11:03:51.938] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C10.dat
[11:03:51.945] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C11.dat
[11:03:51.953] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C12.dat
[11:03:51.960] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C13.dat
[11:03:51.966] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C14.dat
[11:03:51.974] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C15.dat
[11:03:51.991] INFO: enter test to run
[11:04:27.352] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=150;maskuntrimmable=0<-
[11:04:27.352] INFO: running: highrate
[11:04:27.357] INFO: ----------------------------------------------------------------------
[11:04:27.357] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:04:27.357] INFO: ----------------------------------------------------------------------
[11:04:27.357] INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[11:04:27.357] INFO: edge/corner pixel THR is adjusted
[11:04:27.357] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:04:28.315] INFO: Collecting data for 1 seconds...
[11:04:29.319] INFO: Done with hot pixel readout
[11:04:32.837] INFO: PixTest:: pg_setup set to default.
[11:04:32.838] INFO: 0 hot pixels found in step 0
[11:04:32.950] INFO: PixTest::trimHotPixels() done
[11:04:32.950] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C0.dat
[11:04:32.966] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C1.dat
[11:04:32.973] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C2.dat
[11:04:32.980] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C3.dat
[11:04:32.987] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C4.dat
[11:04:32.994] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C5.dat
[11:04:32.001] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C6.dat
[11:04:33.008] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C7.dat
[11:04:33.015] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C8.dat
[11:04:33.022] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C9.dat
[11:04:33.029] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C10.dat
[11:04:33.037] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C11.dat
[11:04:33.044] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C12.dat
[11:04:33.051] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C13.dat
[11:04:33.058] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C14.dat
[11:04:33.065] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//trimParameters35_C15.dat
[11:04:33.081] INFO: enter test to run
[11:05:07.632] INFO: test: Xray setting parameters: ->savemaskfile=1<-
[11:05:07.632] INFO: running: xray
[11:05:07.637] INFO: ----------------------------------------------------------------------
[11:05:07.637] INFO: PixTest::maskHotPixels() running for 10 seconds with 100 kHz trigger rate
[11:05:07.637] INFO: ----------------------------------------------------------------------
[11:05:08.594] INFO: PixTestHighRate::maskHotPixels start TriggerLoop with period 404 and duration 10 seconds and trigger rate 100 kHz
[11:05:17.637] INFO: Done with hot pixel readout
[11:05:31.889] INFO: PixTest:: pg_setup set to default.
[11:05:31.890] INFO: no hot pixel found!
[11:05:31.890] INFO: PixTest::maskHotPixels() done
[11:05:31.892] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-G-2-45_FPIXTest-17C-FNAL-160129-0953_2016-01-29_09h53m_1454082824/000_FPIXTest_p17//defaultMaskFile.dat
[11:05:32.070] INFO: enter test to run
[11:06:12.623] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:06:12.623] INFO: running: xray
[11:06:12.624] INFO: ----------------------------------------------------------------------
[11:06:12.624] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:06:12.625] INFO: ----------------------------------------------------------------------
[11:06:13.588] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:06:25.794] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[11:06:55.299] INFO: Resuming triggers.
[11:07:07.506] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[11:07:37.141] INFO: Resuming triggers.
[11:07:49.342] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[11:08:18.843] INFO: Resuming triggers.
[11:08:31.044] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[11:09:00.515] INFO: Resuming triggers.
[11:09:12.717] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[11:09:42.313] INFO: Resuming triggers.
[11:09:54.515] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[11:10:23.328] INFO: Resuming triggers.
[11:10:35.528] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[11:11:05.111] INFO: Resuming triggers.
[11:11:17.314] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[11:11:46.733] INFO: Resuming triggers.
[11:11:49.432] INFO: data taking finished, elapsed time: 100 seconds.
[11:11:56.249] INFO: PixTest:: pg_setup set to default.
[11:11:56.252] INFO: PixTestXray::doPhRun() done
[11:11:56.390] INFO: enter test to run
[11:12:26.949] INFO: test: HighRate no parameter change
[11:12:26.949] INFO: running: highrate
[11:12:26.950] INFO: ----------------------------------------------------------------------
[11:12:26.950] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:12:26.950] INFO: ----------------------------------------------------------------------
[11:12:27.091] INFO: Expecting 768 events.
[11:12:28.225] INFO: 768 events read in total (418ms).
[11:12:28.226] INFO: Test took 1268ms.
[11:12:29.029] INFO: Expecting 41600 events.
[11:12:32.093] INFO: 41600 events read in total (2537ms).
[11:12:32.094] INFO: Test took 3859ms.
[11:12:32.123] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:32.857] INFO: Expecting 41600 events.
[11:12:36.022] INFO: 41600 events read in total (2638ms).
[11:12:36.023] INFO: Test took 3884ms.
[11:12:36.052] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:36.788] INFO: Expecting 41600 events.
[11:12:39.971] INFO: 41600 events read in total (2656ms).
[11:12:39.971] INFO: Test took 3902ms.
[11:12:39.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:40.736] INFO: Expecting 41600 events.
[11:12:43.917] INFO: 41600 events read in total (2654ms).
[11:12:43.918] INFO: Test took 3901ms.
[11:12:43.947] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:44.681] INFO: Expecting 41600 events.
[11:12:47.864] INFO: 41600 events read in total (2657ms).
[11:12:47.865] INFO: Test took 3901ms.
[11:12:47.895] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:48.629] INFO: Expecting 41600 events.
[11:12:51.827] INFO: 41600 events read in total (2671ms).
[11:12:51.828] INFO: Test took 3916ms.
[11:12:51.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:52.593] INFO: Expecting 41600 events.
[11:12:55.795] INFO: 41600 events read in total (2675ms).
[11:12:55.796] INFO: Test took 3923ms.
[11:12:55.825] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:56.556] INFO: Expecting 41600 events.
[11:12:59.745] INFO: 41600 events read in total (2662ms).
[11:12:59.746] INFO: Test took 3906ms.
[11:12:59.776] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:00.510] INFO: Expecting 41600 events.
[11:13:03.702] INFO: 41600 events read in total (2665ms).
[11:13:03.703] INFO: Test took 3912ms.
[11:13:03.733] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:04.464] INFO: Expecting 41600 events.
[11:13:07.652] INFO: 41600 events read in total (2661ms).
[11:13:07.653] INFO: Test took 3903ms.
[11:13:07.682] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:08.416] INFO: Expecting 41600 events.
[11:13:11.600] INFO: 41600 events read in total (2657ms).
[11:13:11.601] INFO: Test took 3902ms.
[11:13:11.630] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:12.364] INFO: Expecting 41600 events.
[11:13:15.562] INFO: 41600 events read in total (2671ms).
[11:13:15.563] INFO: Test took 3918ms.
[11:13:15.591] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:16.327] INFO: Expecting 41600 events.
[11:13:19.518] INFO: 41600 events read in total (2664ms).
[11:13:19.519] INFO: Test took 3911ms.
[11:13:19.548] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:20.280] INFO: Expecting 41600 events.
[11:13:23.456] INFO: 41600 events read in total (2649ms).
[11:13:23.457] INFO: Test took 3893ms.
[11:13:23.487] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:24.220] INFO: Expecting 41600 events.
[11:13:27.414] INFO: 41600 events read in total (2668ms).
[11:13:27.415] INFO: Test took 3911ms.
[11:13:27.444] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:28.176] INFO: Expecting 41600 events.
[11:13:31.367] INFO: 41600 events read in total (2664ms).
[11:13:31.367] INFO: Test took 3906ms.
[11:13:31.397] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:32.131] INFO: Expecting 41600 events.
[11:13:35.321] INFO: 41600 events read in total (2663ms).
[11:13:35.322] INFO: Test took 3908ms.
[11:13:35.351] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:36.082] INFO: Expecting 41600 events.
[11:13:39.281] INFO: 41600 events read in total (2672ms).
[11:13:39.282] INFO: Test took 3915ms.
[11:13:39.311] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:40.041] INFO: Expecting 41600 events.
[11:13:43.206] INFO: 41600 events read in total (2638ms).
[11:13:43.207] INFO: Test took 3878ms.
[11:13:43.236] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:43.970] INFO: Expecting 41600 events.
[11:13:46.985] INFO: 41600 events read in total (2488ms).
[11:13:46.986] INFO: Test took 3733ms.
[11:13:47.014] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:47.388] INFO: enter test to run
[11:14:18.716] INFO: test: HighRate no parameter change
[11:14:18.716] INFO: running: highrate
[11:14:18.717] INFO: ----------------------------------------------------------------------
[11:14:18.717] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:14:18.717] INFO: ----------------------------------------------------------------------
[11:14:19.336] INFO: Expecting 208000 events.
[11:14:30.894] INFO: 208000 events read in total (11031ms).
[11:14:30.897] INFO: Test took 12171ms.
[11:14:31.015] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:31.258] INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 0 0 3 0 0 0 1 1 0 0
[11:14:31.258] INFO: number of red-efficiency pixels: 68 46 61 105 142 116 125 85 84 98 110 92 94 65 30 31
[11:14:31.258] INFO: number of X-ray hits detected: 54855 36056 55287 86607 94348 95367 97215 66701 60914 77332 78447 69605 68261 42582 17921 20253
[11:14:31.258] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:14:31.258] INFO: number of Vcal hits detected: 207931 207953 207890 207842 207855 207881 207872 207913 207766 207902 207890 207907 207852 207885 207968 207969
[11:14:31.259] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[11:14:31.259] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 99.9 100.0 99.9 99.9 100.0 100.0
[11:14:31.259] INFO: X-ray hit rate [MHz/cm2]: 16.1 10.6 16.2 25.4 27.7 28.0 28.5 19.6 17.9 22.7 23.0 20.4 20.0 12.5 5.3 5.9
[11:14:31.259] INFO: PixTestHighRate::doXPixelAlive() done
[11:14:31.305] INFO: PixTest:: pg_setup set to default.
[11:14:31.315] INFO: enter test to run
[11:15:11.804] INFO: test: HighRate no parameter change
[11:15:11.804] INFO: running: highrate
[11:15:11.805] INFO: ----------------------------------------------------------------------
[11:15:11.805] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:15:11.805] INFO: ----------------------------------------------------------------------
[11:15:12.424] INFO: Expecting 208000 events.
[11:15:25.917] INFO: 208000 events read in total (12966ms).
[11:15:25.922] INFO: Test took 14109ms.
[11:15:26.196] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:26.493] INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 0 0 3 0 0 0 1 1 0 0
[11:15:26.493] INFO: number of red-efficiency pixels: 261 143 263 409 541 435 478 374 278 293 386 274 350 194 59 69
[11:15:26.493] INFO: number of X-ray hits detected: 134336 88164 135142 210849 227215 233034 235190 163859 148545 187460 191246 168153 165480 102430 43972 50024
[11:15:26.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
[11:15:26.493] INFO: number of Vcal hits detected: 207724 207849 207673 207510 207413 207529 207488 207596 207567 207687 207584 207711 207573 207744 207939 207931
[11:15:26.493] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.8 99.9 99.9 99.8 99.9 99.8 99.9 100.0 100.0
[11:15:26.493] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[11:15:26.493] INFO: X-ray hit rate [MHz/cm2]: 39.4 25.8 39.6 61.8 66.6 68.3 68.9 48.0 43.5 54.9 56.1 49.3 48.5 30.0 12.9 14.7
[11:15:26.493] INFO: PixTestHighRate::doXPixelAlive() done
[11:15:26.548] INFO: PixTest:: pg_setup set to default.
[11:15:26.562] INFO: enter test to run
[11:15:51.851] INFO: test: HighRate no parameter change
[11:15:51.851] INFO: running: highrate
[11:15:51.853] INFO: ----------------------------------------------------------------------
[11:15:51.853] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:15:51.853] INFO: ----------------------------------------------------------------------
[11:15:52.469] INFO: Expecting 208000 events.
[11:16:07.192] INFO: 208000 events read in total (14196ms).
[11:16:07.198] INFO: Test took 15336ms.
[11:16:07.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:07.900] INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 0 0 3 0 0 0 1 1 0 0
[11:16:07.900] INFO: number of red-efficiency pixels: 389 240 440 700 969 717 835 625 450 539 720 515 594 340 86 113
[11:16:07.900] INFO: number of X-ray hits detected: 180527 118758 182089 284408 307138 313999 317365 223019 201940 254497 257997 227921 224457 138968 59161 68034
[11:16:07.900] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:16:07.900] INFO: number of Vcal hits detected: 207565 207742 207440 207160 206826 207170 207033 207276 207373 207406 207198 207437 207292 207580 207910 207885
[11:16:07.900] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.5 99.6 99.6 99.7 99.8 99.7 99.6 99.8 99.7 99.8 100.0 99.9
[11:16:07.900] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.4 99.6 99.5 99.7 99.7 99.7 99.6 99.7 99.7 99.8 100.0 99.9
[11:16:07.900] INFO: X-ray hit rate [MHz/cm2]: 52.9 34.8 53.4 83.4 90.0 92.0 93.0 65.4 59.2 74.6 75.6 66.8 65.8 40.7 17.3 19.9
[11:16:07.900] INFO: PixTestHighRate::doXPixelAlive() done
[11:16:07.949] INFO: PixTest:: pg_setup set to default.
[11:16:07.964] INFO: enter test to run
[11:16:32.579] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:16:32.579] INFO: running: xray
[11:16:32.580] INFO: ----------------------------------------------------------------------
[11:16:32.580] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:16:32.580] INFO: ----------------------------------------------------------------------
[11:16:33.544] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:16:40.873] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[11:17:11.220] INFO: Resuming triggers.
[11:17:18.543] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[11:17:48.937] INFO: Resuming triggers.
[11:17:56.263] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[11:18:26.584] INFO: Resuming triggers.
[11:18:33.917] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[11:19:04.197] INFO: Resuming triggers.
[11:19:11.528] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[11:19:41.838] INFO: Resuming triggers.
[11:19:49.165] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[11:20:16.802] INFO: Resuming triggers.
[11:20:24.130] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[11:20:54.419] INFO: Resuming triggers.
[11:21:01.745] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[11:21:32.070] INFO: Resuming triggers.
[11:21:39.395] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[11:22:09.768] INFO: Resuming triggers.
[11:22:17.094] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[11:22:47.471] INFO: Resuming triggers.
[11:22:54.794] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[11:23:25.123] INFO: Resuming triggers.
[11:23:32.446] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[11:24:02.812] INFO: Resuming triggers.
[11:24:10.139] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[11:24:40.597] INFO: Resuming triggers.
[11:24:45.674] INFO: data taking finished, elapsed time: 100 seconds.
[11:25:06.977] INFO: PixTest:: pg_setup set to default.
[11:25:06.980] INFO: PixTestXray::doPhRun() done
[11:25:07.129] INFO: enter test to run
[11:25:23.327] INFO: test: exit no parameter change
[11:25:23.668] QUIET: Connection to board 33 closed.
[11:25:23.669] INFO: pXar: this is the end, my friend