[10:32:18.215] INFO: *** Welcome to pxar ***
[10:32:18.215] INFO: *** Today: 2016/10/25
[10:32:19.025] INFO: *** Version: v1.9.0-818-g96727
[10:32:19.025] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//dacParameters35_C15.dat
[10:32:19.025] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:32:19.025] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//defaultMaskFile.dat
[10:32:19.025] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C15.dat
[10:32:19.092] INFO: clk: 4
[10:32:19.092] INFO: ctr: 4
[10:32:19.092] INFO: sda: 19
[10:32:19.092] INFO: tin: 9
[10:32:19.092] INFO: level: 15
[10:32:19.092] INFO: triggerdelay: 0
[10:32:19.092] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:32:19.092] INFO: Log level: INFO
[10:32:19.110] QUIET: Connection to board DTB_WREHUL opened.
[10:32:19.113] 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:
------------------------------------------------------
[10:32:19.116] INFO: RPC call hashes of host and DTB match: 398089610
[10:32:20.645] INFO: DUT info:
[10:32:20.645] INFO: The DUT currently contains the following objects:
[10:32:20.645] INFO: 2 TBM Cores tbm08c (2 ON)
[10:32:20.645] INFO: TBM Core alpha (0): 7 registers set
[10:32:20.645] INFO: TBM Core beta (1): 7 registers set
[10:32:20.645] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:32:20.645] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:20.645] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:21.075] INFO: enter 'restricted' command line mode
[10:32:21.075] INFO: enter test to run
[10:32:26.433] INFO: test: PixelAlive no parameter change
[10:32:26.433] INFO: running: pixelalive
[10:32:26.456] INFO: ----------------------------------------------------------------------
[10:32:26.456] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:32:26.456] INFO: ----------------------------------------------------------------------
[10:32:26.779] INFO: Expecting 41600 events.
[10:32:31.121] INFO: 41600 events read in total (3624ms).
[10:32:31.289] INFO: Test took 4831ms.
[10:32:31.300] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:31.631] INFO: PixTestAlive::aliveTest() done
[10:32:31.631] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 1 0 0 2 0
[10:32:31.665] INFO: enter test to run
[10:33:01.529] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:33:01.529] INFO: running: highrate
[10:33:01.529] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:33:01.690] INFO: ----------------------------------------------------------------------
[10:33:01.690] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:33:01.690] INFO: ----------------------------------------------------------------------
[10:33:01.690] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:33:01.690] INFO: edge/corner pixel THR is adjusted
[10:33:01.690] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:33:02.649] INFO: Collecting data for 5 seconds...
[10:33:07.667] INFO: Done with hot pixel readout
[10:33:19.158] INFO: PixTest:: pg_setup set to default.
[10:33:19.159] INFO: 1 hot pixels found in step 0
[10:33:20.147] INFO: Collecting data for 5 seconds...
[10:33:25.164] INFO: Done with hot pixel readout
[10:33:36.803] INFO: PixTest:: pg_setup set to default.
[10:33:36.804] INFO: 2 hot pixels found in step 1
[10:33:37.799] INFO: Collecting data for 5 seconds...
[10:33:42.817] INFO: Done with hot pixel readout
[10:33:54.463] INFO: PixTest:: pg_setup set to default.
[10:33:54.464] INFO: 0 hot pixels found in step 2
[10:33:54.500] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:33:54.504] INFO: PixTest::trimHotPixels() done
[10:33:54.523] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C0.dat
[10:33:54.528] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C1.dat
[10:33:54.534] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C2.dat
[10:33:54.539] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C3.dat
[10:33:54.545] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C4.dat
[10:33:54.550] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C5.dat
[10:33:54.557] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C6.dat
[10:33:54.562] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C7.dat
[10:33:54.568] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C8.dat
[10:33:54.573] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C9.dat
[10:33:54.578] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C10.dat
[10:33:54.585] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C11.dat
[10:33:54.590] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C12.dat
[10:33:54.596] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C13.dat
[10:33:54.601] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C14.dat
[10:33:54.607] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C15.dat
[10:33:54.612] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//defaultMaskFile.dat
[10:33:54.628] INFO: enter test to run
[10:34:15.232] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:34:15.232] INFO: running: highrate
[10:34:15.237] INFO: ----------------------------------------------------------------------
[10:34:15.237] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:34:15.237] INFO: ----------------------------------------------------------------------
[10:34:15.237] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:34:15.237] INFO: edge/corner pixel THR is adjusted
[10:34:15.237] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:34:16.194] INFO: Collecting data for 1 seconds...
[10:34:17.198] INFO: Done with hot pixel readout
[10:34:21.071] INFO: PixTest:: pg_setup set to default.
[10:34:21.072] INFO: 0 hot pixels found in step 0
[10:34:21.077] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:34:21.165] INFO: PixTest::trimHotPixels() done
[10:34:21.165] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C0.dat
[10:34:21.176] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C1.dat
[10:34:21.185] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C2.dat
[10:34:21.194] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C3.dat
[10:34:21.200] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C4.dat
[10:34:21.207] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C5.dat
[10:34:21.215] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C6.dat
[10:34:21.220] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C7.dat
[10:34:21.226] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C8.dat
[10:34:21.231] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C9.dat
[10:34:21.236] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C10.dat
[10:34:21.242] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C11.dat
[10:34:21.247] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C12.dat
[10:34:21.253] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C13.dat
[10:34:21.258] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C14.dat
[10:34:21.266] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//trimParameters35_C15.dat
[10:34:21.275] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-1-29_FPIXTest-17C-FNAL-161024-1025-150V_2016-10-24_10h25m_1477322714/000_FPIXTest_p17//defaultMaskFile.dat
[10:34:21.287] INFO: enter test to run
[10:34:42.936] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:34:42.936] INFO: running: xray
[10:34:42.937] INFO: ----------------------------------------------------------------------
[10:34:42.937] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:34:42.937] INFO: ----------------------------------------------------------------------
[10:34:43.900] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:34:55.545] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:35:24.984] INFO: Resuming triggers.
[10:35:36.632] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:36:06.275] INFO: Resuming triggers.
[10:36:17.924] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:36:46.730] INFO: Resuming triggers.
[10:36:58.380] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:37:28.014] INFO: Resuming triggers.
[10:37:39.663] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[10:38:09.104] INFO: Resuming triggers.
[10:38:20.755] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:38:50.500] INFO: Resuming triggers.
[10:39:02.150] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[10:39:32.242] INFO: Resuming triggers.
[10:39:43.893] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:40:13.818] INFO: Resuming triggers.
[10:40:21.009] INFO: data taking finished, elapsed time: 100 seconds.
[10:40:39.522] INFO: PixTest:: pg_setup set to default.
[10:40:39.526] INFO: PixTestXray::doPhRun() done
[10:40:39.690] INFO: enter test to run
[10:41:15.197] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:41:15.197] INFO: running: xray
[10:41:15.198] INFO: ----------------------------------------------------------------------
[10:41:15.199] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:41:15.199] INFO: ----------------------------------------------------------------------
[10:41:16.162] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:41:22.938] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:41:53.452] INFO: Resuming triggers.
[10:42:00.231] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:42:30.687] INFO: Resuming triggers.
[10:42:37.461] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[10:43:08.449] INFO: Resuming triggers.
[10:43:15.226] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[10:43:45.546] INFO: Resuming triggers.
[10:43:52.320] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:44:22.625] INFO: Resuming triggers.
[10:44:29.398] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[10:44:59.729] INFO: Resuming triggers.
[10:45:06.502] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[10:45:36.521] INFO: Resuming triggers.
[10:45:43.301] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[10:46:13.437] INFO: Resuming triggers.
[10:46:20.216] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[10:46:50.256] INFO: Resuming triggers.
[10:46:57.032] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[10:47:27.178] INFO: Resuming triggers.
[10:47:33.957] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[10:48:04.023] INFO: Resuming triggers.
[10:48:10.800] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[10:48:41.050] INFO: Resuming triggers.
[10:48:47.829] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[10:49:18.890] INFO: Resuming triggers.
[10:49:25.664] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[10:49:56.606] INFO: Resuming triggers.
[10:50:02.093] INFO: data taking finished, elapsed time: 100 seconds.
[10:50:27.070] INFO: PixTest:: pg_setup set to default.
[10:50:27.073] INFO: PixTestXray::doPhRun() done
[10:50:27.252] INFO: enter test to run
[10:50:44.202] INFO: test: HighRate no parameter change
[10:50:44.202] INFO: running: highrate
[10:50:44.601] INFO: ----------------------------------------------------------------------
[10:50:44.601] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:50:44.601] INFO: ----------------------------------------------------------------------
[10:50:44.797] INFO: Expecting 768 events.
[10:50:45.961] INFO: 768 events read in total (448ms).
[10:50:45.961] INFO: Test took 1298ms.
[10:50:46.764] INFO: Expecting 41600 events.
[10:50:49.780] INFO: 41600 events read in total (2490ms).
[10:50:49.781] INFO: Test took 3791ms.
[10:50:49.812] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:50.549] INFO: Expecting 41600 events.
[10:50:53.698] INFO: 41600 events read in total (2622ms).
[10:50:53.699] INFO: Test took 3871ms.
[10:50:53.730] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:54.462] INFO: Expecting 41600 events.
[10:50:57.666] INFO: 41600 events read in total (2678ms).
[10:50:57.667] INFO: Test took 3919ms.
[10:50:57.699] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:58.420] INFO: Expecting 41600 events.
[10:51:01.645] INFO: 41600 events read in total (2698ms).
[10:51:01.646] INFO: Test took 3931ms.
[10:51:01.679] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:02.408] INFO: Expecting 41600 events.
[10:51:05.643] INFO: 41600 events read in total (2708ms).
[10:51:05.644] INFO: Test took 3948ms.
[10:51:05.675] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:06.404] INFO: Expecting 41600 events.
[10:51:09.538] INFO: 41600 events read in total (2607ms).
[10:51:09.539] INFO: Test took 3847ms.
[10:51:09.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:10.297] INFO: Expecting 41600 events.
[10:51:13.537] INFO: 41600 events read in total (2713ms).
[10:51:13.538] INFO: Test took 3948ms.
[10:51:13.569] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:14.300] INFO: Expecting 41600 events.
[10:51:17.441] INFO: 41600 events read in total (2614ms).
[10:51:17.443] INFO: Test took 3856ms.
[10:51:17.479] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:18.197] INFO: Expecting 41600 events.
[10:51:21.356] INFO: 41600 events read in total (2632ms).
[10:51:21.357] INFO: Test took 3860ms.
[10:51:21.389] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:22.113] INFO: Expecting 41600 events.
[10:51:25.370] INFO: 41600 events read in total (2730ms).
[10:51:25.371] INFO: Test took 3965ms.
[10:51:25.413] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:26.129] INFO: Expecting 41600 events.
[10:51:29.373] INFO: 41600 events read in total (2717ms).
[10:51:29.374] INFO: Test took 3942ms.
[10:51:29.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:30.131] INFO: Expecting 41600 events.
[10:51:33.299] INFO: 41600 events read in total (2641ms).
[10:51:33.300] INFO: Test took 3876ms.
[10:51:33.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:34.059] INFO: Expecting 41600 events.
[10:51:37.197] INFO: 41600 events read in total (2611ms).
[10:51:37.198] INFO: Test took 3850ms.
[10:51:37.230] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:37.955] INFO: Expecting 41600 events.
[10:51:41.078] INFO: 41600 events read in total (2596ms).
[10:51:41.079] INFO: Test took 3831ms.
[10:51:41.111] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:41.839] INFO: Expecting 41600 events.
[10:51:45.114] INFO: 41600 events read in total (2748ms).
[10:51:45.115] INFO: Test took 3987ms.
[10:51:45.148] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:45.878] INFO: Expecting 41600 events.
[10:51:49.114] INFO: 41600 events read in total (2709ms).
[10:51:49.115] INFO: Test took 3950ms.
[10:51:49.147] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:49.877] INFO: Expecting 41600 events.
[10:51:53.147] INFO: 41600 events read in total (2743ms).
[10:51:53.148] INFO: Test took 3983ms.
[10:51:53.181] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:53.909] INFO: Expecting 41600 events.
[10:51:57.059] INFO: 41600 events read in total (2623ms).
[10:51:57.060] INFO: Test took 3861ms.
[10:51:57.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:57.815] INFO: Expecting 41600 events.
[10:52:00.005] INFO: 41600 events read in total (2663ms).
[10:52:01.006] INFO: Test took 3893ms.
[10:52:01.038] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:01.762] INFO: Expecting 41600 events.
[10:52:04.877] INFO: 41600 events read in total (2588ms).
[10:52:04.878] INFO: Test took 3821ms.
[10:52:04.909] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:05.275] INFO: enter test to run
[10:52:09.441] INFO: test: HighRate no parameter change
[10:52:09.441] INFO: running: highrate
[10:52:09.442] INFO: ----------------------------------------------------------------------
[10:52:09.442] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:52:09.442] INFO: ----------------------------------------------------------------------
[10:52:10.077] INFO: Expecting 208000 events.
[10:52:21.663] INFO: 208000 events read in total (11059ms).
[10:52:21.665] INFO: Test took 12203ms.
[10:52:21.797] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:22.046] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 1 0 0 2 0
[10:52:22.046] INFO: number of red-efficiency pixels: 60 28 48 90 124 94 131 84 78 103 121 84 84 60 18 20
[10:52:22.046] INFO: number of X-ray hits detected: 58859 32592 56048 84905 92639 97935 99202 69615 71838 96988 100075 79897 82150 49867 20229 20872
[10:52:22.046] 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:52:22.046] INFO: number of Vcal hits detected: 207940 207971 207950 207909 207876 207905 207812 207902 207921 207888 207875 207866 207911 207937 207883 207980
[10:52:22.046] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 100.0 99.9 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[10:52:22.046] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 99.9 100.0 99.9 100.0 100.0 99.9 99.9 99.9 100.0 100.0 99.9 100.0
[10:52:22.046] INFO: X-ray hit rate [MHz/cm2]: 17.3 9.6 16.4 24.9 27.2 28.7 29.1 20.4 21.1 28.4 29.3 23.4 24.1 14.6 5.9 6.1
[10:52:22.046] INFO: PixTestHighRate::doXPixelAlive() done
[10:52:22.091] INFO: PixTest:: pg_setup set to default.
[10:52:22.105] INFO: enter test to run
[10:52:46.265] INFO: test: HighRate no parameter change
[10:52:46.265] INFO: running: highrate
[10:52:46.266] INFO: ----------------------------------------------------------------------
[10:52:46.266] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:52:46.266] INFO: ----------------------------------------------------------------------
[10:52:46.885] INFO: Expecting 208000 events.
[10:53:00.383] INFO: 208000 events read in total (12971ms).
[10:53:00.388] INFO: Test took 14113ms.
[10:53:00.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:00.999] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 1 0 0 2 0
[10:53:00.999] INFO: number of red-efficiency pixels: 219 109 202 315 358 355 416 239 232 348 514 378 269 226 75 46
[10:53:00.000] INFO: number of X-ray hits detected: 135345 76577 129710 195832 214512 227749 230526 161886 167163 223490 231757 185177 190833 115284 47343 49162
[10:53:00.000] 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:53:00.000] INFO: number of Vcal hits detected: 207766 207889 207786 207663 207623 207613 207484 207729 207749 207613 207437 207538 207715 207765 207825 207953
[10:53:00.000] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[10:53:00.000] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.7 99.8 99.9 99.9 99.9 100.0
[10:53:00.000] INFO: X-ray hit rate [MHz/cm2]: 39.7 22.4 38.0 57.4 62.9 66.8 67.6 47.4 49.0 65.5 67.9 54.3 55.9 33.8 13.9 14.4
[10:53:00.000] INFO: PixTestHighRate::doXPixelAlive() done
[10:53:01.046] INFO: PixTest:: pg_setup set to default.
[10:53:01.061] INFO: enter test to run
[10:53:19.729] INFO: test: HighRate no parameter change
[10:53:19.729] INFO: running: highrate
[10:53:19.730] INFO: ----------------------------------------------------------------------
[10:53:19.730] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:53:19.730] INFO: ----------------------------------------------------------------------
[10:53:20.351] INFO: Expecting 208000 events.
[10:53:35.615] INFO: 208000 events read in total (14737ms).
[10:53:35.624] INFO: Test took 15886ms.
[10:53:36.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:36.418] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 1 0 0 2 0
[10:53:36.418] INFO: number of red-efficiency pixels: 431 150 403 672 750 805 841 427 514 776 1048 783 519 447 101 85
[10:53:36.418] INFO: number of X-ray hits detected: 196494 111637 188902 285091 312366 329136 335311 236778 243715 325237 336603 271453 277415 167735 68537 71923
[10:53:36.418] 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:53:36.418] INFO: number of Vcal hits detected: 207482 207840 207536 207241 207126 207050 206918 207504 207406 207055 206662 207032 207399 207468 207799 207915
[10:53:36.418] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.6 99.6 99.5 99.8 99.7 99.6 99.4 99.6 99.7 99.8 100.0 100.0
[10:53:36.419] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.5 99.5 99.8 99.7 99.5 99.4 99.5 99.7 99.7 99.9 100.0
[10:53:36.419] INFO: X-ray hit rate [MHz/cm2]: 57.6 32.7 55.4 83.6 91.6 96.5 98.3 69.4 71.4 95.3 98.7 79.6 81.3 49.2 20.1 21.1
[10:53:36.419] INFO: PixTestHighRate::doXPixelAlive() done
[10:53:36.466] INFO: PixTest:: pg_setup set to default.
[10:53:36.477] INFO: enter test to run
[10:53:42.568] INFO: test: exit no parameter change
[10:53:43.107] QUIET: Connection to board 30 closed.
[10:53:43.255] INFO: pXar: this is the end, my friend