[15:51:12.970] <TB3> INFO: *** Welcome to pxar ***
[15:51:12.970] <TB3> INFO: *** Today: 2016/04/11
[15:51:13.281] <TB3> INFO: *** Version: 9751
[15:51:13.281] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//000_PixelAlive_p17//dacParameters35_C15.dat
[15:51:13.282] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//000_PixelAlive_p17//tbmParameters_C0b.dat
[15:51:13.283] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//000_PixelAlive_p17//defaultMaskFile.dat
[15:51:13.283] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//000_PixelAlive_p17//trimParameters35_C15.dat
[15:51:13.358] <TB3> INFO: clk: 4
[15:51:13.358] <TB3> INFO: ctr: 4
[15:51:13.358] <TB3> INFO: sda: 19
[15:51:13.358] <TB3> INFO: tin: 9
[15:51:13.358] <TB3> INFO: level: 15
[15:51:13.358] <TB3> INFO: triggerdelay: 0
[15:51:13.358] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[15:51:13.358] <TB3> INFO: Log level: INFO
[15:51:13.372] <TB3> INFO: Found DTB DTB_WWV6Z5
[15:51:13.386] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[15:51:13.389] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[15:51:13.390] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[15:51:14.933] <TB3> INFO: DUT info:
[15:51:14.933] <TB3> INFO: The DUT currently contains the following objects:
[15:51:14.933] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:51:14.933] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:51:14.933] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:51:14.933] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:51:14.933] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.933] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.933] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.933] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.933] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.933] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.933] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.933] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.933] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.933] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.933] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.933] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.934] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.934] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.934] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:14.934] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:15.335] <TB3> INFO: enter 'restricted' command line mode
[15:51:15.335] <TB3> INFO: enter test to run
[15:51:15.335] <TB3> INFO: test: PixelAlive no parameter change
[15:51:15.335] <TB3> INFO: running: pixelalive
[15:51:15.339] <TB3> INFO: ######################################################################
[15:51:15.339] <TB3> INFO: PixTestAlive::doTest()
[15:51:15.339] <TB3> INFO: ######################################################################
[15:51:15.340] <TB3> INFO: ----------------------------------------------------------------------
[15:51:15.340] <TB3> 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:15.340] <TB3> INFO: ----------------------------------------------------------------------
[15:51:15.617] <TB3> INFO: Expecting 41600 events.
[15:51:19.324] <TB3> INFO: 41600 events read in total (3149ms).
[15:51:19.373] <TB3> INFO: Test took 4032ms.
[15:51:19.592] <TB3> INFO: PixTestAlive::aliveTest() done
[15:51:19.592] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 2 0 0 0
[15:51:19.593] <TB3> INFO: ----------------------------------------------------------------------
[15:51:19.593] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:51:19.593] <TB3> INFO: ----------------------------------------------------------------------
[15:51:19.854] <TB3> INFO: Expecting 41600 events.
[15:51:22.787] <TB3> INFO: 41600 events read in total (2376ms).
[15:51:22.787] <TB3> INFO: Test took 3192ms.
[15:51:22.787] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:51:23.030] <TB3> INFO: PixTestAlive::maskTest() done
[15:51:23.030] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:51:23.031] <TB3> INFO: ----------------------------------------------------------------------
[15:51:23.031] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:51:23.031] <TB3> INFO: ----------------------------------------------------------------------
[15:51:23.287] <TB3> INFO: Expecting 41600 events.
[15:51:27.000] <TB3> INFO: 41600 events read in total (3157ms).
[15:51:27.000] <TB3> INFO: Test took 3967ms.
[15:51:27.221] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[15:51:27.221] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:51:27.221] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[15:51:27.249] <TB3> INFO: enter test to run
[15:51:27.249] <TB3> INFO: test: no parameter change
[15:51:27.406] <TB3> QUIET: Connection to board 121 closed.
[15:51:27.486] <TB3> INFO: pXar: this is the end, my friend

[15:56:37.477] <TB3> INFO: *** Welcome to pxar ***
[15:56:37.477] <TB3> INFO: *** Today: 2016/04/11
[15:56:37.544] <TB3> INFO: *** Version: 9751
[15:56:37.544] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//dacParameters35_C15.dat
[15:56:37.545] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[15:56:37.545] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//defaultMaskFile.dat
[15:56:37.545] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C15.dat
[15:56:37.636] <TB3> INFO: clk: 4
[15:56:37.636] <TB3> INFO: ctr: 4
[15:56:37.636] <TB3> INFO: sda: 19
[15:56:37.636] <TB3> INFO: tin: 9
[15:56:37.636] <TB3> INFO: level: 15
[15:56:37.636] <TB3> INFO: triggerdelay: 0
[15:56:37.636] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[15:56:37.636] <TB3> INFO: Log level: INFO
[15:56:37.650] <TB3> INFO: Found DTB DTB_WWV6Z5
[15:56:37.667] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[15:56:37.669] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[15:56:37.671] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[15:56:39.233] <TB3> INFO: DUT info:
[15:56:39.233] <TB3> INFO: The DUT currently contains the following objects:
[15:56:39.233] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:56:39.233] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:56:39.233] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:56:39.233] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:56:39.233] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.634] <TB3> INFO: enter 'restricted' command line mode
[15:56:39.634] <TB3> INFO: enter test to run
[15:56:39.634] <TB3> INFO: test: delay setting parameters: ->5<-
[15:56:39.634] <TB3> INFO: delay test by 5 seconds...
[15:56:44.634] <TB3> INFO: enter test to run
[15:56:44.634] <TB3> INFO: test: highrate no parameter change
[15:56:44.634] <TB3> INFO: running: highrate
[15:56:44.634] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[15:56:44.846] <TB3> INFO: ----------------------------------------------------------------------
[15:56:44.846] <TB3> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:56:44.846] <TB3> INFO: ----------------------------------------------------------------------
[15:56:44.846] <TB3> INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:56:44.846] <TB3> INFO: edge/corner pixel THR is adjusted
[15:56:44.846] <TB3> INFO: PixTestHighRate::trimHotPixels: step 0...
[15:56:45.562] <TB3> INFO: Collecting data for 1 seconds...
[15:56:46.564] <TB3> INFO: Done with hot pixel readout
[15:56:52.650] <TB3> INFO: PixTest:: pg_setup set to default.
[15:56:52.651] <TB3> INFO: 3 hot pixels found in step 0
[15:56:53.370] <TB3> INFO: Collecting data for 1 seconds...
[15:56:54.372] <TB3> INFO: Done with hot pixel readout
[15:57:00.372] <TB3> INFO: PixTest:: pg_setup set to default.
[15:57:00.373] <TB3> INFO: 3 hot pixels found in step 1
[15:57:01.092] <TB3> INFO: Collecting data for 1 seconds...
[15:57:02.094] <TB3> INFO: Done with hot pixel readout
[15:57:08.083] <TB3> INFO: PixTest:: pg_setup set to default.
[15:57:08.083] <TB3> INFO: 1 hot pixels found in step 2
[15:57:08.802] <TB3> INFO: Collecting data for 1 seconds...
[15:57:09.803] <TB3> INFO: Done with hot pixel readout
[15:57:15.719] <TB3> INFO: PixTest:: pg_setup set to default.
[15:57:15.720] <TB3> INFO: 0 hot pixels found in step 3
[15:57:15.727] <TB3> INFO: In total 0 additional hot pixels could not be trimmed and have been masked.
[15:57:15.754] <TB3> INFO: PixTest::trimHotPixels() done
[15:57:15.754] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C0.dat
[15:57:15.760] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C1.dat
[15:57:15.766] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C2.dat
[15:57:15.772] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C3.dat
[15:57:15.778] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C4.dat
[15:57:15.783] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C5.dat
[15:57:15.789] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C6.dat
[15:57:15.795] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C7.dat
[15:57:15.801] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C8.dat
[15:57:15.807] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C9.dat
[15:57:15.813] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C10.dat
[15:57:15.819] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C11.dat
[15:57:15.825] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C12.dat
[15:57:15.831] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C13.dat
[15:57:15.837] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C14.dat
[15:57:15.843] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C15.dat
[15:57:15.848] <TB3> INFO: write masked pixels into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//defaultMaskFile.dat
[15:57:15.878] <TB3> INFO: enter test to run
[15:57:15.878] <TB3> INFO: test: no parameter change
[15:57:16.076] <TB3> QUIET: Connection to board 121 closed.
[15:57:16.156] <TB3> INFO: pXar: this is the end, my friend

[15:56:37.477] <TB3> INFO: *** Welcome to pxar ***
[15:56:37.477] <TB3> INFO: *** Today: 2016/04/11
[15:56:37.544] <TB3> INFO: *** Version: 9751
[15:56:37.544] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//dacParameters35_C15.dat
[15:56:37.545] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[15:56:37.545] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//defaultMaskFile.dat
[15:56:37.545] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C15.dat
[15:56:37.636] <TB3> INFO: clk: 4
[15:56:37.636] <TB3> INFO: ctr: 4
[15:56:37.636] <TB3> INFO: sda: 19
[15:56:37.636] <TB3> INFO: tin: 9
[15:56:37.636] <TB3> INFO: level: 15
[15:56:37.636] <TB3> INFO: triggerdelay: 0
[15:56:37.636] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[15:56:37.636] <TB3> INFO: Log level: INFO
[15:56:37.650] <TB3> INFO: Found DTB DTB_WWV6Z5
[15:56:37.667] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[15:56:37.669] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[15:56:37.671] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[15:56:39.233] <TB3> INFO: DUT info:
[15:56:39.233] <TB3> INFO: The DUT currently contains the following objects:
[15:56:39.233] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:56:39.233] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:56:39.233] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:56:39.233] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:56:39.233] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.233] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:39.634] <TB3> INFO: enter 'restricted' command line mode
[15:56:39.634] <TB3> INFO: enter test to run
[15:56:39.634] <TB3> INFO: test: delay setting parameters: ->5<-
[15:56:39.634] <TB3> INFO: delay test by 5 seconds...
[15:56:44.634] <TB3> INFO: enter test to run
[15:56:44.634] <TB3> INFO: test: highrate no parameter change
[15:56:44.634] <TB3> INFO: running: highrate
[15:56:44.634] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[15:56:44.846] <TB3> INFO: ----------------------------------------------------------------------
[15:56:44.846] <TB3> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:56:44.846] <TB3> INFO: ----------------------------------------------------------------------
[15:56:44.846] <TB3> INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:56:44.846] <TB3> INFO: edge/corner pixel THR is adjusted
[15:56:44.846] <TB3> INFO: PixTestHighRate::trimHotPixels: step 0...
[15:56:45.562] <TB3> INFO: Collecting data for 1 seconds...
[15:56:46.564] <TB3> INFO: Done with hot pixel readout
[15:56:52.650] <TB3> INFO: PixTest:: pg_setup set to default.
[15:56:52.651] <TB3> INFO: 3 hot pixels found in step 0
[15:56:53.370] <TB3> INFO: Collecting data for 1 seconds...
[15:56:54.372] <TB3> INFO: Done with hot pixel readout
[15:57:00.372] <TB3> INFO: PixTest:: pg_setup set to default.
[15:57:00.373] <TB3> INFO: 3 hot pixels found in step 1
[15:57:01.092] <TB3> INFO: Collecting data for 1 seconds...
[15:57:02.094] <TB3> INFO: Done with hot pixel readout
[15:57:08.083] <TB3> INFO: PixTest:: pg_setup set to default.
[15:57:08.083] <TB3> INFO: 1 hot pixels found in step 2
[15:57:08.802] <TB3> INFO: Collecting data for 1 seconds...
[15:57:09.803] <TB3> INFO: Done with hot pixel readout
[15:57:15.719] <TB3> INFO: PixTest:: pg_setup set to default.
[15:57:15.720] <TB3> INFO: 0 hot pixels found in step 3
[15:57:15.727] <TB3> INFO: In total 0 additional hot pixels could not be trimmed and have been masked.
[15:57:15.754] <TB3> INFO: PixTest::trimHotPixels() done
[15:57:15.754] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C0.dat
[15:57:15.760] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C1.dat
[15:57:15.766] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C2.dat
[15:57:15.772] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C3.dat
[15:57:15.778] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C4.dat
[15:57:15.783] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C5.dat
[15:57:15.789] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C6.dat
[15:57:15.795] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C7.dat
[15:57:15.801] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C8.dat
[15:57:15.807] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C9.dat
[15:57:15.813] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C10.dat
[15:57:15.819] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C11.dat
[15:57:15.825] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C12.dat
[15:57:15.831] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C13.dat
[15:57:15.837] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C14.dat
[15:57:15.843] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//trimParameters35_C15.dat
[15:57:15.848] <TB3> INFO: write masked pixels into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//002_RetrimHotPixels_150//defaultMaskFile.dat
[15:57:15.878] <TB3> INFO: enter test to run
[15:57:15.878] <TB3> INFO: test: no parameter change
[15:57:16.076] <TB3> QUIET: Connection to board 121 closed.
[15:57:16.156] <TB3> INFO: pXar: this is the end, my friend
[15:57:49.848] <TB3> INFO: *** Welcome to pxar ***
[15:57:49.849] <TB3> INFO: *** Today: 2016/04/11
[15:57:49.910] <TB3> INFO: *** Version: 9751
[15:57:49.910] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//dacParameters35_C15.dat
[15:57:49.910] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//tbmParameters_C0b.dat
[15:57:49.910] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//defaultMaskFile.dat
[15:57:49.910] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C15.dat
[15:57:49.980] <TB3> INFO: clk: 4
[15:57:49.980] <TB3> INFO: ctr: 4
[15:57:49.980] <TB3> INFO: sda: 19
[15:57:49.980] <TB3> INFO: tin: 9
[15:57:49.980] <TB3> INFO: level: 15
[15:57:49.980] <TB3> INFO: triggerdelay: 0
[15:57:49.980] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[15:57:49.980] <TB3> INFO: Log level: INFO
[15:57:49.992] <TB3> INFO: Found DTB DTB_WWV6Z5
[15:57:50.008] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[15:57:50.010] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[15:57:50.012] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[15:57:51.548] <TB3> INFO: DUT info:
[15:57:51.548] <TB3> INFO: The DUT currently contains the following objects:
[15:57:51.548] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:57:51.548] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:57:51.548] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:57:51.548] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:57:51.548] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.548] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:51.949] <TB3> INFO: enter 'restricted' command line mode
[15:57:51.949] <TB3> INFO: enter test to run
[15:57:51.949] <TB3> INFO: test: delay setting parameters: ->5<-
[15:57:51.949] <TB3> INFO: delay test by 5 seconds...
[15:57:56.949] <TB3> INFO: enter test to run
[15:57:56.949] <TB3> INFO: test: highrate no parameter change
[15:57:56.949] <TB3> INFO: running: highrate
[15:57:56.949] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//phCalibrationFitErr35_C15.dat
[15:57:57.081] <TB3> INFO: ----------------------------------------------------------------------
[15:57:57.081] <TB3> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:57:57.081] <TB3> INFO: ----------------------------------------------------------------------
[15:57:57.081] <TB3> INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:57:57.081] <TB3> INFO: edge/corner pixel THR is adjusted
[15:57:57.081] <TB3> INFO: PixTestHighRate::trimHotPixels: step 0...
[15:57:57.798] <TB3> INFO: Collecting data for 1 seconds...
[15:57:58.799] <TB3> INFO: Done with hot pixel readout
[15:58:02.440] <TB3> INFO: PixTest:: pg_setup set to default.
[15:58:02.441] <TB3> INFO: 0 hot pixels found in step 0
[15:58:02.444] <TB3> INFO: In total 0 additional hot pixels could not be trimmed and have been masked.
[15:58:02.474] <TB3> INFO: PixTest::trimHotPixels() done
[15:58:02.474] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C0.dat
[15:58:02.481] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C1.dat
[15:58:02.487] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C2.dat
[15:58:02.493] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C3.dat
[15:58:02.500] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C4.dat
[15:58:02.506] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C5.dat
[15:58:02.513] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C6.dat
[15:58:02.519] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C7.dat
[15:58:02.525] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C8.dat
[15:58:02.532] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C9.dat
[15:58:02.538] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C10.dat
[15:58:02.545] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C11.dat
[15:58:02.551] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C12.dat
[15:58:02.557] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C13.dat
[15:58:02.564] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C14.dat
[15:58:02.570] <TB3> INFO: write trim parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//trimParameters35_C15.dat
[15:58:02.577] <TB3> INFO: write masked pixels into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//003_RetrimHotPixels_50//defaultMaskFile.dat
[15:58:02.606] <TB3> INFO: enter test to run
[15:58:02.606] <TB3> INFO: test: no parameter change
[15:58:02.830] <TB3> QUIET: Connection to board 121 closed.
[15:58:02.910] <TB3> INFO: pXar: this is the end, my friend

[15:58:42.768] <TB3> INFO: *** Welcome to pxar ***
[15:58:42.768] <TB3> INFO: *** Today: 2016/04/11
[15:58:42.837] <TB3> INFO: *** Version: 9751
[15:58:42.837] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//004_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//004_HRData_50//dacParameters35_C15.dat
[15:58:42.838] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//004_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//004_HRData_50//tbmParameters_C0b.dat
[15:58:42.838] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//004_HRData_50//defaultMaskFile.dat
[15:58:42.838] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//004_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//004_HRData_50//trimParameters35_C15.dat
[15:58:42.933] <TB3> INFO: clk: 4
[15:58:42.933] <TB3> INFO: ctr: 4
[15:58:42.933] <TB3> INFO: sda: 19
[15:58:42.933] <TB3> INFO: tin: 9
[15:58:42.933] <TB3> INFO: level: 15
[15:58:42.933] <TB3> INFO: triggerdelay: 0
[15:58:42.933] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[15:58:42.933] <TB3> INFO: Log level: INFO
[15:58:42.946] <TB3> INFO: Found DTB DTB_WWV6Z5
[15:58:42.960] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[15:58:42.962] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[15:58:42.964] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[15:58:44.501] <TB3> INFO: DUT info:
[15:58:44.501] <TB3> INFO: The DUT currently contains the following objects:
[15:58:44.501] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:58:44.501] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:58:44.501] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:58:44.501] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:58:44.501] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.501] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:44.902] <TB3> INFO: enter 'restricted' command line mode
[15:58:44.902] <TB3> INFO: enter test to run
[15:58:44.902] <TB3> INFO: test: delay setting parameters: ->5<-
[15:58:44.902] <TB3> INFO: delay test by 5 seconds...
[15:58:49.902] <TB3> INFO: enter test to run
[15:58:49.902] <TB3> INFO: test: Xray no parameter change
[15:58:49.902] <TB3> INFO: running: xray
[15:58:49.902] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//004_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//004_HRData_50//phCalibrationFitErr35_C15.dat
[15:58:50.078] <TB3> INFO: ######################################################################
[15:58:50.078] <TB3> INFO: PixTestXray::doTest()
[15:58:50.078] <TB3> INFO: ######################################################################
[15:58:50.078] <TB3> INFO: ----------------------------------------------------------------------
[15:58:50.078] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:58:50.078] <TB3> INFO: ----------------------------------------------------------------------
[15:58:50.802] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:58:57.906] <TB3> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:59:26.349] <TB3> INFO: Resuming triggers.
[15:59:33.462] <TB3> INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[16:00:01.197] <TB3> INFO: Resuming triggers.
[16:00:08.310] <TB3> INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[16:00:35.927] <TB3> INFO: Resuming triggers.
[16:00:43.039] <TB3> INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[16:01:11.511] <TB3> INFO: Resuming triggers.
[16:01:18.619] <TB3> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[16:01:46.626] <TB3> INFO: Resuming triggers.
[16:01:53.735] <TB3> INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[16:02:22.581] <TB3> INFO: Resuming triggers.
[16:02:29.687] <TB3> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[16:02:58.208] <TB3> INFO: Resuming triggers.
[16:03:05.316] <TB3> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[16:03:32.964] <TB3> INFO: Resuming triggers.
[16:03:40.074] <TB3> INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[16:04:07.620] <TB3> INFO: Resuming triggers.
[16:04:14.731] <TB3> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[16:04:42.230] <TB3> INFO: Resuming triggers.
[16:04:49.341] <TB3> INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[16:05:17.323] <TB3> INFO: Resuming triggers.
[16:05:24.434] <TB3> INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[16:05:53.152] <TB3> INFO: Resuming triggers.
[16:06:00.266] <TB3> INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[16:06:28.001] <TB3> INFO: Resuming triggers.
[16:06:35.117] <TB3> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[16:07:02.964] <TB3> INFO: Resuming triggers.
[16:07:03.506] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[16:07:06.009] <TB3> INFO: PixTest:: pg_setup set to default.
[16:07:06.010] <TB3> INFO: PixTestXray::doPhRun() done
[16:07:06.010] <TB3> INFO: PixTestXray::doTest() done
[16:07:06.225] <TB3> INFO: enter test to run
[16:07:06.225] <TB3> INFO: test: no parameter change
[16:07:06.537] <TB3> QUIET: Connection to board 121 closed.
[16:07:06.616] <TB3> INFO: pXar: this is the end, my friend

[16:08:41.524] <TB3> INFO: *** Welcome to pxar ***
[16:08:41.524] <TB3> INFO: *** Today: 2016/04/11
[16:08:41.586] <TB3> INFO: *** Version: 9751
[16:08:41.586] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//005_HRData_150//dacParameters35_C15.dat
[16:08:41.587] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//005_HRData_150//tbmParameters_C0b.dat
[16:08:41.587] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//005_HRData_150//defaultMaskFile.dat
[16:08:41.587] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//005_HRData_150//trimParameters35_C15.dat
[16:08:41.656] <TB3> INFO: clk: 4
[16:08:41.656] <TB3> INFO: ctr: 4
[16:08:41.656] <TB3> INFO: sda: 19
[16:08:41.656] <TB3> INFO: tin: 9
[16:08:41.656] <TB3> INFO: level: 15
[16:08:41.656] <TB3> INFO: triggerdelay: 0
[16:08:41.656] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[16:08:41.656] <TB3> INFO: Log level: INFO
[16:08:41.668] <TB3> INFO: Found DTB DTB_WWV6Z5
[16:08:41.684] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[16:08:41.686] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[16:08:41.688] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[16:08:43.222] <TB3> INFO: DUT info:
[16:08:43.222] <TB3> INFO: The DUT currently contains the following objects:
[16:08:43.222] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:08:43.222] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:08:43.222] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:08:43.222] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:08:43.222] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.222] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:43.623] <TB3> INFO: enter 'restricted' command line mode
[16:08:43.623] <TB3> INFO: enter test to run
[16:08:43.623] <TB3> INFO: test: delay setting parameters: ->5<-
[16:08:43.623] <TB3> INFO: delay test by 5 seconds...
[16:08:48.623] <TB3> INFO: enter test to run
[16:08:48.623] <TB3> INFO: test: Xray no parameter change
[16:08:48.623] <TB3> INFO: running: xray
[16:08:48.624] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//005_HRData_150//phCalibrationFitErr35_C15.dat
[16:08:48.759] <TB3> INFO: ######################################################################
[16:08:48.759] <TB3> INFO: PixTestXray::doTest()
[16:08:48.759] <TB3> INFO: ######################################################################
[16:08:48.759] <TB3> INFO: ----------------------------------------------------------------------
[16:08:48.759] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:08:48.759] <TB3> INFO: ----------------------------------------------------------------------
[16:08:49.482] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:08:53.501] <TB3> INFO: run duration 4 seconds, buffer almost full (81%), pausing triggers.
[16:09:22.456] <TB3> INFO: Resuming triggers.
[16:09:26.485] <TB3> INFO: run duration 8 seconds, buffer almost full (81%), pausing triggers.
[16:09:55.433] <TB3> INFO: Resuming triggers.
[16:09:59.462] <TB3> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[16:10:28.765] <TB3> INFO: Resuming triggers.
[16:10:32.790] <TB3> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[16:11:01.837] <TB3> INFO: Resuming triggers.
[16:11:05.864] <TB3> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[16:11:34.308] <TB3> INFO: Resuming triggers.
[16:11:38.334] <TB3> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[16:12:07.117] <TB3> INFO: Resuming triggers.
[16:12:11.143] <TB3> INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[16:12:39.513] <TB3> INFO: Resuming triggers.
[16:12:43.538] <TB3> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[16:13:12.458] <TB3> INFO: Resuming triggers.
[16:13:16.483] <TB3> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[16:13:45.030] <TB3> INFO: Resuming triggers.
[16:13:49.052] <TB3> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[16:14:17.895] <TB3> INFO: Resuming triggers.
[16:14:21.924] <TB3> INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[16:14:50.449] <TB3> INFO: Resuming triggers.
[16:14:54.482] <TB3> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[16:15:23.490] <TB3> INFO: Resuming triggers.
[16:15:27.522] <TB3> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[16:15:56.206] <TB3> INFO: Resuming triggers.
[16:16:00.238] <TB3> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[16:16:28.620] <TB3> INFO: Resuming triggers.
[16:16:32.650] <TB3> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[16:17:01.866] <TB3> INFO: Resuming triggers.
[16:17:05.898] <TB3> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[16:17:35.224] <TB3> INFO: Resuming triggers.
[16:17:39.254] <TB3> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[16:18:08.748] <TB3> INFO: Resuming triggers.
[16:18:12.777] <TB3> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[16:18:42.004] <TB3> INFO: Resuming triggers.
[16:18:46.032] <TB3> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[16:19:14.638] <TB3> INFO: Resuming triggers.
[16:19:18.663] <TB3> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[16:19:47.121] <TB3> INFO: Resuming triggers.
[16:19:51.148] <TB3> INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[16:20:20.011] <TB3> INFO: Resuming triggers.
[16:20:24.038] <TB3> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[16:20:52.920] <TB3> INFO: Resuming triggers.
[16:20:56.939] <TB3> INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[16:21:25.259] <TB3> INFO: Resuming triggers.
[16:21:29.283] <TB3> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[16:21:58.729] <TB3> INFO: Resuming triggers.
[16:22:02.158] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[16:22:26.180] <TB3> INFO: PixTest:: pg_setup set to default.
[16:22:26.181] <TB3> INFO: PixTestXray::doPhRun() done
[16:22:26.181] <TB3> INFO: PixTestXray::doTest() done
[16:22:26.368] <TB3> INFO: enter test to run
[16:22:26.368] <TB3> INFO: test: no parameter change
[16:22:26.596] <TB3> QUIET: Connection to board 121 closed.
[16:22:26.676] <TB3> INFO: pXar: this is the end, my friend

[16:25:44.211] <TB3> INFO: *** Welcome to pxar ***
[16:25:44.211] <TB3> INFO: *** Today: 2016/04/11
[16:25:44.276] <TB3> INFO: *** Version: 9751
[16:25:44.276] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//006_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//006_HRSCurves_100//dacParameters35_C15.dat
[16:25:44.277] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//006_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//006_HRSCurves_100//tbmParameters_C0b.dat
[16:25:44.277] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//006_HRSCurves_100//defaultMaskFile.dat
[16:25:44.277] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//006_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//006_HRSCurves_100//trimParameters35_C15.dat
[16:25:44.364] <TB3> INFO: clk: 4
[16:25:44.364] <TB3> INFO: ctr: 4
[16:25:44.365] <TB3> INFO: sda: 19
[16:25:44.365] <TB3> INFO: tin: 9
[16:25:44.365] <TB3> INFO: level: 15
[16:25:44.365] <TB3> INFO: triggerdelay: 0
[16:25:44.365] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[16:25:44.365] <TB3> INFO: Log level: INFO
[16:25:44.378] <TB3> INFO: Found DTB DTB_WWV6Z5
[16:25:44.392] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[16:25:44.394] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[16:25:44.396] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[16:25:45.952] <TB3> INFO: DUT info:
[16:25:45.952] <TB3> INFO: The DUT currently contains the following objects:
[16:25:45.952] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:25:45.952] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:25:45.952] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:25:45.952] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:25:45.952] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:45.953] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:46.354] <TB3> INFO: enter 'restricted' command line mode
[16:25:46.354] <TB3> INFO: enter test to run
[16:25:46.354] <TB3> INFO: test: Highrate no parameter change
[16:25:46.354] <TB3> INFO: running: highrate
[16:25:46.354] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//006_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//006_HRSCurves_100//phCalibrationFitErr35_C15.dat
[16:25:46.507] <TB3> INFO: ----------------------------------------------------------------------
[16:25:46.507] <TB3> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[16:25:46.507] <TB3> INFO: ----------------------------------------------------------------------
[16:25:46.513] <TB3> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 15 .. 75 (20/-1) hits flags = 896 (plus default)
[16:25:46.524] <TB3> INFO: dacScan step from 15 .. 34
[16:25:46.524] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:25:46.524] <TB3> INFO: run 1 of 1
[16:25:47.021] <TB3> INFO: Expecting 4160000 events.
[16:26:20.272] <TB3> INFO: 498050 events read in total (32848ms).
[16:26:53.597] <TB3> INFO: 997650 events read in total (66173ms).
[16:27:27.080] <TB3> INFO: 1497900 events read in total (99656ms).
[16:28:00.298] <TB3> INFO: 1998400 events read in total (132874ms).
[16:28:33.186] <TB3> INFO: 2498400 events read in total (165762ms).
[16:29:06.304] <TB3> INFO: 2997650 events read in total (198880ms).
[16:29:39.337] <TB3> INFO: 3495950 events read in total (231913ms).
[16:30:11.888] <TB3> INFO: 3993900 events read in total (264464ms).
[16:30:22.911] <TB3> INFO: 4160000 events read in total (275487ms).
[16:30:23.084] <TB3> INFO: Test took 276560ms.
[16:30:29.020] <TB3> INFO: dacScan step from 35 .. 54
[16:30:29.020] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:29.020] <TB3> INFO: run 1 of 1
[16:30:29.526] <TB3> INFO: Expecting 4160000 events.
[16:31:01.449] <TB3> INFO: 402200 events read in total (31521ms).
[16:31:32.206] <TB3> INFO: 805100 events read in total (62278ms).
[16:32:03.155] <TB3> INFO: 1206950 events read in total (93227ms).
[16:32:34.217] <TB3> INFO: 1607850 events read in total (124289ms).
[16:33:04.689] <TB3> INFO: 2007150 events read in total (154761ms).
[16:33:35.927] <TB3> INFO: 2404400 events read in total (185999ms).
[16:34:07.017] <TB3> INFO: 2798650 events read in total (217089ms).
[16:34:38.216] <TB3> INFO: 3189800 events read in total (248288ms).
[16:35:08.967] <TB3> INFO: 3578500 events read in total (279039ms).
[16:35:39.576] <TB3> INFO: 3965750 events read in total (309648ms).
[16:35:54.936] <TB3> INFO: 4160000 events read in total (325008ms).
[16:35:55.146] <TB3> INFO: Test took 326125ms.
[16:36:00.869] <TB3> INFO: dacScan step from 55 .. 74
[16:36:00.869] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:36:00.869] <TB3> INFO: run 1 of 1
[16:36:01.352] <TB3> INFO: Expecting 4160000 events.
[16:36:31.945] <TB3> INFO: 361250 events read in total (30191ms).
[16:37:02.549] <TB3> INFO: 723300 events read in total (60795ms).
[16:37:33.631] <TB3> INFO: 1085200 events read in total (91877ms).
[16:38:04.060] <TB3> INFO: 1447200 events read in total (122306ms).
[16:38:34.338] <TB3> INFO: 1809050 events read in total (152584ms).
[16:39:04.916] <TB3> INFO: 2170950 events read in total (183162ms).
[16:39:34.940] <TB3> INFO: 2532850 events read in total (213186ms).
[16:40:05.408] <TB3> INFO: 2894700 events read in total (243654ms).
[16:40:35.726] <TB3> INFO: 3256550 events read in total (273973ms).
[16:41:06.079] <TB3> INFO: 3618650 events read in total (304325ms).
[16:41:36.902] <TB3> INFO: 3980450 events read in total (335148ms).
[16:41:52.391] <TB3> INFO: 4160000 events read in total (350637ms).
[16:41:52.608] <TB3> INFO: Test took 351739ms.
[16:41:58.610] <TB3> INFO: dacScan step from 75 .. 75
[16:41:58.610] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:41:58.610] <TB3> INFO: run 1 of 1
[16:41:59.090] <TB3> INFO: Expecting 208000 events.
[16:42:18.183] <TB3> INFO: 208000 events read in total (18692ms).
[16:42:18.189] <TB3> INFO: Test took 19578ms.
[16:42:18.480] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:19.623] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:20.692] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:21.717] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:22.764] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:23.804] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:24.864] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:25.908] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:26.954] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:28.006] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:29.063] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:30.120] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:31.168] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:32.212] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:33.237] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:34.260] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:42:35.286] <TB3> INFO: PixTest:: pg_setup set to default.
[16:42:35.289] <TB3> INFO: vcal mean: 36.95 37.61 36.76 39.65 39.91 40.54 41.12 40.35 41.19 41.57 41.33 39.91 39.79 38.11 37.86 38.02
[16:42:35.289] <TB3> INFO: vcal RMS: 3.16 1.01 0.83 1.32 5.27 1.16 1.32 1.39 1.37 1.56 1.45 1.34 1.45 1.10 0.97 0.96
[16:42:35.289] <TB3> INFO: number of X-ray hits detected: 142516 147605 128263 251450 268879 291457 302153 294917 338517 354290 344018 273277 268953 158080 165069 159073
[16:42:35.289] <TB3> 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:42:35.289] <TB3> INFO: X-ray hit rate [MHz/cm2]: 41.8 43.3 37.6 73.7 78.8 85.4 88.6 86.4 99.2 103.8 100.8 80.1 78.8 46.3 48.4 46.6
[16:42:35.289] <TB3> INFO: PixTestHighRate::doXNoiseMaps() done
[16:42:35.411] <TB3> INFO: enter test to run
[16:42:35.411] <TB3> INFO: test: no parameter change
[16:42:35.584] <TB3> QUIET: Connection to board 121 closed.
[16:42:35.676] <TB3> INFO: pXar: this is the end, my friend

[17:04:37.412] <TB3> INFO: *** Welcome to pxar ***
[17:04:37.412] <TB3> INFO: *** Today: 2016/04/11
[17:04:37.514] <TB3> INFO: *** Version: 9751
[17:04:37.514] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[17:04:37.515] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[17:04:37.515] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[17:04:37.515] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[17:04:37.609] <TB3> INFO: clk: 4
[17:04:37.609] <TB3> INFO: ctr: 4
[17:04:37.609] <TB3> INFO: sda: 19
[17:04:37.609] <TB3> INFO: tin: 9
[17:04:37.609] <TB3> INFO: level: 15
[17:04:37.609] <TB3> INFO: triggerdelay: 0
[17:04:37.609] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[17:04:37.609] <TB3> INFO: Log level: INFO
[17:04:37.623] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:04:37.639] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:04:37.641] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[17:04:37.643] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[17:04:39.199] <TB3> INFO: DUT info:
[17:04:39.199] <TB3> INFO: The DUT currently contains the following objects:
[17:04:39.199] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:04:39.199] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:04:39.199] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:04:39.199] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:04:39.199] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.199] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.199] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.200] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.200] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.200] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.200] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.200] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.200] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.200] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.200] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.200] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.200] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.200] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.200] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.200] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:39.601] <TB3> INFO: enter 'restricted' command line mode
[17:04:39.601] <TB3> INFO: enter test to run
[17:04:39.601] <TB3> INFO: test: HighRate no parameter change
[17:04:39.601] <TB3> INFO: running: highrate
[17:04:39.601] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[17:04:39.737] <TB3> INFO: ----------------------------------------------------------------------
[17:04:39.737] <TB3> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:04:39.737] <TB3> INFO: ----------------------------------------------------------------------
[17:04:39.830] <TB3> INFO: Expecting 768 events.
[17:04:40.821] <TB3> INFO: 768 events read in total (433ms).
[17:04:40.884] <TB3> INFO: Test took 1142ms.
[17:04:41.380] <TB3> INFO: Expecting 41600 events.
[17:04:44.862] <TB3> INFO: 41600 events read in total (3081ms).
[17:04:44.864] <TB3> INFO: Test took 3977ms.
[17:04:45.437] <TB3> INFO: Expecting 41600 events.
[17:04:48.936] <TB3> INFO: 41600 events read in total (3097ms).
[17:04:48.938] <TB3> INFO: Test took 3986ms.
[17:04:49.519] <TB3> INFO: Expecting 41600 events.
[17:04:53.117] <TB3> INFO: 41600 events read in total (3196ms).
[17:04:53.119] <TB3> INFO: Test took 4085ms.
[17:04:53.708] <TB3> INFO: Expecting 41600 events.
[17:04:57.308] <TB3> INFO: 41600 events read in total (3199ms).
[17:04:57.310] <TB3> INFO: Test took 4090ms.
[17:04:57.879] <TB3> INFO: Expecting 41600 events.
[17:05:01.437] <TB3> INFO: 41600 events read in total (3156ms).
[17:05:01.439] <TB3> INFO: Test took 4040ms.
[17:05:02.012] <TB3> INFO: Expecting 41600 events.
[17:05:05.625] <TB3> INFO: 41600 events read in total (3212ms).
[17:05:05.628] <TB3> INFO: Test took 4099ms.
[17:05:06.224] <TB3> INFO: Expecting 41600 events.
[17:05:09.861] <TB3> INFO: 41600 events read in total (3235ms).
[17:05:09.863] <TB3> INFO: Test took 4128ms.
[17:05:10.436] <TB3> INFO: Expecting 41600 events.
[17:05:14.163] <TB3> INFO: 41600 events read in total (3325ms).
[17:05:14.165] <TB3> INFO: Test took 4211ms.
[17:05:14.770] <TB3> INFO: Expecting 41600 events.
[17:05:18.442] <TB3> INFO: 41600 events read in total (3270ms).
[17:05:18.444] <TB3> INFO: Test took 4176ms.
[17:05:19.055] <TB3> INFO: Expecting 41600 events.
[17:05:22.707] <TB3> INFO: 41600 events read in total (3250ms).
[17:05:22.709] <TB3> INFO: Test took 4156ms.
[17:05:23.318] <TB3> INFO: Expecting 41600 events.
[17:05:26.883] <TB3> INFO: 41600 events read in total (3163ms).
[17:05:26.885] <TB3> INFO: Test took 4073ms.
[17:05:27.491] <TB3> INFO: Expecting 41600 events.
[17:05:31.074] <TB3> INFO: 41600 events read in total (3182ms).
[17:05:31.076] <TB3> INFO: Test took 4088ms.
[17:05:31.648] <TB3> INFO: Expecting 41600 events.
[17:05:35.181] <TB3> INFO: 41600 events read in total (3132ms).
[17:05:35.183] <TB3> INFO: Test took 4017ms.
[17:05:35.782] <TB3> INFO: Expecting 41600 events.
[17:05:39.361] <TB3> INFO: 41600 events read in total (3177ms).
[17:05:39.362] <TB3> INFO: Test took 4079ms.
[17:05:39.930] <TB3> INFO: Expecting 41600 events.
[17:05:43.447] <TB3> INFO: 41600 events read in total (3115ms).
[17:05:43.449] <TB3> INFO: Test took 3998ms.
[17:05:44.022] <TB3> INFO: Expecting 41600 events.
[17:05:47.596] <TB3> INFO: 41600 events read in total (3172ms).
[17:05:47.598] <TB3> INFO: Test took 4061ms.
[17:05:48.166] <TB3> INFO: Expecting 41600 events.
[17:05:51.776] <TB3> INFO: 41600 events read in total (3209ms).
[17:05:51.778] <TB3> INFO: Test took 4093ms.
[17:05:52.410] <TB3> INFO: Expecting 41600 events.
[17:05:55.986] <TB3> INFO: 41600 events read in total (3174ms).
[17:05:55.987] <TB3> INFO: Test took 4098ms.
[17:05:56.554] <TB3> INFO: Expecting 41600 events.
[17:06:00.196] <TB3> INFO: 41600 events read in total (3240ms).
[17:06:00.198] <TB3> INFO: Test took 4123ms.
[17:06:00.764] <TB3> INFO: Expecting 41600 events.
[17:06:04.066] <TB3> INFO: 41600 events read in total (2900ms).
[17:06:04.067] <TB3> INFO: Test took 3782ms.
[17:06:04.390] <TB3> INFO: enter test to run
[17:06:04.390] <TB3> INFO: test: Pretest no parameter change
[17:06:04.390] <TB3> INFO: running: pretest
[17:06:04.390] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[17:06:04.390] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[17:06:04.390] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[17:06:04.390] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[17:06:04.391] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[17:06:04.391] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[17:06:04.391] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[17:06:04.391] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[17:06:04.391] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[17:06:04.391] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[17:06:04.391] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[17:06:04.391] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[17:06:04.391] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[17:06:04.391] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[17:06:04.391] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[17:06:04.392] <TB3> INFO: write dac parameters into /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[17:06:04.392] <TB3> INFO: enter test to run
[17:06:04.392] <TB3> INFO: test: no parameter change
[17:06:04.563] <TB3> QUIET: Connection to board 121 closed.
[17:06:04.642] <TB3> INFO: pXar: this is the end, my friend

[17:06:42.345] <TB3> INFO: *** Welcome to pxar ***
[17:06:42.345] <TB3> INFO: *** Today: 2016/04/11
[17:06:42.404] <TB3> INFO: *** Version: 9751
[17:06:42.404] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//012_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//012_HREfficiency_50//dacParameters35_C15.dat
[17:06:42.405] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//012_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//012_HREfficiency_50//tbmParameters_C0b.dat
[17:06:42.405] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//012_HREfficiency_50//defaultMaskFile.dat
[17:06:42.405] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//012_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//012_HREfficiency_50//trimParameters35_C15.dat
[17:06:42.478] <TB3> INFO: clk: 4
[17:06:42.478] <TB3> INFO: ctr: 4
[17:06:42.478] <TB3> INFO: sda: 19
[17:06:42.478] <TB3> INFO: tin: 9
[17:06:42.478] <TB3> INFO: level: 15
[17:06:42.478] <TB3> INFO: triggerdelay: 0
[17:06:42.478] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[17:06:42.478] <TB3> INFO: Log level: INFO
[17:06:42.491] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:06:42.505] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:06:42.507] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[17:06:42.509] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[17:06:44.070] <TB3> INFO: DUT info:
[17:06:44.070] <TB3> INFO: The DUT currently contains the following objects:
[17:06:44.070] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:06:44.070] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:06:44.070] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:06:44.070] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:06:44.070] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.070] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:44.471] <TB3> INFO: enter 'restricted' command line mode
[17:06:44.471] <TB3> INFO: enter test to run
[17:06:44.471] <TB3> INFO: test: PixelAlive no parameter change
[17:06:44.471] <TB3> INFO: running: pixelalive
[17:06:44.474] <TB3> INFO: ######################################################################
[17:06:44.474] <TB3> INFO: PixTestAlive::doTest()
[17:06:44.474] <TB3> INFO: ######################################################################
[17:06:44.476] <TB3> INFO: ----------------------------------------------------------------------
[17:06:44.476] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:06:44.476] <TB3> INFO: ----------------------------------------------------------------------
[17:06:44.737] <TB3> INFO: Expecting 208000 events.
[17:06:55.864] <TB3> INFO: 208000 events read in total (10569ms).
[17:06:55.865] <TB3> INFO: Test took 11388ms.
[17:06:56.094] <TB3> INFO: PixTestAlive::aliveTest() done
[17:06:56.094] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 1 0 0 0
[17:06:56.095] <TB3> INFO: ----------------------------------------------------------------------
[17:06:56.095] <TB3> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:06:56.095] <TB3> INFO: ----------------------------------------------------------------------
[17:06:56.363] <TB3> INFO: Expecting 208000 events.
[17:07:03.719] <TB3> INFO: 208000 events read in total (6799ms).
[17:07:03.719] <TB3> INFO: Test took 7623ms.
[17:07:03.719] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:07:03.959] <TB3> INFO: PixTestAlive::maskTest() done
[17:07:03.959] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:07:03.961] <TB3> INFO: ----------------------------------------------------------------------
[17:07:03.961] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:07:03.961] <TB3> INFO: ----------------------------------------------------------------------
[17:07:04.229] <TB3> INFO: Expecting 208000 events.
[17:07:15.137] <TB3> INFO: 208000 events read in total (10352ms).
[17:07:15.138] <TB3> INFO: Test took 11176ms.
[17:07:15.333] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[17:07:15.333] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:07:15.334] <TB3> INFO: PixTestAlive::doTest() done, duration: 30 seconds
[17:07:15.364] <TB3> INFO: enter test to run
[17:07:15.364] <TB3> INFO: test: delay setting parameters: ->2<-
[17:07:15.364] <TB3> INFO: delay test by 2 seconds...
[17:07:17.364] <TB3> INFO: enter test to run
[17:07:17.364] <TB3> INFO: test: HighRate no parameter change
[17:07:17.364] <TB3> INFO: running: highrate
[17:07:17.364] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//012_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//012_HREfficiency_50//phCalibrationFitErr35_C15.dat
[17:07:17.493] <TB3> INFO: ----------------------------------------------------------------------
[17:07:17.493] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:07:17.493] <TB3> INFO: ----------------------------------------------------------------------
[17:07:17.975] <TB3> INFO: Expecting 208000 events.
[17:07:31.996] <TB3> INFO: 208000 events read in total (13619ms).
[17:07:32.001] <TB3> INFO: Test took 14505ms.
[17:07:32.446] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 3 0 0 0 0 1 0 0 1 0 0 0
[17:07:32.446] <TB3> INFO: number of red-efficiency pixels: 212 86 65 185 357 202 226 200 231 245 239 185 199 121 99 99
[17:07:32.446] <TB3> INFO: number of X-ray hits detected: 67485 70427 61626 124128 133152 143749 150186 146798 167344 176747 170791 135271 134203 77095 80605 77916
[17:07:32.446] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:07:32.446] <TB3> INFO: number of Vcal hits detected: 207639 207914 207933 207785 200478 207794 207759 207794 207762 207694 207754 207808 207745 207872 207900 207899
[17:07:32.446] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.8 100.0 100.0 99.9 96.3 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[17:07:32.446] <TB3> INFO: Vcal hit overall efficiency (%): 99.8 100.0 100.0 99.9 96.4 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[17:07:32.446] <TB3> INFO: X-ray hit rate [MHz/cm2]: 19.8 20.6 18.1 36.4 39.0 42.1 44.0 43.0 49.0 51.8 50.1 39.6 39.3 22.6 23.6 22.8
[17:07:32.446] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[17:07:32.472] <TB3> INFO: PixTest:: pg_setup set to default.
[17:07:32.480] <TB3> INFO: enter test to run
[17:07:32.480] <TB3> INFO: test: no parameter change
[17:07:32.696] <TB3> QUIET: Connection to board 121 closed.
[17:07:32.776] <TB3> INFO: pXar: this is the end, my friend

[17:08:07.989] <TB3> INFO: *** Welcome to pxar ***
[17:08:07.989] <TB3> INFO: *** Today: 2016/04/11
[17:08:08.052] <TB3> INFO: *** Version: 9751
[17:08:08.052] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//013_HREfficiency_75//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//013_HREfficiency_75//dacParameters35_C15.dat
[17:08:08.052] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//013_HREfficiency_75//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//013_HREfficiency_75//tbmParameters_C0b.dat
[17:08:08.052] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//013_HREfficiency_75//defaultMaskFile.dat
[17:08:08.052] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//013_HREfficiency_75//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//013_HREfficiency_75//trimParameters35_C15.dat
[17:08:08.125] <TB3> INFO: clk: 4
[17:08:08.125] <TB3> INFO: ctr: 4
[17:08:08.125] <TB3> INFO: sda: 19
[17:08:08.125] <TB3> INFO: tin: 9
[17:08:08.125] <TB3> INFO: level: 15
[17:08:08.125] <TB3> INFO: triggerdelay: 0
[17:08:08.125] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[17:08:08.125] <TB3> INFO: Log level: INFO
[17:08:08.138] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:08:08.153] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:08:08.155] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[17:08:08.156] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[17:08:09.694] <TB3> INFO: DUT info:
[17:08:09.694] <TB3> INFO: The DUT currently contains the following objects:
[17:08:09.694] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:08:09.694] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:08:09.694] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:08:09.694] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:08:09.694] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:09.694] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:10.095] <TB3> INFO: enter 'restricted' command line mode
[17:08:10.095] <TB3> INFO: enter test to run
[17:08:10.095] <TB3> INFO: test: PixelAlive no parameter change
[17:08:10.095] <TB3> INFO: running: pixelalive
[17:08:10.098] <TB3> INFO: ######################################################################
[17:08:10.098] <TB3> INFO: PixTestAlive::doTest()
[17:08:10.098] <TB3> INFO: ######################################################################
[17:08:10.099] <TB3> INFO: ----------------------------------------------------------------------
[17:08:10.099] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:08:10.099] <TB3> INFO: ----------------------------------------------------------------------
[17:08:10.427] <TB3> INFO: Expecting 208000 events.
[17:08:21.641] <TB3> INFO: 208000 events read in total (10656ms).
[17:08:21.642] <TB3> INFO: Test took 11542ms.
[17:08:21.862] <TB3> INFO: PixTestAlive::aliveTest() done
[17:08:21.862] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 1 0 0 0
[17:08:21.864] <TB3> INFO: ----------------------------------------------------------------------
[17:08:21.864] <TB3> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:08:21.864] <TB3> INFO: ----------------------------------------------------------------------
[17:08:22.136] <TB3> INFO: Expecting 208000 events.
[17:08:29.612] <TB3> INFO: 208000 events read in total (6920ms).
[17:08:29.613] <TB3> INFO: Test took 7748ms.
[17:08:29.613] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:08:29.854] <TB3> INFO: PixTestAlive::maskTest() done
[17:08:29.854] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:08:29.855] <TB3> INFO: ----------------------------------------------------------------------
[17:08:29.855] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:08:29.855] <TB3> INFO: ----------------------------------------------------------------------
[17:08:30.116] <TB3> INFO: Expecting 208000 events.
[17:08:41.235] <TB3> INFO: 208000 events read in total (10563ms).
[17:08:41.236] <TB3> INFO: Test took 11380ms.
[17:08:41.434] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[17:08:41.434] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:08:41.434] <TB3> INFO: PixTestAlive::doTest() done, duration: 31 seconds
[17:08:41.472] <TB3> INFO: enter test to run
[17:08:41.472] <TB3> INFO: test: delay setting parameters: ->2<-
[17:08:41.472] <TB3> INFO: delay test by 2 seconds...
[17:08:43.472] <TB3> INFO: enter test to run
[17:08:43.472] <TB3> INFO: test: HighRate no parameter change
[17:08:43.472] <TB3> INFO: running: highrate
[17:08:43.473] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//013_HREfficiency_75//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//013_HREfficiency_75//phCalibrationFitErr35_C15.dat
[17:08:43.651] <TB3> INFO: ----------------------------------------------------------------------
[17:08:43.651] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:08:43.651] <TB3> INFO: ----------------------------------------------------------------------
[17:08:44.134] <TB3> INFO: Expecting 208000 events.
[17:09:00.481] <TB3> INFO: 208000 events read in total (15946ms).
[17:09:00.487] <TB3> INFO: Test took 16833ms.
[17:09:01.058] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 23 0 0 0 0 1 0 0 1 0 0 0
[17:09:01.058] <TB3> INFO: number of red-efficiency pixels: 281 139 140 333 522 385 386 453 414 542 482 323 349 209 157 193
[17:09:01.058] <TB3> INFO: number of X-ray hits detected: 101223 106117 92452 182917 194944 211388 220763 217382 246111 257687 251083 198143 196138 114036 119381 115345
[17:09:01.058] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:09:01.058] <TB3> INFO: number of Vcal hits detected: 206617 207857 207854 207624 199891 207597 207571 207519 207556 207355 207474 207661 207578 207780 207842 207802
[17:09:01.058] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.3 99.9 99.9 99.8 96.5 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.9 99.9 99.9
[17:09:01.058] <TB3> INFO: Vcal hit overall efficiency (%): 99.3 99.9 99.9 99.8 96.1 99.8 99.8 99.8 99.8 99.7 99.7 99.8 99.8 99.9 99.9 99.9
[17:09:01.058] <TB3> INFO: X-ray hit rate [MHz/cm2]: 29.7 31.1 27.1 53.6 57.1 62.0 64.7 63.7 72.1 75.5 73.6 58.1 57.5 33.4 35.0 33.8
[17:09:01.058] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[17:09:01.084] <TB3> INFO: PixTest:: pg_setup set to default.
[17:09:01.092] <TB3> INFO: enter test to run
[17:09:01.092] <TB3> INFO: test: no parameter change
[17:09:01.309] <TB3> QUIET: Connection to board 121 closed.
[17:09:01.388] <TB3> INFO: pXar: this is the end, my friend

[17:09:40.346] <TB3> INFO: *** Welcome to pxar ***
[17:09:40.346] <TB3> INFO: *** Today: 2016/04/11
[17:09:40.407] <TB3> INFO: *** Version: 9751
[17:09:40.407] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//014_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//014_HREfficiency_100//dacParameters35_C15.dat
[17:09:40.407] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//014_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//014_HREfficiency_100//tbmParameters_C0b.dat
[17:09:40.408] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//014_HREfficiency_100//defaultMaskFile.dat
[17:09:40.408] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//014_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//014_HREfficiency_100//trimParameters35_C15.dat
[17:09:40.497] <TB3> INFO: clk: 4
[17:09:40.497] <TB3> INFO: ctr: 4
[17:09:40.497] <TB3> INFO: sda: 19
[17:09:40.497] <TB3> INFO: tin: 9
[17:09:40.497] <TB3> INFO: level: 15
[17:09:40.497] <TB3> INFO: triggerdelay: 0
[17:09:40.497] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[17:09:40.497] <TB3> INFO: Log level: INFO
[17:09:40.510] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:09:40.525] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:09:40.527] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[17:09:40.528] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[17:09:42.082] <TB3> INFO: DUT info:
[17:09:42.082] <TB3> INFO: The DUT currently contains the following objects:
[17:09:42.082] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:09:42.082] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:09:42.082] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:09:42.082] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:09:42.082] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.082] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:42.483] <TB3> INFO: enter 'restricted' command line mode
[17:09:42.483] <TB3> INFO: enter test to run
[17:09:42.483] <TB3> INFO: test: PixelAlive no parameter change
[17:09:42.483] <TB3> INFO: running: pixelalive
[17:09:42.486] <TB3> INFO: ######################################################################
[17:09:42.486] <TB3> INFO: PixTestAlive::doTest()
[17:09:42.486] <TB3> INFO: ######################################################################
[17:09:42.488] <TB3> INFO: ----------------------------------------------------------------------
[17:09:42.488] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:09:42.488] <TB3> INFO: ----------------------------------------------------------------------
[17:09:42.784] <TB3> INFO: Expecting 208000 events.
[17:09:53.990] <TB3> INFO: 208000 events read in total (10648ms).
[17:09:53.992] <TB3> INFO: Test took 11503ms.
[17:09:54.215] <TB3> INFO: PixTestAlive::aliveTest() done
[17:09:54.216] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 1 0 0 0
[17:09:54.217] <TB3> INFO: ----------------------------------------------------------------------
[17:09:54.217] <TB3> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:09:54.217] <TB3> INFO: ----------------------------------------------------------------------
[17:09:54.503] <TB3> INFO: Expecting 208000 events.
[17:10:01.835] <TB3> INFO: 208000 events read in total (6776ms).
[17:10:01.836] <TB3> INFO: Test took 7617ms.
[17:10:01.836] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:10:02.071] <TB3> INFO: PixTestAlive::maskTest() done
[17:10:02.071] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:10:02.072] <TB3> INFO: ----------------------------------------------------------------------
[17:10:02.072] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:10:02.072] <TB3> INFO: ----------------------------------------------------------------------
[17:10:02.335] <TB3> INFO: Expecting 208000 events.
[17:10:13.479] <TB3> INFO: 208000 events read in total (10587ms).
[17:10:13.480] <TB3> INFO: Test took 11407ms.
[17:10:13.674] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[17:10:13.674] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:10:13.674] <TB3> INFO: PixTestAlive::doTest() done, duration: 31 seconds
[17:10:13.714] <TB3> INFO: enter test to run
[17:10:13.714] <TB3> INFO: test: delay setting parameters: ->2<-
[17:10:13.714] <TB3> INFO: delay test by 2 seconds...
[17:10:15.714] <TB3> INFO: enter test to run
[17:10:15.714] <TB3> INFO: test: HighRate no parameter change
[17:10:15.714] <TB3> INFO: running: highrate
[17:10:15.715] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//014_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//014_HREfficiency_100//phCalibrationFitErr35_C15.dat
[17:10:15.854] <TB3> INFO: ----------------------------------------------------------------------
[17:10:15.854] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:10:15.854] <TB3> INFO: ----------------------------------------------------------------------
[17:10:16.336] <TB3> INFO: Expecting 208000 events.
[17:10:33.960] <TB3> INFO: 208000 events read in total (17222ms).
[17:10:33.967] <TB3> INFO: Test took 18110ms.
[17:10:34.687] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 85 0 0 0 0 1 0 0 1 0 0 0
[17:10:34.687] <TB3> INFO: number of red-efficiency pixels: 344 225 291 629 769 616 734 909 867 964 964 559 701 370 289 283
[17:10:34.687] <TB3> INFO: number of X-ray hits detected: 142220 147636 129466 250851 267806 291519 302567 297544 340009 354784 343788 273475 269197 158740 165906 159053
[17:10:34.687] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:10:34.687] <TB3> INFO: number of Vcal hits detected: 204478 207767 207680 207251 199394 207292 207146 206957 207012 206792 206814 207347 207149 207573 207698 207702
[17:10:34.687] <TB3> INFO: Vcal hit fiducial efficiency (%): 98.3 99.9 99.9 99.7 97.8 99.7 99.6 99.5 99.6 99.5 99.5 99.7 99.6 99.8 99.9 99.9
[17:10:34.687] <TB3> INFO: Vcal hit overall efficiency (%): 98.3 99.9 99.8 99.6 95.9 99.7 99.6 99.5 99.5 99.4 99.4 99.7 99.6 99.8 99.9 99.9
[17:10:34.687] <TB3> INFO: X-ray hit rate [MHz/cm2]: 41.7 43.3 37.9 73.5 78.5 85.4 88.7 87.2 99.7 104.0 100.8 80.2 78.9 46.5 48.6 46.6
[17:10:34.687] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[17:10:34.712] <TB3> INFO: PixTest:: pg_setup set to default.
[17:10:34.722] <TB3> INFO: enter test to run
[17:10:34.722] <TB3> INFO: test: no parameter change
[17:10:34.975] <TB3> QUIET: Connection to board 121 closed.
[17:10:35.055] <TB3> INFO: pXar: this is the end, my friend

[17:11:12.091] <TB3> INFO: *** Welcome to pxar ***
[17:11:12.091] <TB3> INFO: *** Today: 2016/04/11
[17:11:12.174] <TB3> INFO: *** Version: 9751
[17:11:12.174] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//015_HREfficiency_125//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//015_HREfficiency_125//dacParameters35_C15.dat
[17:11:12.175] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//015_HREfficiency_125//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//015_HREfficiency_125//tbmParameters_C0b.dat
[17:11:12.175] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//015_HREfficiency_125//defaultMaskFile.dat
[17:11:12.175] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//015_HREfficiency_125//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//015_HREfficiency_125//trimParameters35_C15.dat
[17:11:12.258] <TB3> INFO: clk: 4
[17:11:12.258] <TB3> INFO: ctr: 4
[17:11:12.258] <TB3> INFO: sda: 19
[17:11:12.258] <TB3> INFO: tin: 9
[17:11:12.258] <TB3> INFO: level: 15
[17:11:12.258] <TB3> INFO: triggerdelay: 0
[17:11:12.258] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[17:11:12.258] <TB3> INFO: Log level: INFO
[17:11:12.272] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:11:12.287] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:11:12.289] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[17:11:12.291] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[17:11:13.832] <TB3> INFO: DUT info:
[17:11:13.832] <TB3> INFO: The DUT currently contains the following objects:
[17:11:13.832] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:11:13.832] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:11:13.832] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:11:13.832] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:11:13.832] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:13.832] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:14.233] <TB3> INFO: enter 'restricted' command line mode
[17:11:14.233] <TB3> INFO: enter test to run
[17:11:14.233] <TB3> INFO: test: PixelAlive no parameter change
[17:11:14.233] <TB3> INFO: running: pixelalive
[17:11:14.236] <TB3> INFO: ######################################################################
[17:11:14.236] <TB3> INFO: PixTestAlive::doTest()
[17:11:14.236] <TB3> INFO: ######################################################################
[17:11:14.238] <TB3> INFO: ----------------------------------------------------------------------
[17:11:14.238] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:11:14.238] <TB3> INFO: ----------------------------------------------------------------------
[17:11:14.504] <TB3> INFO: Expecting 208000 events.
[17:11:25.693] <TB3> INFO: 208000 events read in total (10632ms).
[17:11:25.695] <TB3> INFO: Test took 11456ms.
[17:11:25.910] <TB3> INFO: PixTestAlive::aliveTest() done
[17:11:25.910] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 1 0 0 0
[17:11:25.911] <TB3> INFO: ----------------------------------------------------------------------
[17:11:25.911] <TB3> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:11:25.911] <TB3> INFO: ----------------------------------------------------------------------
[17:11:26.178] <TB3> INFO: Expecting 208000 events.
[17:11:33.637] <TB3> INFO: 208000 events read in total (6903ms).
[17:11:33.637] <TB3> INFO: Test took 7725ms.
[17:11:33.638] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:11:33.874] <TB3> INFO: PixTestAlive::maskTest() done
[17:11:33.874] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:11:33.876] <TB3> INFO: ----------------------------------------------------------------------
[17:11:33.876] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:11:33.876] <TB3> INFO: ----------------------------------------------------------------------
[17:11:34.160] <TB3> INFO: Expecting 208000 events.
[17:11:45.101] <TB3> INFO: 208000 events read in total (10385ms).
[17:11:45.101] <TB3> INFO: Test took 11224ms.
[17:11:45.295] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[17:11:45.295] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:11:45.295] <TB3> INFO: PixTestAlive::doTest() done, duration: 31 seconds
[17:11:45.327] <TB3> INFO: enter test to run
[17:11:45.327] <TB3> INFO: test: delay setting parameters: ->2<-
[17:11:45.327] <TB3> INFO: delay test by 2 seconds...
[17:11:47.327] <TB3> INFO: enter test to run
[17:11:47.327] <TB3> INFO: test: HighRate no parameter change
[17:11:47.327] <TB3> INFO: running: highrate
[17:11:47.327] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//015_HREfficiency_125//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//015_HREfficiency_125//phCalibrationFitErr35_C15.dat
[17:11:47.503] <TB3> INFO: ----------------------------------------------------------------------
[17:11:47.503] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:11:47.503] <TB3> INFO: ----------------------------------------------------------------------
[17:11:47.985] <TB3> INFO: Expecting 208000 events.
[17:12:07.553] <TB3> INFO: 208000 events read in total (19167ms).
[17:12:07.562] <TB3> INFO: Test took 20056ms.
[17:12:08.399] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 126 0 0 0 0 1 0 0 1 0 0 0
[17:12:08.399] <TB3> INFO: number of red-efficiency pixels: 408 344 390 946 1090 963 1074 1295 1232 1347 1306 829 1025 498 429 409
[17:12:08.399] <TB3> INFO: number of X-ray hits detected: 174231 181078 157575 305130 327089 352407 366648 360923 410629 430363 414329 333469 327371 193387 202133 194979
[17:12:08.399] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:12:08.399] <TB3> INFO: number of Vcal hits detected: 202678 207634 207549 206796 198811 206826 206590 206349 206419 206074 206259 206943 206704 207337 207545 207558
[17:12:08.399] <TB3> INFO: Vcal hit fiducial efficiency (%): 97.4 99.8 99.8 99.5 98.6 99.5 99.4 99.3 99.3 99.2 99.3 99.6 99.5 99.7 99.8 99.8
[17:12:08.399] <TB3> INFO: Vcal hit overall efficiency (%): 97.4 99.8 99.8 99.4 95.6 99.4 99.3 99.2 99.2 99.1 99.2 99.5 99.4 99.7 99.8 99.8
[17:12:08.399] <TB3> INFO: X-ray hit rate [MHz/cm2]: 51.1 53.1 46.2 89.4 95.9 103.3 107.5 105.8 120.4 126.1 121.4 97.7 96.0 56.7 59.2 57.1
[17:12:08.399] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[17:12:08.424] <TB3> INFO: PixTest:: pg_setup set to default.
[17:12:08.434] <TB3> INFO: enter test to run
[17:12:08.434] <TB3> INFO: test: no parameter change
[17:12:08.664] <TB3> QUIET: Connection to board 121 closed.
[17:12:08.744] <TB3> INFO: pXar: this is the end, my friend

[17:12:45.358] <TB3> INFO: *** Welcome to pxar ***
[17:12:45.358] <TB3> INFO: *** Today: 2016/04/11
[17:12:45.424] <TB3> INFO: *** Version: 9751
[17:12:45.424] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//016_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//016_HREfficiency_150//dacParameters35_C15.dat
[17:12:45.425] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//016_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//016_HREfficiency_150//tbmParameters_C0b.dat
[17:12:45.425] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//016_HREfficiency_150//defaultMaskFile.dat
[17:12:45.425] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//016_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//016_HREfficiency_150//trimParameters35_C15.dat
[17:12:45.508] <TB3> INFO: clk: 4
[17:12:45.508] <TB3> INFO: ctr: 4
[17:12:45.508] <TB3> INFO: sda: 19
[17:12:45.508] <TB3> INFO: tin: 9
[17:12:45.508] <TB3> INFO: level: 15
[17:12:45.508] <TB3> INFO: triggerdelay: 0
[17:12:45.508] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[17:12:45.508] <TB3> INFO: Log level: INFO
[17:12:45.521] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:12:45.537] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:12:45.539] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[17:12:45.541] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[17:12:47.095] <TB3> INFO: DUT info:
[17:12:47.095] <TB3> INFO: The DUT currently contains the following objects:
[17:12:47.095] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:12:47.095] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:12:47.095] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:12:47.095] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:12:47.095] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.095] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:47.496] <TB3> INFO: enter 'restricted' command line mode
[17:12:47.496] <TB3> INFO: enter test to run
[17:12:47.496] <TB3> INFO: test: PixelAlive no parameter change
[17:12:47.496] <TB3> INFO: running: pixelalive
[17:12:47.499] <TB3> INFO: ######################################################################
[17:12:47.499] <TB3> INFO: PixTestAlive::doTest()
[17:12:47.499] <TB3> INFO: ######################################################################
[17:12:47.501] <TB3> INFO: ----------------------------------------------------------------------
[17:12:47.501] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:12:47.501] <TB3> INFO: ----------------------------------------------------------------------
[17:12:47.761] <TB3> INFO: Expecting 208000 events.
[17:12:59.008] <TB3> INFO: 208000 events read in total (10689ms).
[17:12:59.010] <TB3> INFO: Test took 11508ms.
[17:12:59.229] <TB3> INFO: PixTestAlive::aliveTest() done
[17:12:59.229] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 1 0 0 0
[17:12:59.230] <TB3> INFO: ----------------------------------------------------------------------
[17:12:59.230] <TB3> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:12:59.230] <TB3> INFO: ----------------------------------------------------------------------
[17:12:59.494] <TB3> INFO: Expecting 208000 events.
[17:13:07.013] <TB3> INFO: 208000 events read in total (6963ms).
[17:13:07.014] <TB3> INFO: Test took 7783ms.
[17:13:07.015] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:13:07.251] <TB3> INFO: PixTestAlive::maskTest() done
[17:13:07.251] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:13:07.253] <TB3> INFO: ----------------------------------------------------------------------
[17:13:07.253] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:13:07.253] <TB3> INFO: ----------------------------------------------------------------------
[17:13:07.536] <TB3> INFO: Expecting 208000 events.
[17:13:18.590] <TB3> INFO: 208000 events read in total (10497ms).
[17:13:18.591] <TB3> INFO: Test took 11337ms.
[17:13:18.787] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[17:13:18.787] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:13:18.787] <TB3> INFO: PixTestAlive::doTest() done, duration: 31 seconds
[17:13:18.818] <TB3> INFO: enter test to run
[17:13:18.818] <TB3> INFO: test: delay setting parameters: ->2<-
[17:13:18.818] <TB3> INFO: delay test by 2 seconds...
[17:13:20.818] <TB3> INFO: enter test to run
[17:13:20.818] <TB3> INFO: test: HighRate no parameter change
[17:13:20.818] <TB3> INFO: running: highrate
[17:13:20.818] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//016_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//016_HREfficiency_150//phCalibrationFitErr35_C15.dat
[17:13:20.971] <TB3> INFO: ----------------------------------------------------------------------
[17:13:20.971] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:13:20.971] <TB3> INFO: ----------------------------------------------------------------------
[17:13:21.471] <TB3> INFO: Expecting 208000 events.
[17:13:43.072] <TB3> INFO: 208000 events read in total (21199ms).
[17:13:43.083] <TB3> INFO: Test took 22109ms.
[17:13:44.041] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 146 0 0 0 0 1 0 0 1 0 0 0
[17:13:44.041] <TB3> INFO: number of red-efficiency pixels: 506 471 476 1346 1509 1322 1575 1861 1811 1928 1799 1253 1425 704 588 554
[17:13:44.042] <TB3> INFO: number of X-ray hits detected: 209461 217349 190202 363218 389136 420172 437258 428549 490539 511124 492432 395769 390015 231940 243392 232925
[17:13:44.042] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:13:44.042] <TB3> INFO: number of Vcal hits detected: 201205 207488 207359 206184 198082 206156 205668 205322 205369 204816 205221 206258 205973 206988 207358 207384
[17:13:44.042] <TB3> INFO: Vcal hit fiducial efficiency (%): 96.6 99.8 99.8 99.2 98.8 99.2 99.0 98.8 98.8 98.7 98.8 99.3 99.2 99.6 99.7 99.7
[17:13:44.042] <TB3> INFO: Vcal hit overall efficiency (%): 96.7 99.8 99.7 99.1 95.2 99.1 98.9 98.7 98.7 98.5 98.7 99.2 99.0 99.5 99.7 99.7
[17:13:44.042] <TB3> INFO: X-ray hit rate [MHz/cm2]: 61.4 63.7 55.7 106.5 114.1 123.2 128.2 125.6 143.8 149.8 144.3 116.0 114.3 68.0 71.3 68.3
[17:13:44.042] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[17:13:44.069] <TB3> INFO: PixTest:: pg_setup set to default.
[17:13:44.079] <TB3> INFO: enter test to run
[17:13:44.079] <TB3> INFO: test: no parameter change
[17:13:44.354] <TB3> QUIET: Connection to board 121 closed.
[17:13:44.434] <TB3> INFO: pXar: this is the end, my friend

[17:14:20.469] <TB3> INFO: *** Welcome to pxar ***
[17:14:20.469] <TB3> INFO: *** Today: 2016/04/11
[17:14:20.533] <TB3> INFO: *** Version: 9751
[17:14:20.533] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//017_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//017_HREfficiency_200//dacParameters35_C15.dat
[17:14:20.533] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//017_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//017_HREfficiency_200//tbmParameters_C0b.dat
[17:14:20.533] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//017_HREfficiency_200//defaultMaskFile.dat
[17:14:20.533] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//017_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//017_HREfficiency_200//trimParameters35_C15.dat
[17:14:20.607] <TB3> INFO: clk: 4
[17:14:20.607] <TB3> INFO: ctr: 4
[17:14:20.607] <TB3> INFO: sda: 19
[17:14:20.607] <TB3> INFO: tin: 9
[17:14:20.607] <TB3> INFO: level: 15
[17:14:20.607] <TB3> INFO: triggerdelay: 0
[17:14:20.607] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[17:14:20.607] <TB3> INFO: Log level: INFO
[17:14:20.621] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:14:20.637] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:14:20.639] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[17:14:20.641] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[17:14:22.200] <TB3> INFO: DUT info:
[17:14:22.200] <TB3> INFO: The DUT currently contains the following objects:
[17:14:22.200] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:14:22.200] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:14:22.200] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:14:22.200] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:14:22.200] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.200] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:14:22.601] <TB3> INFO: enter 'restricted' command line mode
[17:14:22.601] <TB3> INFO: enter test to run
[17:14:22.601] <TB3> INFO: test: PixelAlive no parameter change
[17:14:22.601] <TB3> INFO: running: pixelalive
[17:14:22.605] <TB3> INFO: ######################################################################
[17:14:22.605] <TB3> INFO: PixTestAlive::doTest()
[17:14:22.605] <TB3> INFO: ######################################################################
[17:14:22.606] <TB3> INFO: ----------------------------------------------------------------------
[17:14:22.606] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:14:22.606] <TB3> INFO: ----------------------------------------------------------------------
[17:14:22.899] <TB3> INFO: Expecting 208000 events.
[17:14:33.833] <TB3> INFO: 208000 events read in total (10376ms).
[17:14:33.835] <TB3> INFO: Test took 11228ms.
[17:14:34.054] <TB3> INFO: PixTestAlive::aliveTest() done
[17:14:34.054] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 1 0 0 0
[17:14:34.055] <TB3> INFO: ----------------------------------------------------------------------
[17:14:34.055] <TB3> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:14:34.055] <TB3> INFO: ----------------------------------------------------------------------
[17:14:34.322] <TB3> INFO: Expecting 208000 events.
[17:14:41.789] <TB3> INFO: 208000 events read in total (6911ms).
[17:14:41.789] <TB3> INFO: Test took 7732ms.
[17:14:41.790] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:14:42.023] <TB3> INFO: PixTestAlive::maskTest() done
[17:14:42.023] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:14:42.025] <TB3> INFO: ----------------------------------------------------------------------
[17:14:42.025] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:14:42.025] <TB3> INFO: ----------------------------------------------------------------------
[17:14:42.300] <TB3> INFO: Expecting 208000 events.
[17:14:53.349] <TB3> INFO: 208000 events read in total (10493ms).
[17:14:53.349] <TB3> INFO: Test took 11323ms.
[17:14:53.543] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[17:14:53.543] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:14:53.543] <TB3> INFO: PixTestAlive::doTest() done, duration: 30 seconds
[17:14:53.576] <TB3> INFO: enter test to run
[17:14:53.576] <TB3> INFO: test: delay setting parameters: ->2<-
[17:14:53.576] <TB3> INFO: delay test by 2 seconds...
[17:14:55.576] <TB3> INFO: enter test to run
[17:14:55.576] <TB3> INFO: test: HighRate no parameter change
[17:14:55.576] <TB3> INFO: running: highrate
[17:14:55.576] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//017_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//017_HREfficiency_200//phCalibrationFitErr35_C15.dat
[17:14:55.716] <TB3> INFO: ----------------------------------------------------------------------
[17:14:55.716] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:14:55.716] <TB3> INFO: ----------------------------------------------------------------------
[17:14:56.198] <TB3> INFO: Expecting 208000 events.
[17:15:21.736] <TB3> INFO: 208000 events read in total (25136ms).
[17:15:21.750] <TB3> INFO: Test took 26032ms.
[17:15:22.985] <TB3> INFO: number of dead pixels (per ROC): 5 0 0 0 158 0 0 0 0 1 0 0 1 0 0 0
[17:15:22.985] <TB3> INFO: number of red-efficiency pixels: 737 882 876 2321 2484 2347 2553 3037 3096 3136 3054 2192 2560 1133 1142 1066
[17:15:22.985] <TB3> INFO: number of X-ray hits detected: 285740 298238 261578 493212 528496 566578 591796 577783 659106 692400 663934 537987 527792 316928 332692 318367
[17:15:22.985] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:15:22.985] <TB3> INFO: number of Vcal hits detected: 199992 206945 206510 203750 195454 203703 202512 201664 201543 200076 200811 203953 203186 205718 206632 206686
[17:15:22.985] <TB3> INFO: Vcal hit fiducial efficiency (%): 96.2 99.5 99.4 98.2 98.0 98.2 97.7 97.2 97.1 96.7 97.0 98.3 97.9 99.1 99.4 99.4
[17:15:22.985] <TB3> INFO: Vcal hit overall efficiency (%): 96.2 99.5 99.3 98.0 94.0 97.9 97.4 97.0 96.9 96.2 96.5 98.1 97.7 98.9 99.3 99.4
[17:15:22.985] <TB3> INFO: X-ray hit rate [MHz/cm2]: 83.8 87.4 76.7 144.6 154.9 166.1 173.5 169.4 193.2 202.9 194.6 157.7 154.7 92.9 97.5 93.3
[17:15:22.985] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[17:15:23.011] <TB3> INFO: PixTest:: pg_setup set to default.
[17:15:23.022] <TB3> INFO: enter test to run
[17:15:23.022] <TB3> INFO: test: no parameter change
[17:15:23.254] <TB3> QUIET: Connection to board 121 closed.
[17:15:23.333] <TB3> INFO: pXar: this is the end, my friend

[17:16:01.626] <TB3> INFO: *** Welcome to pxar ***
[17:16:01.626] <TB3> INFO: *** Today: 2016/04/11
[17:16:01.687] <TB3> INFO: *** Version: 9751
[17:16:01.687] <TB3> INFO: readRocDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//018_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//018_HREfficiency_250//dacParameters35_C15.dat
[17:16:01.688] <TB3> INFO: readTbmDacs: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//018_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//018_HREfficiency_250//tbmParameters_C0b.dat
[17:16:01.688] <TB3> INFO: readMaskFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//018_HREfficiency_250//defaultMaskFile.dat
[17:16:01.688] <TB3> INFO: readTrimFile: /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//018_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//018_HREfficiency_250//trimParameters35_C15.dat
[17:16:01.760] <TB3> INFO: clk: 4
[17:16:01.760] <TB3> INFO: ctr: 4
[17:16:01.760] <TB3> INFO: sda: 19
[17:16:01.760] <TB3> INFO: tin: 9
[17:16:01.760] <TB3> INFO: level: 15
[17:16:01.760] <TB3> INFO: triggerdelay: 0
[17:16:01.760] <TB3> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[17:16:01.761] <TB3> INFO: Log level: INFO
[17:16:01.773] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:16:01.789] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:16:01.791] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[17:16:01.793] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[17:16:03.349] <TB3> INFO: DUT info:
[17:16:03.349] <TB3> INFO: The DUT currently contains the following objects:
[17:16:03.349] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:16:03.349] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:16:03.349] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:16:03.349] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:16:03.349] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.349] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:03.750] <TB3> INFO: enter 'restricted' command line mode
[17:16:03.750] <TB3> INFO: enter test to run
[17:16:03.750] <TB3> INFO: test: PixelAlive no parameter change
[17:16:03.750] <TB3> INFO: running: pixelalive
[17:16:03.753] <TB3> INFO: ######################################################################
[17:16:03.753] <TB3> INFO: PixTestAlive::doTest()
[17:16:03.753] <TB3> INFO: ######################################################################
[17:16:03.754] <TB3> INFO: ----------------------------------------------------------------------
[17:16:03.754] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:16:03.755] <TB3> INFO: ----------------------------------------------------------------------
[17:16:04.020] <TB3> INFO: Expecting 208000 events.
[17:16:15.130] <TB3> INFO: 208000 events read in total (10552ms).
[17:16:15.132] <TB3> INFO: Test took 11376ms.
[17:16:15.353] <TB3> INFO: PixTestAlive::aliveTest() done
[17:16:15.353] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 1 0 0 0
[17:16:15.354] <TB3> INFO: ----------------------------------------------------------------------
[17:16:15.354] <TB3> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:16:15.354] <TB3> INFO: ----------------------------------------------------------------------
[17:16:15.618] <TB3> INFO: Expecting 208000 events.
[17:16:23.033] <TB3> INFO: 208000 events read in total (6859ms).
[17:16:23.034] <TB3> INFO: Test took 7679ms.
[17:16:23.035] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:16:23.273] <TB3> INFO: PixTestAlive::maskTest() done
[17:16:23.273] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:16:23.274] <TB3> INFO: ----------------------------------------------------------------------
[17:16:23.274] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:16:23.274] <TB3> INFO: ----------------------------------------------------------------------
[17:16:23.562] <TB3> INFO: Expecting 208000 events.
[17:16:34.926] <TB3> INFO: 208000 events read in total (10808ms).
[17:16:34.926] <TB3> INFO: Test took 11651ms.
[17:16:35.119] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[17:16:35.119] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:16:35.119] <TB3> INFO: PixTestAlive::doTest() done, duration: 31 seconds
[17:16:35.152] <TB3> INFO: enter test to run
[17:16:35.152] <TB3> INFO: test: delay setting parameters: ->2<-
[17:16:35.153] <TB3> INFO: delay test by 2 seconds...
[17:16:37.153] <TB3> INFO: enter test to run
[17:16:37.153] <TB3> INFO: test: HighRate no parameter change
[17:16:37.153] <TB3> INFO: running: highrate
[17:16:37.153] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//018_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2195_XrayQualification_2016-04-11_17h49m_1460389795//018_HREfficiency_250//phCalibrationFitErr35_C15.dat
[17:16:37.316] <TB3> INFO: ----------------------------------------------------------------------
[17:16:37.316] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:16:37.316] <TB3> INFO: ----------------------------------------------------------------------
[17:16:37.821] <TB3> INFO: Expecting 208000 events.
[17:17:07.185] <TB3> INFO: 208000 events read in total (28962ms).
[17:17:07.204] <TB3> INFO: Test took 29886ms.
[17:17:08.737] <TB3> INFO: number of dead pixels (per ROC): 4 0 0 0 160 0 0 0 0 1 0 0 1 0 0 0
[17:17:08.737] <TB3> INFO: number of red-efficiency pixels: 1089 1463 1210 3092 3146 3203 3317 3776 3768 3827 3739 2988 3393 1530 1726 1627
[17:17:08.737] <TB3> INFO: number of X-ray hits detected: 351862 368585 322259 607554 652314 697525 730526 711543 812076 856087 816737 664905 650413 394013 411443 393380
[17:17:08.737] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:17:08.737] <TB3> INFO: number of Vcal hits detected: 199446 206084 205458 200234 191815 199856 197837 196401 195673 193179 195069 200378 199285 203856 205674 205736
[17:17:08.737] <TB3> INFO: Vcal hit fiducial efficiency (%): 95.9 99.1 99.0 96.7 96.5 96.6 95.7 94.9 94.5 93.7 94.5 96.9 96.2 98.4 99.0 99.0
[17:17:08.737] <TB3> INFO: Vcal hit overall efficiency (%): 95.9 99.1 98.8 96.3 92.2 96.1 95.1 94.4 94.1 92.9 93.8 96.3 95.8 98.0 98.9 98.9
[17:17:08.737] <TB3> INFO: X-ray hit rate [MHz/cm2]: 103.1 108.0 94.5 178.1 191.2 204.4 214.1 208.6 238.0 250.9 239.4 194.9 190.6 115.5 120.6 115.3
[17:17:08.737] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[17:17:08.763] <TB3> INFO: PixTest:: pg_setup set to default.
[17:17:08.778] <TB3> INFO: enter test to run
[17:17:08.778] <TB3> INFO: test: no parameter change
[17:17:09.009] <TB3> QUIET: Connection to board 121 closed.
[17:17:09.090] <TB3> INFO: pXar: this is the end, my friend