[09:34:00.676] INFO: *** Welcome to pxar ***
[09:34:00.676] INFO: *** Today: 2016/10/25
[09:34:01.746] INFO: *** Version: v1.9.0-818-g96727
[09:34:01.747] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//dacParameters35_C15.dat
[09:34:01.825] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//tbmParameters_C0b.dat
[09:34:01.825] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//defaultMaskFile.dat
[09:34:01.830] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C15.dat
[09:34:01.973] INFO: clk: 4
[09:34:01.973] INFO: ctr: 4
[09:34:01.973] INFO: sda: 19
[09:34:01.974] INFO: tin: 9
[09:34:01.974] INFO: level: 15
[09:34:01.974] INFO: triggerdelay: 0
[09:34:01.974] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[09:34:01.974] INFO: Log level: INFO
[09:34:01.991] QUIET: Connection to board DTB_WREHUL opened.
[09:34:01.994] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 30
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREHUL
MAC address: 40D85511801E
Hostname: pixelDTB030
Comment:
------------------------------------------------------
[09:34:01.997] INFO: RPC call hashes of host and DTB match: 398089610
[09:34:03.545] INFO: DUT info:
[09:34:03.545] INFO: The DUT currently contains the following objects:
[09:34:03.545] INFO: 2 TBM Cores tbm08c (2 ON)
[09:34:03.545] INFO: TBM Core alpha (0): 7 registers set
[09:34:03.546] INFO: TBM Core beta (1): 7 registers set
[09:34:03.546] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:34:03.546] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.546] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:03.002] INFO: enter 'restricted' command line mode
[09:34:03.003] INFO: enter test to run
[09:34:16.960] INFO: test: PixelAlive no parameter change
[09:34:16.961] INFO: running: pixelalive
[09:34:17.022] INFO: ----------------------------------------------------------------------
[09:34:17.022] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:34:17.022] INFO: ----------------------------------------------------------------------
[09:34:17.344] INFO: Expecting 41600 events.
[09:34:21.713] INFO: 41600 events read in total (3651ms).
[09:34:21.883] INFO: Test took 4857ms.
[09:34:21.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:22.427] INFO: PixTestAlive::aliveTest() done
[09:34:22.427] INFO: number of dead pixels (per ROC): 1 2 0 1 0 0 0 0 0 0 3 1 1 4 2 1
[09:34:22.596] INFO: enter test to run
[09:34:50.984] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[09:34:50.984] INFO: running: highrate
[09:34:50.002] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[09:34:51.244] INFO: ----------------------------------------------------------------------
[09:34:51.244] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[09:34:51.244] INFO: ----------------------------------------------------------------------
[09:34:51.244] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[09:34:51.244] INFO: edge/corner pixel THR is adjusted
[09:34:51.244] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:34:52.204] INFO: Collecting data for 5 seconds...
[09:34:57.221] INFO: Done with hot pixel readout
[09:35:08.966] INFO: PixTest:: pg_setup set to default.
[09:35:08.967] INFO: 7 hot pixels found in step 0
[09:35:09.957] INFO: Collecting data for 5 seconds...
[09:35:14.975] INFO: Done with hot pixel readout
[09:35:26.700] INFO: PixTest:: pg_setup set to default.
[09:35:26.701] INFO: 8 hot pixels found in step 1
[09:35:27.698] INFO: Collecting data for 5 seconds...
[09:35:32.715] INFO: Done with hot pixel readout
[09:35:44.337] INFO: PixTest:: pg_setup set to default.
[09:35:44.338] INFO: 2 hot pixels found in step 2
[09:35:45.332] INFO: Collecting data for 5 seconds...
[09:35:50.349] INFO: Done with hot pixel readout
[09:36:01.967] INFO: PixTest:: pg_setup set to default.
[09:36:01.968] INFO: 2 hot pixels found in step 3
[09:36:02.962] INFO: Collecting data for 5 seconds...
[09:36:07.979] INFO: Done with hot pixel readout
[09:36:19.677] INFO: PixTest:: pg_setup set to default.
[09:36:19.678] INFO: 4 hot pixels found in step 4
[09:36:20.670] INFO: Collecting data for 5 seconds...
[09:36:25.687] INFO: Done with hot pixel readout
[09:36:37.226] INFO: PixTest:: pg_setup set to default.
[09:36:37.227] INFO: 4 hot pixels found in step 5
[09:36:38.222] INFO: Collecting data for 5 seconds...
[09:36:43.238] INFO: Done with hot pixel readout
[09:36:54.899] INFO: PixTest:: pg_setup set to default.
[09:36:54.900] INFO: 8 hot pixels found in step 6
[09:36:55.895] INFO: Collecting data for 5 seconds...
[09:37:00.911] INFO: Done with hot pixel readout
[09:37:12.593] INFO: PixTest:: pg_setup set to default.
[09:37:12.594] INFO: 3 hot pixels found in step 7
[09:37:13.587] INFO: Collecting data for 5 seconds...
[09:37:18.604] INFO: Done with hot pixel readout
[09:37:30.363] INFO: PixTest:: pg_setup set to default.
[09:37:30.364] INFO: 5 hot pixels found in step 8
[09:37:31.360] INFO: Collecting data for 5 seconds...
[09:37:36.378] INFO: Done with hot pixel readout
[09:37:47.936] INFO: PixTest:: pg_setup set to default.
[09:37:47.937] INFO: 1 hot pixels found in step 9
[09:37:48.931] INFO: Collecting data for 5 seconds...
[09:37:53.948] INFO: Done with hot pixel readout
[09:38:05.710] INFO: PixTest:: pg_setup set to default.
[09:38:05.711] INFO: 3 hot pixels found in step 10
[09:38:06.705] INFO: Collecting data for 5 seconds...
[09:38:11.722] INFO: Done with hot pixel readout
[09:38:23.494] INFO: PixTest:: pg_setup set to default.
[09:38:23.495] INFO: 3 hot pixels found in step 11
[09:38:24.489] INFO: Collecting data for 5 seconds...
[09:38:29.505] INFO: Done with hot pixel readout
[09:38:41.210] INFO: PixTest:: pg_setup set to default.
[09:38:41.211] INFO: 4 hot pixels found in step 12
[09:38:42.206] INFO: Collecting data for 5 seconds...
[09:38:47.223] INFO: Done with hot pixel readout
[09:38:59.013] INFO: PixTest:: pg_setup set to default.
[09:38:59.014] INFO: 2 hot pixels found in step 13
[09:39:00.008] INFO: Collecting data for 5 seconds...
[09:39:05.025] INFO: Done with hot pixel readout
[09:39:16.887] INFO: PixTest:: pg_setup set to default.
[09:39:16.888] INFO: 4 hot pixels found in step 14
[09:39:16.926] INFO: 4 hot pixels could not be trimmed and have been masked.
[09:39:16.947] INFO: PixTest::trimHotPixels() done
[09:39:16.965] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C0.dat
[09:39:16.971] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C1.dat
[09:39:16.977] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C2.dat
[09:39:16.983] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C3.dat
[09:39:16.988] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C4.dat
[09:39:16.994] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C5.dat
[09:39:16.999] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C6.dat
[09:39:16.004] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C7.dat
[09:39:17.010] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C8.dat
[09:39:17.015] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C9.dat
[09:39:17.021] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C10.dat
[09:39:17.026] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C11.dat
[09:39:17.032] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C12.dat
[09:39:17.037] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C13.dat
[09:39:17.043] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C14.dat
[09:39:17.048] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C15.dat
[09:39:17.053] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//defaultMaskFile.dat
[09:39:17.065] INFO: enter test to run
[09:39:44.094] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[09:39:44.094] INFO: running: highrate
[09:39:44.099] INFO: ----------------------------------------------------------------------
[09:39:44.099] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[09:39:44.099] INFO: ----------------------------------------------------------------------
[09:39:44.099] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[09:39:44.099] INFO: edge/corner pixel THR is adjusted
[09:39:44.099] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:39:45.055] INFO: Collecting data for 1 seconds...
[09:39:46.059] INFO: Done with hot pixel readout
[09:39:50.021] INFO: PixTest:: pg_setup set to default.
[09:39:50.022] INFO: 0 hot pixels found in step 0
[09:39:50.027] INFO: 0 hot pixels could not be trimmed and have been masked.
[09:39:50.113] INFO: PixTest::trimHotPixels() done
[09:39:50.113] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C0.dat
[09:39:50.122] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C1.dat
[09:39:50.129] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C2.dat
[09:39:50.134] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C3.dat
[09:39:50.140] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C4.dat
[09:39:50.145] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C5.dat
[09:39:50.150] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C6.dat
[09:39:50.156] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C7.dat
[09:39:50.161] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C8.dat
[09:39:50.166] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C9.dat
[09:39:50.172] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C10.dat
[09:39:50.177] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C11.dat
[09:39:50.183] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C12.dat
[09:39:50.188] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C13.dat
[09:39:50.193] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C14.dat
[09:39:50.199] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C15.dat
[09:39:50.204] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-A-10_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//defaultMaskFile.dat
[09:39:50.213] INFO: enter test to run
[09:40:11.526] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[09:40:11.526] INFO: running: xray
[09:40:11.527] INFO: ----------------------------------------------------------------------
[09:40:11.527] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:40:11.527] INFO: ----------------------------------------------------------------------
[09:40:12.515] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:40:24.287] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[09:40:52.372] INFO: Resuming triggers.
[09:41:04.145] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[09:41:33.894] INFO: Resuming triggers.
[09:41:45.670] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[09:42:15.290] INFO: Resuming triggers.
[09:42:27.065] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[09:42:57.643] INFO: Resuming triggers.
[09:43:09.420] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[09:43:39.476] INFO: Resuming triggers.
[09:43:51.255] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[09:44:21.193] INFO: Resuming triggers.
[09:44:32.970] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[09:45:02.422] INFO: Resuming triggers.
[09:45:14.200] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[09:45:43.708] INFO: Resuming triggers.
[09:45:49.869] INFO: data taking finished, elapsed time: 100 seconds.
[09:46:05.311] INFO: PixTest:: pg_setup set to default.
[09:46:05.315] INFO: PixTestXray::doPhRun() done
[09:46:05.497] INFO: enter test to run
[09:46:45.411] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[09:46:45.412] INFO: running: xray
[09:46:45.413] INFO: ----------------------------------------------------------------------
[09:46:45.413] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:46:45.413] INFO: ----------------------------------------------------------------------
[09:46:46.376] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:46:52.880] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[09:47:23.053] INFO: Resuming triggers.
[09:47:29.559] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[09:47:59.757] INFO: Resuming triggers.
[09:48:06.260] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[09:48:36.356] INFO: Resuming triggers.
[09:48:42.859] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[09:49:13.119] INFO: Resuming triggers.
[09:49:19.621] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[09:49:49.928] INFO: Resuming triggers.
[09:49:56.429] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[09:50:26.514] INFO: Resuming triggers.
[09:50:33.019] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[09:51:03.049] INFO: Resuming triggers.
[09:51:09.549] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[09:51:39.630] INFO: Resuming triggers.
[09:51:46.129] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[09:52:16.290] INFO: Resuming triggers.
[09:52:22.788] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[09:52:53.893] INFO: Resuming triggers.
[09:53:00.389] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[09:53:31.422] INFO: Resuming triggers.
[09:53:37.921] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[09:54:08.288] INFO: Resuming triggers.
[09:54:14.791] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[09:54:45.631] INFO: Resuming triggers.
[09:54:52.131] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[09:55:23.038] INFO: Resuming triggers.
[09:55:29.535] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[09:56:01.377] INFO: Resuming triggers.
[09:56:04.214] INFO: data taking finished, elapsed time: 100 seconds.
[09:56:18.324] INFO: PixTest:: pg_setup set to default.
[09:56:18.327] INFO: PixTestXray::doPhRun() done
[09:56:18.480] INFO: enter test to run
[09:56:35.047] INFO: test: HighRate no parameter change
[09:56:35.047] INFO: running: highrate
[09:56:35.066] INFO: ----------------------------------------------------------------------
[09:56:35.066] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:56:35.066] INFO: ----------------------------------------------------------------------
[09:56:35.240] INFO: Expecting 768 events.
[09:56:36.393] INFO: 768 events read in total (437ms).
[09:56:36.393] INFO: Test took 1307ms.
[09:56:37.196] INFO: Expecting 41600 events.
[09:56:40.306] INFO: 41600 events read in total (2583ms).
[09:56:40.307] INFO: Test took 3817ms.
[09:56:40.343] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:41.066] INFO: Expecting 41600 events.
[09:56:44.281] INFO: 41600 events read in total (2688ms).
[09:56:44.282] INFO: Test took 3922ms.
[09:56:44.318] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:45.038] INFO: Expecting 41600 events.
[09:56:48.477] INFO: 41600 events read in total (2912ms).
[09:56:48.478] INFO: Test took 4141ms.
[09:56:48.514] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:49.223] INFO: Expecting 41600 events.
[09:56:52.539] INFO: 41600 events read in total (2789ms).
[09:56:52.540] INFO: Test took 4008ms.
[09:56:52.576] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:53.297] INFO: Expecting 41600 events.
[09:56:56.630] INFO: 41600 events read in total (2806ms).
[09:56:56.631] INFO: Test took 4037ms.
[09:56:56.676] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:57.370] INFO: Expecting 41600 events.
[09:57:00.635] INFO: 41600 events read in total (2738ms).
[09:57:00.636] INFO: Test took 3935ms.
[09:57:00.673] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:01.390] INFO: Expecting 41600 events.
[09:57:04.731] INFO: 41600 events read in total (2814ms).
[09:57:04.732] INFO: Test took 4040ms.
[09:57:04.768] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:05.490] INFO: Expecting 41600 events.
[09:57:08.882] INFO: 41600 events read in total (2866ms).
[09:57:08.883] INFO: Test took 4096ms.
[09:57:08.920] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:09.639] INFO: Expecting 41600 events.
[09:57:13.035] INFO: 41600 events read in total (2869ms).
[09:57:13.036] INFO: Test took 4098ms.
[09:57:13.072] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:13.792] INFO: Expecting 41600 events.
[09:57:17.126] INFO: 41600 events read in total (2808ms).
[09:57:17.127] INFO: Test took 4037ms.
[09:57:17.164] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:17.882] INFO: Expecting 41600 events.
[09:57:21.211] INFO: 41600 events read in total (2803ms).
[09:57:21.212] INFO: Test took 4030ms.
[09:57:21.247] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:21.965] INFO: Expecting 41600 events.
[09:57:25.222] INFO: 41600 events read in total (2730ms).
[09:57:25.223] INFO: Test took 3957ms.
[09:57:25.259] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:25.976] INFO: Expecting 41600 events.
[09:57:29.441] INFO: 41600 events read in total (2938ms).
[09:57:29.442] INFO: Test took 4164ms.
[09:57:29.477] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:30.195] INFO: Expecting 41600 events.
[09:57:33.471] INFO: 41600 events read in total (2749ms).
[09:57:33.472] INFO: Test took 3976ms.
[09:57:33.507] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:34.228] INFO: Expecting 41600 events.
[09:57:37.478] INFO: 41600 events read in total (2723ms).
[09:57:37.479] INFO: Test took 3953ms.
[09:57:37.514] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:38.239] INFO: Expecting 41600 events.
[09:57:41.498] INFO: 41600 events read in total (2732ms).
[09:57:41.499] INFO: Test took 3967ms.
[09:57:41.534] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:42.257] INFO: Expecting 41600 events.
[09:57:45.508] INFO: 41600 events read in total (2724ms).
[09:57:45.509] INFO: Test took 3955ms.
[09:57:45.545] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:46.269] INFO: Expecting 41600 events.
[09:57:49.516] INFO: 41600 events read in total (2720ms).
[09:57:49.517] INFO: Test took 3954ms.
[09:57:49.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:50.277] INFO: Expecting 41600 events.
[09:57:53.485] INFO: 41600 events read in total (2681ms).
[09:57:53.486] INFO: Test took 3915ms.
[09:57:53.521] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:54.247] INFO: Expecting 41600 events.
[09:57:57.334] INFO: 41600 events read in total (2560ms).
[09:57:57.335] INFO: Test took 3794ms.
[09:57:57.369] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:57.741] INFO: enter test to run
[09:58:44.351] INFO: test: HighRate no parameter change
[09:58:44.351] INFO: running: highrate
[09:58:44.352] INFO: ----------------------------------------------------------------------
[09:58:44.352] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:58:44.352] INFO: ----------------------------------------------------------------------
[09:58:44.973] INFO: Expecting 208000 events.
[09:58:56.822] INFO: 208000 events read in total (11322ms).
[09:58:56.825] INFO: Test took 12463ms.
[09:58:56.975] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:57.229] INFO: number of dead pixels (per ROC): 1 2 0 1 0 0 0 0 0 0 3 1 1 4 2 1
[09:58:57.229] INFO: number of red-efficiency pixels: 67 44 71 98 106 124 170 78 85 122 145 130 113 74 21 30
[09:58:57.229] INFO: number of X-ray hits detected: 65802 42526 64980 100047 105001 113806 117164 76848 80905 115864 112378 92479 93712 58847 21883 22156
[09:58:57.229] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:58:57.229] INFO: number of Vcal hits detected: 207883 207857 207928 207852 207893 207874 207826 207920 207912 207876 207701 207820 207837 207727 207881 207920
[09:58:57.229] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 99.9 99.9 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[09:58:57.229] INFO: Vcal hit overall efficiency (%): 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 100.0
[09:58:57.229] INFO: X-ray hit rate [MHz/cm2]: 19.3 12.5 19.0 29.3 30.8 33.4 34.3 22.5 23.7 34.0 32.9 27.1 27.5 17.2 6.4 6.5
[09:58:57.229] INFO: PixTestHighRate::doXPixelAlive() done
[09:58:57.279] INFO: PixTest:: pg_setup set to default.
[09:58:57.293] INFO: enter test to run
[09:59:37.502] INFO: test: HighRate no parameter change
[09:59:37.502] INFO: running: highrate
[09:59:37.503] INFO: ----------------------------------------------------------------------
[09:59:37.503] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:59:37.503] INFO: ----------------------------------------------------------------------
[09:59:38.120] INFO: Expecting 208000 events.
[09:59:51.808] INFO: 208000 events read in total (13161ms).
[09:59:51.813] INFO: Test took 14300ms.
[09:59:52.117] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:52.421] INFO: number of dead pixels (per ROC): 1 2 0 1 0 0 0 0 0 0 3 1 1 4 2 1
[09:59:52.421] INFO: number of red-efficiency pixels: 266 155 197 328 376 389 523 280 322 519 505 387 385 221 56 47
[09:59:52.421] INFO: number of X-ray hits detected: 136340 88448 134231 207771 217361 236077 241900 158350 168315 238234 230907 190960 194969 122359 45493 45887
[09:59:52.421] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:59:52.422] INFO: number of Vcal hits detected: 207672 207743 207793 207609 207602 207575 207421 207697 207658 207438 207309 207537 207539 207570 207845 207904
[09:59:52.422] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[09:59:52.422] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.9 99.8 99.8 99.8 99.7 99.9 99.8 99.7 99.7 99.8 99.8 99.8 99.9 100.0
[09:59:52.422] INFO: X-ray hit rate [MHz/cm2]: 40.0 25.9 39.3 60.9 63.7 69.2 70.9 46.4 49.3 69.8 67.7 56.0 57.1 35.9 13.3 13.4
[09:59:52.422] INFO: PixTestHighRate::doXPixelAlive() done
[09:59:52.467] INFO: PixTest:: pg_setup set to default.
[09:59:52.482] INFO: enter test to run
[10:00:10.382] INFO: test: HighRate no parameter change
[10:00:10.382] INFO: running: highrate
[10:00:10.383] INFO: ----------------------------------------------------------------------
[10:00:10.383] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:00:10.383] INFO: ----------------------------------------------------------------------
[10:00:10.000] INFO: Expecting 208000 events.
[10:00:26.737] INFO: 208000 events read in total (15211ms).
[10:00:26.745] INFO: Test took 16352ms.
[10:00:27.224] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:27.584] INFO: number of dead pixels (per ROC): 1 2 0 1 0 0 0 0 0 0 3 1 1 4 2 1
[10:00:27.584] INFO: number of red-efficiency pixels: 549 277 495 749 853 969 1343 638 692 1291 1271 914 971 538 97 64
[10:00:27.584] INFO: number of X-ray hits detected: 208925 135120 204700 315946 330480 359104 368862 243357 256374 365069 352146 292389 296795 186731 69586 70161
[10:00:27.584] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:00:27.584] INFO: number of Vcal hits detected: 207274 207599 207406 207091 207013 206843 206152 207178 207158 206349 206171 206790 206803 207126 207798 207887
[10:00:27.584] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.7 99.6 99.6 99.5 99.2 99.7 99.6 99.3 99.3 99.5 99.5 99.7 100.0 100.0
[10:00:27.585] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.7 99.6 99.5 99.4 99.1 99.6 99.6 99.2 99.1 99.4 99.4 99.6 99.9 99.9
[10:00:27.585] INFO: X-ray hit rate [MHz/cm2]: 61.2 39.6 60.0 92.6 96.9 105.3 108.1 71.3 75.1 107.0 103.2 85.7 87.0 54.7 20.4 20.6
[10:00:27.585] INFO: PixTestHighRate::doXPixelAlive() done
[10:00:27.631] INFO: PixTest:: pg_setup set to default.
[10:00:27.652] INFO: enter test to run
[10:00:37.582] INFO: test: exit no parameter change
[10:00:37.954] QUIET: Connection to board 30 closed.
[10:00:37.969] INFO: pXar: this is the end, my friend