[12:11:37.988] INFO: *** Welcome to pxar ***
[12:11:37.988] INFO: *** Today: 2016/08/24
[12:11:38.009] INFO: *** Version: v1.9.0-818-g96727
[12:11:38.009] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//dacParameters35_C15.dat
[12:11:38.010] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:11:38.011] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//defaultMaskFile.dat
[12:11:38.011] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C15.dat
[12:11:38.128] INFO: clk: 4
[12:11:38.129] INFO: ctr: 4
[12:11:38.129] INFO: sda: 19
[12:11:38.129] INFO: tin: 9
[12:11:38.129] INFO: level: 15
[12:11:38.129] INFO: triggerdelay: 0
[12:11:38.129] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:11:38.129] INFO: Log level: INFO
[12:11:38.145] QUIET: Connection to board DTB_WREK4U opened.
[12:11:38.148] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[12:11:38.151] INFO: RPC call hashes of host and DTB match: 398089610
[12:11:39.682] INFO: DUT info:
[12:11:39.682] INFO: The DUT currently contains the following objects:
[12:11:39.682] INFO: 2 TBM Cores tbm08c (2 ON)
[12:11:39.683] INFO: TBM Core alpha (0): 7 registers set
[12:11:39.683] INFO: TBM Core beta (1): 7 registers set
[12:11:39.683] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:11:39.683] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.683] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.683] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.683] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.683] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.683] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.683] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.683] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.683] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.683] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.683] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.683] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.683] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.683] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.684] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:39.684] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:11:40.087] INFO: enter 'restricted' command line mode
[12:11:40.087] INFO: enter test to run
[12:11:47.769] INFO: test: PixelAlive no parameter change
[12:11:47.769] INFO: running: pixelalive
[12:11:47.778] INFO: ----------------------------------------------------------------------
[12:11:47.778] 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:11:47.778] INFO: ----------------------------------------------------------------------
[12:11:48.099] INFO: Expecting 41600 events.
[12:11:52.487] INFO: 41600 events read in total (3669ms).
[12:11:52.649] INFO: Test took 4869ms.
[12:11:52.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:52.920] INFO: PixTestAlive::aliveTest() done
[12:11:52.920] INFO: number of dead pixels (per ROC): 0 0 1 0 0 1 0 0 0 0 0 0 0 0 0 1
[12:11:52.953] INFO: enter test to run
[15:49:24.448] INFO: *** Welcome to pxar ***
[15:49:24.465] INFO: *** Today: 2016/08/24
[15:49:24.499] INFO: *** Version: v1.9.0-818-g96727
[15:49:24.499] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//dacParameters35_C15.dat
[15:49:24.535] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:49:24.535] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//defaultMaskFile.dat
[15:49:24.538] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C15.dat
[15:49:24.617] INFO: clk: 4
[15:49:24.617] INFO: ctr: 4
[15:49:24.617] INFO: sda: 19
[15:49:24.617] INFO: tin: 9
[15:49:24.617] INFO: level: 15
[15:49:24.617] INFO: triggerdelay: 0
[15:49:24.617] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[15:49:24.617] INFO: Log level: INFO
[15:49:24.634] QUIET: Connection to board DTB_WREK4U opened.
[15:49:24.638] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[15:49:24.640] INFO: RPC call hashes of host and DTB match: 398089610
[15:49:26.172] INFO: DUT info:
[15:49:26.172] INFO: The DUT currently contains the following objects:
[15:49:26.172] INFO: 2 TBM Cores tbm08c (2 ON)
[15:49:26.172] INFO: TBM Core alpha (0): 7 registers set
[15:49:26.172] INFO: TBM Core beta (1): 7 registers set
[15:49:26.172] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:49:26.172] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.172] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.172] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.172] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.172] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.172] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.173] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.173] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.173] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.173] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.173] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.173] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.173] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.173] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.173] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.173] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:26.576] INFO: enter 'restricted' command line mode
[15:49:26.576] INFO: enter test to run
[15:49:48.897] INFO: test: PixelAlive no parameter change
[15:49:48.897] INFO: running: pixelalive
[15:49:48.906] INFO: ----------------------------------------------------------------------
[15:49:48.906] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:49:48.906] INFO: ----------------------------------------------------------------------
[15:49:49.227] INFO: Expecting 41600 events.

[15:49:52.693] INFO: Expecting 41600 events.

[15:49:55.885] INFO: PixTestAlive::aliveTest() done with 666667 decoding errors
[15:49:55.885] INFO: number of dead pixels (per ROC): 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160
[15:49:55.918] INFO: enter test to run
[15:51:36.266] INFO: *** Welcome to pxar ***
[15:51:36.266] INFO: *** Today: 2016/08/24
[15:51:36.284] INFO: *** Version: v1.9.0-818-g96727
[15:51:36.284] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//dacParameters35_C15.dat
[15:51:36.285] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:51:36.285] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//defaultMaskFile.dat
[15:51:36.285] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C15.dat
[15:51:36.362] INFO: clk: 4
[15:51:36.362] INFO: ctr: 4
[15:51:36.362] INFO: sda: 19
[15:51:36.362] INFO: tin: 9
[15:51:36.362] INFO: level: 15
[15:51:36.362] INFO: triggerdelay: 0
[15:51:36.362] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[15:51:36.362] INFO: Log level: INFO
[15:51:36.379] QUIET: Connection to board DTB_WREK4U opened.
[15:51:36.382] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[15:51:36.385] INFO: RPC call hashes of host and DTB match: 398089610
[15:51:37.913] INFO: DUT info:
[15:51:37.913] INFO: The DUT currently contains the following objects:
[15:51:37.913] INFO: 2 TBM Cores tbm08c (2 ON)
[15:51:37.913] INFO: TBM Core alpha (0): 7 registers set
[15:51:37.913] INFO: TBM Core beta (1): 7 registers set
[15:51:37.913] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:51:37.913] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.913] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.913] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.913] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.913] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.913] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.913] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.913] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.913] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.913] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.913] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.914] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.914] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.914] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.914] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:37.914] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:38.317] INFO: enter 'restricted' command line mode
[15:51:38.317] INFO: enter test to run
[15:51:44.936] INFO: test: PixelAlive no parameter change
[15:51:44.936] INFO: running: pixelalive
[15:51:44.945] INFO: ----------------------------------------------------------------------
[15:51:44.945] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:51:44.945] INFO: ----------------------------------------------------------------------
[15:51:45.260] INFO: Expecting 41600 events.

[15:51:48.727] INFO: Expecting 41600 events.

[15:51:51.922] INFO: PixTestAlive::aliveTest() done with 666667 decoding errors
[15:51:51.922] INFO: number of dead pixels (per ROC): 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160 4160
[15:51:51.950] INFO: enter test to run
[15:53:19.565] INFO: *** Welcome to pxar ***
[15:53:19.565] INFO: *** Today: 2016/08/24
[15:53:19.587] INFO: *** Version: v1.9.0-818-g96727
[15:53:19.587] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//dacParameters35_C15.dat
[15:53:19.587] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:53:19.588] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//defaultMaskFile.dat
[15:53:19.588] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C15.dat
[15:53:19.665] INFO: clk: 4
[15:53:19.665] INFO: ctr: 4
[15:53:19.665] INFO: sda: 19
[15:53:19.665] INFO: tin: 9
[15:53:19.665] INFO: level: 15
[15:53:19.665] INFO: triggerdelay: 0
[15:53:19.665] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[15:53:19.665] INFO: Log level: INFO
[15:53:19.681] QUIET: Connection to board DTB_WREK4U opened.
[15:53:19.684] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[15:53:19.687] INFO: RPC call hashes of host and DTB match: 398089610
[15:53:21.239] INFO: DUT info:
[15:53:21.239] INFO: The DUT currently contains the following objects:
[15:53:21.239] INFO: 2 TBM Cores tbm08c (2 ON)
[15:53:21.239] INFO: TBM Core alpha (0): 7 registers set
[15:53:21.239] INFO: TBM Core beta (1): 7 registers set
[15:53:21.239] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:53:21.239] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.239] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.239] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.239] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.239] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.239] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.239] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.239] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.239] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.239] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.239] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.239] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.240] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.240] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.240] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.240] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:21.643] INFO: enter 'restricted' command line mode
[15:53:21.643] INFO: enter test to run
[15:53:30.527] INFO: test: PixelAlive no parameter change
[15:53:30.527] INFO: running: pixelalive
[15:53:30.538] INFO: ----------------------------------------------------------------------
[15:53:30.538] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:53:30.539] INFO: ----------------------------------------------------------------------
[15:53:30.861] INFO: Expecting 41600 events.
[15:53:35.231] INFO: 41600 events read in total (3651ms).
[15:53:35.388] INFO: Test took 4847ms.
[15:53:35.396] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:35.662] INFO: PixTestAlive::aliveTest() done
[15:53:35.662] INFO: number of dead pixels (per ROC): 0 0 1 0 0 1 0 0 0 0 0 0 0 0 0 1
[15:53:35.706] INFO: enter test to run
[15:54:02.535] INFO: test: timing no parameter change
[15:54:02.535] INFO: running: timing
[15:54:02.560] INFO: ######################################################################
[15:54:02.560] INFO: PixTestTiming::doTest()
[15:54:02.560] INFO: ######################################################################
[15:54:02.560] INFO: ----------------------------------------------------------------------
[15:54:02.560] INFO: PixTestTiming::TBMPhaseScan()
[15:54:02.560] INFO: ----------------------------------------------------------------------
[16:00:22.379] INFO: TBM Phase Settings: 224
[16:00:22.379] INFO: 400MHz Phase: 0
[16:00:22.379] INFO: 160MHz Phase: 7
[16:00:22.379] INFO: Functional Phase Area: 4
[16:00:22.382] INFO: Test took 379822 ms.
[16:00:22.382] INFO: PixTestTiming::TBMPhaseScan() done.
[16:00:22.382] INFO: ----------------------------------------------------------------------
[16:00:22.382] INFO: PixTestTiming::ROCDelayScan()
[16:00:22.382] INFO: ----------------------------------------------------------------------
[16:03:53.248] INFO: ROC Delay Settings: 228
[16:03:53.248] INFO: ROC Header-Trailer/Token Delay: 11
[16:03:53.248] INFO: ROC Port 0 Delay: 4
[16:03:53.248] INFO: ROC Port 1 Delay: 4
[16:03:53.248] INFO: Functional ROC Area: 4
[16:03:53.251] INFO: Test took 210869 ms.
[16:03:53.251] INFO: PixTestTiming::ROCDelayScan() done.
[16:03:53.252] INFO: ----------------------------------------------------------------------
[16:03:53.252] INFO: PixTestTiming::TimingTest()
[16:03:53.252] INFO: ----------------------------------------------------------------------
[16:04:09.400] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:24.353] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:39.321] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:54.453] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:09.515] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:24.742] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:39.873] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:55.012] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:10.081] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:25.025] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:25.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:25.425] INFO: ----------------------------------------------------------------------
[16:06:25.425] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[16:06:25.425] INFO: ----------------------------------------------------------------------
[16:06:25.425] INFO: ----------------------------------------------------------------------
[16:06:25.425] INFO: Read back bit status: 1
[16:06:25.425] INFO: ----------------------------------------------------------------------
[16:06:25.425] INFO: ----------------------------------------------------------------------
[16:06:25.425] INFO: Timings are good!
[16:06:25.425] INFO: ----------------------------------------------------------------------
[16:06:25.425] INFO: Test took 152174 ms.
[16:06:25.425] INFO: PixTestTiming::TimingTest() done.
[16:06:25.447] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//tbmParameters_C0a.dat
[16:06:25.447] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:06:25.447] INFO: PixTestTiming::doTest took 742890 ms.
[16:06:25.447] INFO: PixTestTiming::doTest() done
[16:06:25.447] INFO: Write out TBMPhaseScan_0_V0
[16:06:25.447] INFO: Write out TBMPhaseScan_1_V0
[16:06:25.447] INFO: Write out CombinedTBMPhaseScan_V0
[16:06:25.470] INFO: Write out ROCDelayScan3_V0
[16:06:25.471] INFO: enter test to run
[16:06:47.666] INFO: test: \HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[16:06:47.666] INFO: running: \highrate
[16:06:47.681] INFO: command ->\highrate<- not known, ignored
[16:06:47.682] INFO: enter test to run
[16:07:11.962] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[16:07:11.962] INFO: running: highrate
[16:07:11.963] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[16:07:12.166] INFO: ----------------------------------------------------------------------
[16:07:12.166] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[16:07:12.166] INFO: ----------------------------------------------------------------------
[16:07:12.166] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[16:07:12.166] INFO: edge/corner pixel THR is adjusted
[16:07:12.166] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:07:13.124] INFO: Collecting data for 5 seconds...
[16:07:18.144] INFO: Done with hot pixel readout
[16:07:29.925] INFO: PixTest:: pg_setup set to default.




[16:07:29.926] INFO: 6 hot pixels found in step 0
[16:07:30.918] INFO: Collecting data for 5 seconds...
[16:07:35.938] INFO: Done with hot pixel readout
[16:07:47.852] INFO: PixTest:: pg_setup set to default.



[16:07:47.853] INFO: 5 hot pixels found in step 1
[16:07:48.845] INFO: Collecting data for 5 seconds...
[16:07:53.865] INFO: Done with hot pixel readout
[16:08:05.542] INFO: PixTest:: pg_setup set to default.



[16:08:05.543] INFO: 4 hot pixels found in step 2
[16:08:06.534] INFO: Collecting data for 5 seconds...
[16:08:11.555] INFO: Done with hot pixel readout
[16:08:23.223] INFO: PixTest:: pg_setup set to default.



[16:08:23.224] INFO: 7 hot pixels found in step 3
[16:08:24.215] INFO: Collecting data for 5 seconds...
[16:08:29.237] INFO: Done with hot pixel readout
[16:08:40.982] INFO: PixTest:: pg_setup set to default.





[16:08:40.983] INFO: 5 hot pixels found in step 4
[16:08:41.019] INFO: 5 hot pixels could not be trimmed and have been masked.
[16:08:41.022] INFO: PixTest::trimHotPixels() done
[16:08:41.022] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C0.dat
[16:08:41.028] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C1.dat
[16:08:41.062] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C2.dat
[16:08:41.069] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C3.dat
[16:08:41.074] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C4.dat
[16:08:41.080] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C5.dat
[16:08:41.085] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C6.dat
[16:08:41.091] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C7.dat
[16:08:41.096] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C8.dat
[16:08:41.102] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C9.dat
[16:08:41.107] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C10.dat
[16:08:41.113] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C11.dat
[16:08:41.118] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C12.dat
[16:08:41.124] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C13.dat
[16:08:41.129] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C14.dat
[16:08:41.135] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C15.dat
[16:08:41.140] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//defaultMaskFile.dat
[16:08:41.150] INFO: enter test to run
[16:09:11.937] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[16:09:11.937] INFO: running: highrate
[16:09:11.942] INFO: ----------------------------------------------------------------------
[16:09:11.942] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[16:09:11.942] INFO: ----------------------------------------------------------------------
[16:09:11.942] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[16:09:11.942] INFO: edge/corner pixel THR is adjusted
[16:09:11.942] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:09:12.899] INFO: Collecting data for 1 seconds...
[16:09:13.903] INFO: Done with hot pixel readout
[16:09:17.746] INFO: PixTest:: pg_setup set to default.
[16:09:17.747] INFO: 0 hot pixels found in step 0
[16:09:17.753] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:09:17.849] INFO: PixTest::trimHotPixels() done
[16:09:17.849] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C0.dat
[16:09:17.860] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C1.dat
[16:09:17.865] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C2.dat
[16:09:17.871] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C3.dat
[16:09:17.877] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C4.dat
[16:09:17.882] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C5.dat
[16:09:17.887] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C6.dat
[16:09:17.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C7.dat
[16:09:17.898] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C8.dat
[16:09:17.903] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C9.dat
[16:09:17.909] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C10.dat
[16:09:17.915] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C11.dat
[16:09:17.920] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C12.dat
[16:09:17.925] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C13.dat
[16:09:17.930] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C14.dat
[16:09:17.936] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//trimParameters35_C15.dat
[16:09:17.942] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-7-06_FPIXTest-17C-FNAL-160819-1210-150V_2016-08-19_12h10m_1471626637/000_FPIXTest_p17//defaultMaskFile.dat
[16:09:17.951] INFO: enter test to run
[16:10:05.169] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[16:10:05.169] INFO: running: xray
[16:10:05.170] INFO: ----------------------------------------------------------------------
[16:10:05.170] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:10:05.170] INFO: ----------------------------------------------------------------------
[16:10:06.133] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:10:18.437] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[16:10:47.604] INFO: Resuming triggers.
[16:10:59.913] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[16:11:29.163] INFO: Resuming triggers.
[16:11:41.468] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[16:12:10.598] INFO: Resuming triggers.
[16:12:22.912] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[16:12:52.172] INFO: Resuming triggers.
[16:13:04.483] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[16:13:33.712] INFO: Resuming triggers.
[16:13:46.026] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[16:14:15.268] INFO: Resuming triggers.
[16:14:27.579] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[16:14:56.740] INFO: Resuming triggers.
[16:15:09.052] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[16:15:38.245] INFO: Resuming triggers.
[16:15:40.089] INFO: data taking finished, elapsed time: 100 seconds.
[16:15:44.859] INFO: PixTest:: pg_setup set to default.
[16:15:44.863] INFO: PixTestXray::doPhRun() done
[16:15:45.034] INFO: enter test to run
[16:16:07.334] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[16:16:07.334] INFO: running: xray
[16:16:07.335] INFO: ----------------------------------------------------------------------
[16:16:07.335] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:16:07.336] INFO: ----------------------------------------------------------------------
[16:16:08.299] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:16:15.367] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[16:16:45.459] INFO: Resuming triggers.
[16:16:52.529] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[16:17:23.591] INFO: Resuming triggers.
[16:17:30.660] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[16:18:00.703] INFO: Resuming triggers.
[16:18:07.770] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[16:18:38.450] INFO: Resuming triggers.
[16:18:45.515] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[16:19:16.336] INFO: Resuming triggers.
[16:19:23.402] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[16:19:55.035] INFO: Resuming triggers.
[16:20:02.098] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[16:20:33.272] INFO: Resuming triggers.
[16:20:40.334] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[16:21:10.945] INFO: Resuming triggers.
[16:21:18.011] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[16:21:47.956] INFO: Resuming triggers.
[16:21:55.022] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[16:22:25.113] INFO: Resuming triggers.
[16:22:32.178] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[16:23:02.197] INFO: Resuming triggers.
[16:23:09.265] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[16:23:39.361] INFO: Resuming triggers.
[16:23:46.431] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[16:24:16.509] INFO: Resuming triggers.
[16:24:23.577] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[16:24:53.604] INFO: Resuming triggers.
[16:24:55.021] INFO: data taking finished, elapsed time: 100 seconds.
[16:25:01.408] INFO: PixTest:: pg_setup set to default.
[16:25:01.411] INFO: PixTestXray::doPhRun() done
[16:25:01.558] INFO: enter test to run
[16:25:25.282] INFO: test: HighRate no parameter change
[16:25:25.282] INFO: running: highrate
[16:25:25.299] INFO: ----------------------------------------------------------------------
[16:25:25.299] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:25:25.299] INFO: ----------------------------------------------------------------------
[16:25:25.445] INFO: Expecting 768 events.
[16:25:26.578] INFO: 768 events read in total (418ms).
[16:25:26.579] INFO: Test took 1268ms.
[16:25:27.382] INFO: Expecting 41600 events.
[16:25:30.427] INFO: 41600 events read in total (2518ms).
[16:25:30.428] INFO: Test took 3842ms.
[16:25:30.459] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:31.191] INFO: Expecting 41600 events.
[16:25:34.357] INFO: 41600 events read in total (2639ms).
[16:25:34.358] INFO: Test took 3881ms.
[16:25:34.389] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:35.121] INFO: Expecting 41600 events.
[16:25:38.339] INFO: 41600 events read in total (2691ms).
[16:25:38.340] INFO: Test took 3933ms.
[16:25:38.371] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:39.100] INFO: Expecting 41600 events.
[16:25:42.320] INFO: 41600 events read in total (2693ms).
[16:25:42.321] INFO: Test took 3932ms.
[16:25:42.352] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:43.077] INFO: Expecting 41600 events.
[16:25:46.304] INFO: 41600 events read in total (2701ms).
[16:25:46.305] INFO: Test took 3935ms.
[16:25:46.338] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:47.062] INFO: Expecting 41600 events.
[16:25:50.277] INFO: 41600 events read in total (2688ms).
[16:25:50.278] INFO: Test took 3923ms.
[16:25:50.309] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:51.037] INFO: Expecting 41600 events.
[16:25:54.253] INFO: 41600 events read in total (2689ms).
[16:25:54.254] INFO: Test took 3927ms.
[16:25:54.285] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:55.016] INFO: Expecting 41600 events.
[16:25:58.245] INFO: 41600 events read in total (2702ms).
[16:25:58.246] INFO: Test took 3943ms.
[16:25:58.278] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:58.006] INFO: Expecting 41600 events.
[16:26:02.229] INFO: 41600 events read in total (2697ms).
[16:26:02.231] INFO: Test took 3935ms.
[16:26:02.262] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:02.990] INFO: Expecting 41600 events.
[16:26:06.215] INFO: 41600 events read in total (2698ms).
[16:26:06.216] INFO: Test took 3936ms.
[16:26:06.248] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:06.977] INFO: Expecting 41600 events.
[16:26:10.197] INFO: 41600 events read in total (2693ms).
[16:26:10.197] INFO: Test took 3932ms.
[16:26:10.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:10.956] INFO: Expecting 41600 events.
[16:26:14.181] INFO: 41600 events read in total (2698ms).
[16:26:14.182] INFO: Test took 3935ms.
[16:26:14.213] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:14.940] INFO: Expecting 41600 events.
[16:26:18.156] INFO: 41600 events read in total (2690ms).
[16:26:18.157] INFO: Test took 3926ms.
[16:26:18.188] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:18.919] INFO: Expecting 41600 events.
[16:26:22.141] INFO: 41600 events read in total (2695ms).
[16:26:22.142] INFO: Test took 3935ms.
[16:26:22.173] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:22.904] INFO: Expecting 41600 events.
[16:26:26.123] INFO: 41600 events read in total (2692ms).
[16:26:26.124] INFO: Test took 3933ms.
[16:26:26.156] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:26.885] INFO: Expecting 41600 events.
[16:26:30.105] INFO: 41600 events read in total (2693ms).
[16:26:30.106] INFO: Test took 3933ms.
[16:26:30.138] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:30.863] INFO: Expecting 41600 events.
[16:26:34.069] INFO: 41600 events read in total (2679ms).
[16:26:34.069] INFO: Test took 3914ms.
[16:26:34.101] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:34.831] INFO: Expecting 41600 events.
[16:26:38.038] INFO: 41600 events read in total (2680ms).
[16:26:38.039] INFO: Test took 3921ms.
[16:26:38.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:38.798] INFO: Expecting 41600 events.
[16:26:41.984] INFO: 41600 events read in total (2659ms).
[16:26:41.985] INFO: Test took 3897ms.
[16:26:42.016] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:42.743] INFO: Expecting 41600 events.
[16:26:45.812] INFO: 41600 events read in total (2542ms).
[16:26:45.813] INFO: Test took 3779ms.
[16:26:45.845] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:46.212] INFO: enter test to run
[16:27:57.785] INFO: test: HighRate no parameter change
[16:27:57.785] INFO: running: highrate
[16:27:57.787] INFO: ----------------------------------------------------------------------
[16:27:57.787] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:27:57.787] INFO: ----------------------------------------------------------------------
[16:27:58.401] INFO: Expecting 208000 events.
[16:28:10.121] INFO: 208000 events read in total (11193ms).
[16:28:10.124] INFO: Test took 12329ms.
[16:28:10.253] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:10.501] INFO: number of dead pixels (per ROC): 0 0 1 0 0 1 0 0 0 0 0 0 0 0 0 1
[16:28:10.501] INFO: number of red-efficiency pixels: 86 42 139 106 105 134 144 114 82 116 111 82 94 61 16 23
[16:28:10.501] INFO: number of X-ray hits detected: 60287 37859 61884 95996 103954 107103 104955 73392 72675 91872 89020 76226 77592 47976 18873 24291
[16:28:10.501] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:28:10.501] INFO: number of Vcal hits detected: 207911 207957 207801 207892 207893 207815 207855 207885 207917 207882 207887 207916 207905 207938 207984 207928
[16:28:10.501] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 100.0 99.9 99.9 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[16:28:10.501] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[16:28:10.501] INFO: X-ray hit rate [MHz/cm2]: 17.7 11.1 18.1 28.1 30.5 31.4 30.8 21.5 21.3 26.9 26.1 22.3 22.7 14.1 5.5 7.1
[16:28:10.501] INFO: PixTestHighRate::doXPixelAlive() done
[16:28:10.552] INFO: PixTest:: pg_setup set to default.
[16:28:10.569] INFO: enter test to run
[16:28:16.977] INFO: test: HighRate no parameter change
[16:28:16.977] INFO: running: highrate
[16:28:16.978] INFO: ----------------------------------------------------------------------
[16:28:16.978] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:28:16.978] INFO: ----------------------------------------------------------------------
[16:28:17.588] INFO: Expecting 208000 events.
[16:28:30.834] INFO: 208000 events read in total (12719ms).
[16:28:30.839] INFO: Test took 13854ms.
[16:28:31.111] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:31.405] INFO: number of dead pixels (per ROC): 0 0 1 0 0 1 0 0 0 0 0 0 0 0 0 1
[16:28:31.405] INFO: number of red-efficiency pixels: 223 110 355 384 411 399 534 281 200 363 364 245 281 181 57 65
[16:28:31.405] INFO: number of X-ray hits detected: 126549 79639 129477 198305 215866 222526 218685 152954 152510 191097 186218 158913 162267 99443 39499 50547
[16:28:31.405] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:28:31.405] INFO: number of Vcal hits detected: 207757 207886 207537 207591 207559 207517 207426 207712 207794 207623 207615 207744 207706 207808 207942 207885
[16:28:31.405] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.8 99.7 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[16:28:31.405] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.8 99.7 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 99.9
[16:28:31.405] INFO: X-ray hit rate [MHz/cm2]: 37.1 23.3 38.0 58.1 63.3 65.2 64.1 44.8 44.7 56.0 54.6 46.6 47.6 29.1 11.6 14.8
[16:28:31.405] INFO: PixTestHighRate::doXPixelAlive() done
[16:28:31.455] INFO: PixTest:: pg_setup set to default.
[16:28:31.473] INFO: enter test to run
[16:28:34.849] INFO: test: HighRate no parameter change
[16:28:34.849] INFO: running: highrate
[16:28:34.851] INFO: enter test to run
[16:28:50.161] INFO: test: HighRate no parameter change
[16:28:50.161] INFO: running: highrate
[16:28:50.162] INFO: ----------------------------------------------------------------------
[16:28:50.162] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:28:50.162] INFO: ----------------------------------------------------------------------
[16:28:50.779] INFO: Expecting 208000 events.
[16:29:05.934] INFO: 208000 events read in total (14628ms).
[16:29:05.941] INFO: Test took 15770ms.
[16:29:06.347] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:06.687] INFO: number of dead pixels (per ROC): 0 0 1 0 0 1 0 0 0 0 0 0 0 0 0 1
[16:29:06.687] INFO: number of red-efficiency pixels: 530 197 707 975 893 952 1251 728 428 751 896 493 622 448 100 95
[16:29:06.687] INFO: number of X-ray hits detected: 193473 122122 200759 307253 331755 342190 337302 236777 234479 294624 288095 245452 250000 154301 61307 79177
[16:29:06.687] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:29:06.687] INFO: number of Vcal hits detected: 207343 207786 206987 206807 206901 206768 206377 207101 207535 207113 206923 207436 207315 207484 207896 207852
[16:29:06.687] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.6 99.5 99.5 99.5 99.3 99.6 99.8 99.6 99.5 99.8 99.7 99.8 100.0 100.0
[16:29:06.687] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.5 99.4 99.5 99.4 99.2 99.6 99.8 99.6 99.5 99.7 99.7 99.8 100.0 99.9
[16:29:06.687] INFO: X-ray hit rate [MHz/cm2]: 56.7 35.8 58.8 90.1 97.2 100.3 98.9 69.4 68.7 86.4 84.4 71.9 73.3 45.2 18.0 23.2
[16:29:06.687] INFO: PixTestHighRate::doXPixelAlive() done
[16:29:06.740] INFO: PixTest:: pg_setup set to default.
[16:29:06.753] INFO: enter test to run
[16:29:19.553] INFO: test: exit no parameter change
[16:29:19.962] QUIET: Connection to board 32 closed.
[16:29:19.963] INFO: pXar: this is the end, my friend