[13:52:49.579] INFO: *** Welcome to pxar ***
[13:52:49.580] INFO: *** Today: 2016/07/07
[13:52:50.377] INFO: *** Version: v1.9.0-814-g7497
[13:52:50.377] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//dacParameters35_C15.dat
[13:52:50.421] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:52:50.422] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//defaultMaskFile.dat
[13:52:50.427] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C15.dat
[13:52:50.546] INFO: clk: 4
[13:52:50.546] INFO: ctr: 4
[13:52:50.546] INFO: sda: 19
[13:52:50.546] INFO: tin: 9
[13:52:50.546] INFO: level: 15
[13:52:50.546] INFO: triggerdelay: 0
[13:52:50.550] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:52:50.550] INFO: Log level: INFO
[13:52:50.569] QUIET: Connection to board DTB_WREKRL opened.
[13:52:50.572] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[13:52:50.575] INFO: RPC call hashes of host and DTB match: 398089610
[13:52:52.119] INFO: DUT info:
[13:52:52.119] INFO: The DUT currently contains the following objects:
[13:52:52.119] INFO: 2 TBM Cores tbm08c (2 ON)
[13:52:52.119] INFO: TBM Core alpha (0): 7 registers set
[13:52:52.119] INFO: TBM Core beta (1): 7 registers set
[13:52:52.119] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:52:52.119] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.119] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:52:52.591] INFO: enter 'restricted' command line mode
[13:52:52.591] INFO: enter test to run
[13:52:58.052] INFO: test: PixelAlive no parameter change
[13:52:58.052] INFO: running: pixelalive
[13:52:58.077] INFO: ----------------------------------------------------------------------
[13:52:58.077] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:52:58.077] INFO: ----------------------------------------------------------------------
[13:52:58.397] INFO: Expecting 41600 events.
[13:53:02.733] INFO: 41600 events read in total (3617ms).
[13:53:02.894] INFO: Test took 4813ms.
[13:53:02.909] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:03.204] INFO: PixTestAlive::aliveTest() done
[13:53:03.204] INFO: number of dead pixels (per ROC): 0 0 0 0 52 0 0 0 0 0 0 0 0 0 0 0
[13:53:03.237] INFO: enter test to run
[13:53:53.763] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:53:53.763] INFO: running: highrate
[13:53:53.764] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:53:54.299] INFO: ----------------------------------------------------------------------
[13:53:54.299] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:53:54.299] INFO: ----------------------------------------------------------------------
[13:53:54.299] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:53:54.299] INFO: edge/corner pixel THR is adjusted
[13:53:54.299] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:53:55.258] INFO: Collecting data for 5 seconds...
[13:54:00.277] INFO: Done with hot pixel readout
[13:54:12.152] INFO: PixTest:: pg_setup set to default.
[13:54:12.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:54:12.153] INFO: 34 hot pixels found in step 0
[13:54:13.146] INFO: Collecting data for 5 seconds...
[13:54:18.162] INFO: Done with hot pixel readout
[13:54:30.377] INFO: PixTest:: pg_setup set to default.
[13:54:30.377] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:54:30.378] INFO: 41 hot pixels found in step 1
[13:54:31.370] INFO: Collecting data for 5 seconds...
[13:54:36.389] INFO: Done with hot pixel readout
[13:54:36.706] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 8 ROCs were found
[13:54:36.706] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:54:36.706] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19e 8000 43c8 119 2427 43c8 43c8 60a 2a47 711 2046 43c8 282 2864 43c8 43c8 9 2449 43c8 43c8 e000 c000
[13:54:36.706] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a198 80b1 4388 4388 20a 282e 4388 4388 c0 2445 75b 2662 4388 4388 4388 4388 e000 c000
[13:54:36.706] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a199 80c0 4388 40a 2262 4388 9a 2a41 2cb 244a 4388 4388 4388 4388 354 282f 4388 4388 e000 c000
[13:54:36.706] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19a 8000 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[13:54:36.706] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a19b 8040 4388 4388 1c 2641 4388 d5 2267 4388 43c8 43c8 43c8 43c9 d5c 2000 1ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff
[13:54:36.706] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19c 80b1 43c8 43c8 408 282f 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:54:36.706] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19d 80c0 43c8 43c8 71a 2245 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:54:48.629] INFO: PixTest:: pg_setup set to default.
[13:54:48.630] INFO: 39 hot pixels found in step 2
[13:54:49.628] INFO: Collecting data for 5 seconds...
[13:54:54.646] INFO: Done with hot pixel readout
[13:54:54.940] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (53) != TBM ID (63)
[13:54:54.940] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (53) != TBM ID (20)
[13:54:54.940] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[13:54:54.940] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (64) != TBM ID (54)
[13:54:54.940] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (21) != TBM ID (54)
[13:54:54.940] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:54:54.940] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a039 80c0 43c8 43c8 43c8 4a0 204f 43c8 43c8 703 2080 43c8 43c8 711 264d 43c8 561 2847 e000 c000
[13:54:54.940] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a033 8040 4388 159 2a4d 4389 4388 4389 4388 4389 4388 4389 e000 c000
[13:54:54.940] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a034 80b1 4388 4388 53 2664 4388 812 2629 4388 cd 244d 4388 54c 2a6d 4388 404 222a 512 2049 4388 95 2441 4388 e000 c000
[13:54:54.941] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03f 80ff 43c8 43c8 2db 206a 43c8 4a2 224f 43c8 43c8 9c 2244 43c8 43c8 43c8 e000 c000
[13:54:54.941] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a036 8000 43c8 43c8 43c8 43c8 15b 2a63 43c8 8d 244f 43c8 43c8 93 2a49 c5 2463 422 2a6d 43c8 e000 c000
[13:54:54.941] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a037 8040 43c8 43c8 43c8 43c8 43c8 28b 284d 43c8 11 2840 43c8 619 2465 43c8 3 2449 e000 c000
[13:54:54.941] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a038 80b1 43c8 43c8 43c8 43c8 43c8 43c8 43c8 803 2443 43c8 e000 c000
[13:54:54.941] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:54:54.941] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a139 80c0 43c8 43c8 43c8 819 262d 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:54:54.941] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a133 8040 4388 453 2649 4389 4388 d8 2266 15c 2024 85a 244f 4389 4388 4389 4388 4389 e000 c000
[13:54:54.941] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a134 80b1 4388 4388 4388 4388 212 242f 4388 6c5 242b 4388 4388 4388 e000 c000
[13:54:54.941] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a114 803e 43c8 43c8 43c8 c8 2649 43c8 43c8 43c8 43c8 90 2229 e000 c000
[13:54:54.941] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a136 8000 43c8 492 2068 43c8 43c8 43c8 43c8 760 282a 43c8 43c8 43c8 e000 c000
[13:54:54.941] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a137 8040 43c8 159 2a42 43c8 404 2a41 4d9 2649 65d 2249 43c8 43c8 611 2a4d 43c8 490 2045 43c8 212 2248 43c8 43c8 e000 c000
[13:54:54.941] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a138 80b1 43c8 43c8 449 2241 43c8 305 2645 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:55:06.852] INFO: PixTest:: pg_setup set to default.
[13:55:06.852] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:55:06.853] INFO: 45 hot pixels found in step 3
[13:55:07.849] INFO: Collecting data for 5 seconds...
[13:55:12.867] INFO: Done with hot pixel readout
[13:55:25.113] INFO: PixTest:: pg_setup set to default.
[13:55:25.113] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:55:25.114] INFO: 33 hot pixels found in step 4
[13:55:26.110] INFO: Collecting data for 5 seconds...
[13:55:31.128] INFO: Done with hot pixel readout
[13:55:43.363] INFO: PixTest:: pg_setup set to default.
[13:55:43.363] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:55:43.363] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:55:43.364] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:55:43.364] INFO: 38 hot pixels found in step 5
[13:55:44.360] INFO: Collecting data for 5 seconds...
[13:55:49.378] INFO: Done with hot pixel readout
[13:56:01.298] INFO: PixTest:: pg_setup set to default.
[13:56:01.298] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:01.298] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:01.298] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:01.298] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:01.299] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:01.299] INFO: 25 hot pixels found in step 6
[13:56:02.295] INFO: Collecting data for 5 seconds...
[13:56:07.313] INFO: Done with hot pixel readout
[13:56:19.522] INFO: PixTest:: pg_setup set to default.
[13:56:19.522] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:19.523] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:19.523] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:19.523] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:19.523] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:19.523] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:19.523] INFO: 36 hot pixels found in step 7
[13:56:20.519] INFO: Collecting data for 5 seconds...
[13:56:25.537] INFO: Done with hot pixel readout
[13:56:26.376] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 7 ROCs were found
[13:56:26.377] WARNING: Channel 1 ROC 6: Readback start marker after 15 readouts!
[13:56:26.377] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 7 ROCs were found
[13:56:26.377] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:56:26.377] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f1 80c0 4388 4388 111 284f 4388 4388 150 2a48 4389 4389 4389 4389 e000 c000
[13:56:26.377] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0eb 8040 43c8 55d 2640 43c8 43c8 43c8 75d 2a4a 43c8 154 262c 43c8 64b 2a4c 43c8 43c8 1c 2448 e000 c000
[13:56:26.377] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ec 80b1 43c8 43c8 43c8 43c8 43c8 848 226e 43c8 43c8 103 2649 43c8 28d 262c 840 2649 e000 c000
[13:56:26.377] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ed 80c0 43c8 43c8 43c8 49c 2065 43c8 449 2446 43c8 43c8 43c8 303 242d 43c8 e000 c000
[13:56:26.377] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a0ee 8000 43c8 43c8 43c8 85b 264d 4388 4388 4388 4388 1bfe 29d5 15 2555 d11 4388 0 23ff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[13:56:26.377] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ef 8040 438a 438a 98 2a43 438a 438a 713 2a4a 438a 453 2462 438a 438a 438a e000 c000
[13:56:26.377] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f0 80b1 4388 4388 412 2664 4388 65a 2062 4388 4388 4388 85a 224f 4388 6e0 204d 4388 304 2842 e000 c000
[13:56:26.377] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:56:26.377] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f1 80c0 4388 18 2449 853 2665 4388 4388 40c 2a28 41d 2649 71b 244f 4388 4389 658 282d 4389 4389 520 266d 75b 2a45 4389 e000 c000
[13:56:26.377] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1eb 8040 43c8 43c8 43c8 749 2041 43c8 6d2 264a 43c8 43c8 14b 2a42 43c8 43c8 85d 2a44 e000 c000
[13:56:26.377] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ec 80b1 43c8 43c8 85 242f 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:56:26.377] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ed 80c0 43c8 43c8 43c8 4b 2648 80b 2865 43c8 750 2040 43c8 43c8 43c8 43c8 9b 2a45 e000 c000
[13:56:26.377] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a1ee 8000 43c8 54 2640 43c8 43c8 4388 4388 4388 438a 1429 26bd 7d7 2eaa 42 2057 0 207f fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[13:56:26.378] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ef 8040 438a 438a 438a 438a 438a 31d 264a 438a 438a 438a e000 c000
[13:56:26.378] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f0 80b1 4389 15 2a4b 4389 2d9 2649 852 264a 4389 842 2649 4389 4389 4389 301 2a41 4389 4389 e000 c000
[13:56:34.453] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:56:34.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ef 8040 43ca 43ca 15c 202d 43ca 403 2848 549 2245 43ca 28a 264f 50b 2267 43ca 290 262c 43ca 43ca 159 2843 2d8 2247 719 2065 718 2045 43ca e000 c000
[13:56:34.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e9 80c0 4388 4388 91 284d 4388 4388 4388 4388 4388 d5 2245 4388 e000 c000
[13:56:34.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ea 8000 4388 4388 4388 4388 b 2062 4388 4388 4388 4388 54a 242f e000 c000
[13:56:34.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0eb 8040 4388 4388 4388 4388 54a 204b 4388 4388 40b 2249 4388 74a 2464 4388 e000 c000
[13:56:34.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ec 80b1 4388 41c 262e 4388 4388 4388 4388 89 2245 4388 4388 260 284f 4388 213 244f 35a 264f e000 d000
[13:56:34.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ed 80c0 43c8 20 2429 43c8 dd 2466 43c8 60d 2249 43c8 43c8 692 2861 43c8 43c8 43c8 205 2843 e000 c000
[13:56:34.453] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ee 8000 43c8 43c8 43c8 43c8 619 2848 43c8 43c8 804 282e 43c8 110 2841 110 2a4e 21b 246f 43c8 349 2467 e000 c000
[13:56:35.857] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[13:56:35.860] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:56:35.860] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a9 80c0 43c8 43c8 43c8 413 284f 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:56:35.860] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a3 8040 4388 4389 4388 4389 4388 621 204e 4389 4388 4389 e000 c000
[13:56:35.860] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a4 80b1 4388 4388 35d 224d 4388 4388 4388 4388 4388 4388 e000 c000
[13:56:35.860] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a5 80c0 4388 4388 751 2040 4388 4388 153 2265 451 2a4d 4388 2d4 202f 4388 4388 4388 e000 c000
[13:56:35.860] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a6 8000 43c8 10 26b2 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:56:35.860] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a7 8040 43c8 43c8 c 2443 43c8 43c8 84a 224b 43c8 4b 262f 71d 2a49 43c8 43c8 312 2642 43c8 e000 c000
[13:56:35.860] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a8 80b1 43c8 43c8 dd 2049 43c8 43c8 43c8 618 282d 43c8 43c8 43c8 e000 c000
[13:56:37.818] INFO: PixTest:: pg_setup set to default.
[13:56:37.818] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:37.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:37.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:37.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:37.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:37.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:37.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:37.819] INFO: 35 hot pixels found in step 8
[13:56:38.815] INFO: Collecting data for 5 seconds...
[13:56:43.834] INFO: Done with hot pixel readout
[13:56:47.120] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 9 ROCs were found
[13:56:47.120] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 8 ROCs were found
[13:56:47.120] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b4 80b1 4388 4388 4388 6cd 244d 4388 281 244d 4388 522 226c 4388 4388 4388 e000 c000
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ae 8000 43c8 113 284c 43c8 43c8 43c8 412 2a68 43c8 10c 2441 43c8 43c8 43c8 e000 c000
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0af 8040 43ca 43ca 43ca 43ca 43ca 543 204d 43ca 68a 244f 43ca 698 2046 43ca 4d1 2a42 e000 c000
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b0 80b1 43c8 43c8 43c8 494 2a45 43c8 43c8 604 2845 43c8 9 202d 43c8 43c8 44d 2447 e000 c000
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a0b1 80c0 43c8 43c8 43c8 43c8 43c9 15 2444 43c9 43c9 185a 3a45 4389 24a 25f5 4388 2500 ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b2 8000 4388 4388 4389 4389 4388 4388 4389 4ca 2a4f 4389 e000 c000
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b3 8040 4388 1d 2028 2dd 284c 4389 4388 85d 2646 4389 4388 41b 2865 4389 4388 14b 2041 443 204f 4389 e000 c000
[13:56:47.120] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b4 80b1 4388 4388 289 2463 359 2047 4388 41a 2640 4388 4388 4388 4388 4388 e000 c000
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ae 8000 43c8 43c8 40c 2644 43c8 43c8 48b 2265 43c8 64a 222d 43c8 43c8 43c8 e000 c000
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1af 8040 43ca 43ca 43ca 50d 2a4a 43ca 43ca 412 2440 44c 2247 812 2842 43ca 50 2041 43ca 43ca e000 c000
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b0 80b1 43c9 689 222e 43c9 43c9 88 2029 150 2024 43c9 43c9 db 2429 43c9 203 2a49 43c9 43c9 e000 c000
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a1b1 80c0 43c8 521 224f 43c8 43c8 43c8 43c9 43c9 34d 3041 5389 4389 105 2aaa 57 2000 7f 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b2 8000 4388 459 2a47 4388 4389 4389 11a 284b 4388 4388 4389 4389 e000 c000
[13:56:47.120] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b3 8040 4388 4389 11 2a4b 109 2642 2ca 2040 4c0 2448 4388 450 2027 553 2a6c 6cd 2847 4389 4388 c9 2a2f 2c3 2068 758 262c 4389 4388 4389 e000 c000
[13:56:51.072] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (246) != TBM ID (245)
[13:56:51.072] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (246) != TBM ID (245)
[13:56:51.072] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[13:56:51.072] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (246) != TBM ID (247)
[13:56:51.072] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (246) != TBM ID (247)
[13:56:51.072] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:56:51.072] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fa 8000 4388 4388 4388 4388 4388 4388 64b 264a 4388 293 2849 84c 2647 4388 e000 c000
[13:56:51.072] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f4 80b1 43c8 25d 242d 43c8 43c8 43c8 149 2462 149 2666 43c8 e0 222f 43c8 43c8 43c8 e000 c000
[13:56:51.072] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f5 80c0 43c8 43c8 43c8 43c8 43c8 43c8 43c8 35d 244d 502 282f 43c8 e000 c000
[13:56:51.072] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f5 8055 4388 4388 4388 44d 2840 4388 4388 8b 264e 4d5 204d 4388 6e0 284a 750 2042 4388 254 2a42 4388 e000 c000
[13:56:51.072] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f7 8040 4388 2d1 2268 4388 4388 4388 854 2a45 4388 4388 848 2662 4388 4388 492 282f 681 242f e000 c000
[13:56:51.072] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f8 80b1 4388 4388 4388 4388 353 2469 4388 5c 2441 4388 4388 4388 e000 c000
[13:56:51.072] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f9 80c0 4388 12 2428 4388 4388 4388 4388 1c 2425 405 2847 4388 4388 50a 2823 4388 35b 284b 615 2461 e000 c000
[13:56:51.072] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:56:51.072] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fa 8000 4388 4388 28c 2245 414 2243 4388 5d 2a4f 20d 2446 752 2a42 4388 4388 4388 4388 4388 e000 c000
[13:56:51.072] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f4 80b1 43c8 43c8 43c8 43c8 712 2847 43c8 43c8 43c8 43c8 e000 c000
[13:56:51.072] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f5 80c0 43c8 43c8 43c8 319 2863 43c8 43c8 43c8 43c8 819 2627 43c8 e000 c000
[13:56:51.072] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f5 80a8 15c 4388 4388 4388 4388 292 2840 4388 4388 4388 e000 c000
[13:56:51.073] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f7 8040 4388 4388 40d 2645 4388 4388 402 2065 4388 10a 282e 10a 2a47 499 226c 4388 4388 4388 e000 c000
[13:56:51.073] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f8 80b1 4388 4388 688 2849 4388 85a 2262 4388 4388 68d 2245 4388 4388 4388 e000 c000
[13:56:51.073] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f9 80c0 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[13:56:56.124] INFO: PixTest:: pg_setup set to default.
[13:56:56.124] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:56.125] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:56.125] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:56.125] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:56.125] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:56:56.126] INFO: 30 hot pixels found in step 9
[13:56:57.126] INFO: Collecting data for 5 seconds...
[13:57:02.144] INFO: Done with hot pixel readout
[13:57:06.053] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:57:06.053] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a029 80c0 43c8 660 2442 43c8 43c8 43c8 2c8 2841 43c8 28b 2847 43c8 43c8 111 2249 43c8 e000 c000
[13:57:06.053] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a023 8040 4388 4d2 2640 4389 4388 4389 4388 34c 2645 4389 4388 4389 e000 c000
[13:57:06.053] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a024 80b1 4388 4388 4388 44d 2a6b 4388 4388 28c 2441 4388 4388 612 2641 4388 55 2244 35b 224d e000 c000
[13:57:06.053] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a025 80c0 4388 4388 4388 4388 85 284b 4388 35a 2a4b 4388 2c5 284f 4388 4388 353 2662 e000 c000
[13:57:06.053] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a026 8000 4388 112 204f 45b 2444 655 224e 751 204d 4388 4388 4388 15a 2a49 4388 4388 4388 4388 2c9 264d e000 d000
[13:57:06.053] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a027 8040 43c8 43c8 43c8 43c8 50 2849 43c8 43c8 45d 2a46 43c8 43c8 e000 c000
[13:57:06.054] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a028 80b1 43c8 43c8 43c8 43c8 305 2640 43c8 43c8 54b 2069 43c8 119 2446 289 264c 481 264c 43c8 e000 c000
[13:57:06.054] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:57:06.054] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a129 80c0 43c8 43c8 458 2441 754 2825 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:57:06.054] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a123 8040 4388 258 2240 4389 4388 8a 224b 854 2241 4389 4388 4389 4388 4389 e000 c000
[13:57:06.054] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a124 80b1 4388 762 2064 4388 d4 2a45 4388 4388 851 224a 4388 4388 14 2441 4388 558 2641 4388 e000 c000
[13:57:06.054] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a125 80c0 4388 441 2666 4388 4388 59 2242 74a 264b 854 2a44 4388 4388 690 2046 4388 4388 4388 e000 c000
[13:57:06.054] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a126 8000 4388 48a 244f 4388 d1 2245 120 2441 4388 4388 493 284d 493 2a4f 51a 2a42 4388 420 2a6b 4388 4388 53c8 e000 c000
[13:57:06.054] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a127 8040 43c8 43c8 43c8 43c8 43c8 412 282c 51a 2a2c 43c8 43c8 43c8 e000 c000
[13:57:06.054] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a128 80b1 43c8 9b 204f 298 2843 43c8 43c8 28a 264d 43c8 43c8 43c8 2d3 2845 43c8 43c8 e000 c000
[13:57:09.886] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06e 8000 43c8 43c8 43c8 43c8 40a 2a4c 43c8 1d 244d 43c8 d5 2442 43c8 43c8 e000 c000
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a068 80b1 4388 4388 4388 4388 4388 4388 20a 242f 4388 64d 2a44 4388 201 2440 309 2449 e000 c000
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a069 80c0 4388 4388 4388 4388 4388 315 2042 759 2280 4388 4388 249 244a 4388 e000 c000
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06a 8000 4388 4388 4388 441 2462 488 2446 48a 2a4b 4388 4388 4388 4388 108 2629 4388 19 244d e000 c000
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06b 8040 4388 64a 2a45 4388 4388 4388 21b 2062 43c8 53c8 53c8 10f8 2330 ff 2fff 43c8 42 242c e000 c000
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06c 80b1 43c8 d9 2868 718 2447 43c8 43c8 43c8 698 2028 43c8 43c8 5b 2a4a 65a 2a4c 43c8 43c8 e000 c000
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06d 80c0 43c8 43c8 43c8 490 264b 855 2a40 43c8 a2 2268 43c8 252 2465 490 224f 43c8 43c8 4d 2846 43c8 4d0 242f e000 c000
[13:57:09.886] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16e 8000 43c8 43c8 43c8 43c8 43c8 43c8 283 2849 43c8 43c8 e000 c000
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a168 80b1 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a169 80c0 4388 4388 4388 4388 4388 4388 4388 4388 e000 c000
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16a 8000 4388 4388 219 222c 60b 2649 4388 4388 4388 4388 251 2248 4388 4388 e000 c000
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16b 8040 4388 4388 d9 264c 4388 4388 4388 43c8 43c8 53c8 f00f c07e
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16c 80b1 43c8 43c8 43c8 211 2849 43c8 15a 264a 43c8 549 2240 43c8 83 2240 341 2840 458 284d 43c8 43c8 e000 c000
[13:57:09.886] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16d 80c0 43c8 49a 2a4d 43c8 43c8 70d 222c 74d 2a42 43c8 43c8 43c8 4d8 2a42 43c8 43c8 e000 c000
[13:57:10.611] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:57:10.612] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[13:57:10.612] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cb 8040 4388 4388 4388 4388 855 2a44 4388 658 2848 4388 4388 99 202f 99 262f 619 2a64 4388 2 2245 e000 c000
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c5 80c0 43c8 43c8 43c8 43c8 43c8 248 2443 60c 2462 43c8 4d5 2442 43c8 43c8 e000 c000
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c6 8000 43c8 43c8 43c8 43c8 715 204f 43c8 43c8 200 2825 43c8 15 2445 43c8 e000 c000
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c7 8040 43c8 209 2848 43c8 43c8 852 262d 43c8 462 244f 43c8 211 2244 41c 2a42 43c8 55c 2447 43c8 4d5 204f 43c8 21d 264d e000 c000
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c8 80b1 8f8 2295 44c 4388 18 2440 4388 4388 419 2a4e 4388 4388 4388 449 246d 4388 db 2a47 e000 c000
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c9 80c0 4388 4388 4388 494 2a42 4388 218 2645 4388 4388 4388 290 2648 4388 e000 c000
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ca 8000 4388 4388 450 2a4f 4388 4388 3 2645 4388 4d5 2044 4388 495 2449 4388 715 2a4f 4388 d8 2225 541 2040 e000 c000
[13:57:10.612] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1cb 8040 4388 4388 818 2245 4388 4388 4388 4388 41a 2644 4388 4388 e000 c000
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c5 80c0 43c8 115 262d 115 2a2a 43c8 1 2a46 41a 2848 43c8 81b 2848 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c6 8000 43c8 43c8 559 2a48 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c7 8040 43c8 43c8 43c8 10 2a49 6c5 2648 43c8 53 286b 9a 2467 152 2265 43c8 43c8 43c8 43c8 601 2426 e000 c000
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c8 80b1 87c 4388 4388 4388 714 2645 4388 452 224f 4388 51c 2880 4388 504 2045 860 2048 4388 e000 c000
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c9 80c0 4388 19 2647 4388 6cb 2a2f 4388 4388 2d0 264d 4388 4388 4388 4388 e000 c000
[13:57:10.612] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ca 8000 4388 4388 4388 45 2842 dd 2047 315 2863 4388 4388 4388 4388 4388 e000 c000
[13:57:14.269] INFO: PixTest:: pg_setup set to default.
[13:57:14.269] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:14.269] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:14.269] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:14.269] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:14.270] INFO: 21 hot pixels found in step 10
[13:57:15.266] INFO: Collecting data for 5 seconds...
[13:57:20.286] INFO: Done with hot pixel readout
[13:57:32.733] INFO: PixTest:: pg_setup set to default.
[13:57:32.733] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:32.733] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:32.733] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:32.733] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:32.733] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:32.733] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:32.733] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:32.734] INFO: 29 hot pixels found in step 11
[13:57:33.729] INFO: Collecting data for 5 seconds...
[13:57:38.748] INFO: Done with hot pixel readout
[13:57:51.247] INFO: PixTest:: pg_setup set to default.
[13:57:51.247] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:51.247] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:51.247] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:51.247] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:51.248] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:51.248] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:51.248] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:51.248] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:57:51.249] INFO: 33 hot pixels found in step 12
[13:57:52.244] INFO: Collecting data for 5 seconds...
[13:57:57.264] INFO: Done with hot pixel readout
[13:58:09.799] INFO: PixTest:: pg_setup set to default.
[13:58:09.800] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:09.800] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:09.800] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:09.800] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:09.800] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:09.800] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:09.800] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:09.800] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:09.801] INFO: 25 hot pixels found in step 13
[13:58:10.796] INFO: Collecting data for 5 seconds...
[13:58:15.817] INFO: Done with hot pixel readout
[13:58:27.970] INFO: PixTest:: pg_setup set to default.
[13:58:27.970] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:27.970] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:27.970] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:27.970] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:27.970] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:27.970] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:27.970] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:27.970] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:27.970] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:27.970] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:58:27.971] INFO: 20 hot pixels found in step 14
[13:58:28.010] INFO: 20 hot pixels could not be trimmed and have been masked.
[13:58:28.014] INFO: PixTest::trimHotPixels() done
[13:58:28.014] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C0.dat
[13:58:28.027] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C1.dat
[13:58:28.036] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C2.dat
[13:58:28.045] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C3.dat
[13:58:28.053] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C4.dat
[13:58:28.061] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C5.dat
[13:58:28.067] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C6.dat
[13:58:28.074] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C7.dat
[13:58:28.080] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C8.dat
[13:58:28.086] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C9.dat
[13:58:28.092] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C10.dat
[13:58:28.098] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C11.dat
[13:58:28.104] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C12.dat
[13:58:28.109] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C13.dat
[13:58:28.114] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C14.dat
[13:58:28.120] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C15.dat
[13:58:28.125] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//defaultMaskFile.dat
[13:58:28.136] INFO: enter test to run
[13:58:47.441] INFO: test: timing no parameter change
[13:58:47.441] INFO: running: timing
[13:58:47.445] INFO: ######################################################################
[13:58:47.445] INFO: PixTestTiming::doTest()
[13:58:47.445] INFO: ######################################################################
[13:58:47.445] INFO: ----------------------------------------------------------------------
[13:58:47.445] INFO: PixTestTiming::TBMPhaseScan()
[13:58:47.445] INFO: ----------------------------------------------------------------------
[14:04:57.758] INFO: TBM Phase Settings: 236
[14:04:57.758] INFO: 400MHz Phase: 3
[14:04:57.758] INFO: 160MHz Phase: 7
[14:04:57.758] INFO: Functional Phase Area: 4
[14:04:57.810] INFO: Test took 370365 ms.
[14:04:57.810] INFO: PixTestTiming::TBMPhaseScan() done.
[14:04:57.811] INFO: ----------------------------------------------------------------------
[14:04:57.811] INFO: PixTestTiming::ROCDelayScan()
[14:04:57.811] INFO: ----------------------------------------------------------------------
[14:07:33.936] INFO: ROC Delay Settings: 228
[14:07:33.936] INFO: ROC Header-Trailer/Token Delay: 11
[14:07:33.936] INFO: ROC Port 0 Delay: 4
[14:07:33.936] INFO: ROC Port 1 Delay: 4
[14:07:33.936] INFO: Functional ROC Area: 5
[14:07:33.940] INFO: Test took 156130 ms.
[14:07:33.940] INFO: PixTestTiming::ROCDelayScan() done.
[14:07:33.940] INFO: ----------------------------------------------------------------------
[14:07:33.940] INFO: PixTestTiming::TimingTest()
[14:07:33.940] INFO: ----------------------------------------------------------------------
[14:07:50.121] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:05.126] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:20.387] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:35.469] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:50.842] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:06.092] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:21.382] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:36.630] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:51.930] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:07.207] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:07.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:07.602] INFO: ----------------------------------------------------------------------
[14:10:07.602] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[14:10:07.602] INFO: ----------------------------------------------------------------------
[14:10:07.603] INFO: ----------------------------------------------------------------------
[14:10:07.603] INFO: Read back bit status: 1
[14:10:07.603] INFO: ----------------------------------------------------------------------
[14:10:07.603] INFO: ----------------------------------------------------------------------
[14:10:07.603] INFO: Timings are good!
[14:10:07.603] INFO: ----------------------------------------------------------------------
[14:10:07.603] INFO: Test took 153663 ms.
[14:10:07.603] INFO: PixTestTiming::TimingTest() done.
[14:10:07.614] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//tbmParameters_C0a.dat
[14:10:07.614] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:10:07.614] INFO: PixTestTiming::doTest took 680173 ms.
[14:10:07.614] INFO: PixTestTiming::doTest() done
[14:10:07.615] INFO: Write out TBMPhaseScan_0_V0
[14:10:07.615] INFO: Write out TBMPhaseScan_1_V0
[14:10:07.615] INFO: Write out CombinedTBMPhaseScan_V0
[14:10:07.640] INFO: Write out ROCDelayScan3_V0
[14:10:07.640] INFO: enter test to run
[14:11:10.493] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:11:10.493] INFO: running: highrate
[14:11:10.498] INFO: ----------------------------------------------------------------------
[14:11:10.498] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:11:10.498] INFO: ----------------------------------------------------------------------
[14:11:10.498] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:11:10.498] INFO: edge/corner pixel THR is adjusted
[14:11:10.498] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:11:11.457] INFO: Collecting data for 5 seconds...
[14:11:16.475] INFO: Done with hot pixel readout
[14:11:28.502] INFO: PixTest:: pg_setup set to default.
[14:11:28.502] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:11:28.503] INFO: 19 hot pixels found in step 0
[14:11:29.499] INFO: Collecting data for 5 seconds...
[14:11:34.515] INFO: Done with hot pixel readout
[14:11:46.625] INFO: PixTest:: pg_setup set to default.
[14:11:46.625] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:11:46.626] INFO: 23 hot pixels found in step 1
[14:11:47.621] INFO: Collecting data for 5 seconds...
[14:11:52.637] INFO: Done with hot pixel readout
[14:12:04.766] INFO: PixTest:: pg_setup set to default.
[14:12:04.766] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:12:04.767] INFO: 21 hot pixels found in step 2
[14:12:05.763] INFO: Collecting data for 5 seconds...
[14:12:10.779] INFO: Done with hot pixel readout
[14:12:22.915] INFO: PixTest:: pg_setup set to default.
[14:12:22.916] INFO: 24 hot pixels found in step 3
[14:12:23.910] INFO: Collecting data for 5 seconds...
[14:12:28.927] INFO: Done with hot pixel readout
[14:12:41.073] INFO: PixTest:: pg_setup set to default.
[14:12:41.073] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:12:41.073] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:12:41.074] INFO: 19 hot pixels found in step 4
[14:12:42.068] INFO: Collecting data for 5 seconds...
[14:12:47.084] INFO: Done with hot pixel readout
[14:12:59.233] INFO: PixTest:: pg_setup set to default.
[14:12:59.233] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:12:59.234] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:12:59.234] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:12:59.234] INFO: 22 hot pixels found in step 5
[14:13:00.229] INFO: Collecting data for 5 seconds...
[14:13:05.245] INFO: Done with hot pixel readout
[14:13:17.388] INFO: PixTest:: pg_setup set to default.
[14:13:17.388] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:17.388] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:17.388] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:17.388] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:17.388] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:17.389] INFO: 22 hot pixels found in step 6
[14:13:18.383] INFO: Collecting data for 5 seconds...
[14:13:23.399] INFO: Done with hot pixel readout
[14:13:35.561] INFO: PixTest:: pg_setup set to default.
[14:13:35.561] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:35.561] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:35.561] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:35.561] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:35.561] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:35.561] INFO: 23 hot pixels found in step 7
[14:13:36.558] INFO: Collecting data for 5 seconds...
[14:13:41.575] INFO: Done with hot pixel readout
[14:13:53.711] INFO: PixTest:: pg_setup set to default.
[14:13:53.711] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:53.711] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:53.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:53.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:13:53.712] INFO: 27 hot pixels found in step 8
[14:13:54.706] INFO: Collecting data for 5 seconds...
[14:13:59.724] INFO: Done with hot pixel readout
[14:14:11.943] INFO: PixTest:: pg_setup set to default.
[14:14:11.943] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:11.943] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:11.943] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:11.943] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:11.944] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:11.944] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:11.944] INFO: 21 hot pixels found in step 9
[14:14:12.941] INFO: Collecting data for 5 seconds...
[14:14:17.958] INFO: Done with hot pixel readout
[14:14:30.081] INFO: PixTest:: pg_setup set to default.
[14:14:30.081] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:30.081] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:30.081] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:30.081] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:30.081] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:30.082] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:30.082] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:30.082] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:30.082] INFO: 25 hot pixels found in step 10
[14:14:31.077] INFO: Collecting data for 5 seconds...
[14:14:36.093] INFO: Done with hot pixel readout
[14:14:48.210] INFO: PixTest:: pg_setup set to default.
[14:14:48.210] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:48.210] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:48.210] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:48.210] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:48.210] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:48.210] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:14:48.211] INFO: 27 hot pixels found in step 11
[14:14:49.206] INFO: Collecting data for 5 seconds...
[14:14:54.222] INFO: Done with hot pixel readout
[14:15:06.377] INFO: PixTest:: pg_setup set to default.
[14:15:06.377] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:06.377] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:06.378] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:06.378] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:06.378] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:06.378] INFO: 27 hot pixels found in step 12
[14:15:07.373] INFO: Collecting data for 5 seconds...
[14:15:12.390] INFO: Done with hot pixel readout
[14:15:24.542] INFO: PixTest:: pg_setup set to default.
[14:15:24.542] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:24.542] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:24.542] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:24.542] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:24.542] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:24.543] INFO: 22 hot pixels found in step 13
[14:15:25.538] INFO: Collecting data for 5 seconds...
[14:15:30.555] INFO: Done with hot pixel readout
[14:15:42.683] INFO: PixTest:: pg_setup set to default.
[14:15:42.683] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:42.683] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:42.683] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:42.683] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:42.683] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:42.684] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:42.684] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:15:42.684] INFO: 33 hot pixels found in step 14
[14:15:42.723] INFO: 33 hot pixels could not be trimmed and have been masked.
[14:15:42.727] INFO: PixTest::trimHotPixels() done
[14:15:42.727] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C0.dat
[14:15:42.733] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C1.dat
[14:15:42.739] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C2.dat
[14:15:42.746] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C3.dat
[14:15:42.751] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C4.dat
[14:15:42.757] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C5.dat
[14:15:42.762] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C6.dat
[14:15:42.767] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C7.dat
[14:15:42.773] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C8.dat
[14:15:42.778] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C9.dat
[14:15:42.783] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C10.dat
[14:15:42.789] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C11.dat
[14:15:42.794] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C12.dat
[14:15:42.799] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C13.dat
[14:15:42.805] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C14.dat
[14:15:42.811] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C15.dat
[14:15:42.816] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//defaultMaskFile.dat
[14:15:42.826] INFO: enter test to run
[14:16:33.666] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:16:33.666] INFO: running: highrate
[14:16:33.672] INFO: ----------------------------------------------------------------------
[14:16:33.672] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:16:33.672] INFO: ----------------------------------------------------------------------
[14:16:33.672] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:16:33.672] INFO: edge/corner pixel THR is adjusted
[14:16:33.672] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:16:34.630] INFO: Collecting data for 1 seconds...
[14:16:35.634] INFO: Done with hot pixel readout
[14:16:39.900] INFO: PixTest:: pg_setup set to default.
[14:16:39.901] INFO: 0 hot pixels found in step 0
[14:16:39.906] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:16:39.988] INFO: PixTest::trimHotPixels() done
[14:16:39.989] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C0.dat
[14:16:39.999] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C1.dat
[14:16:40.004] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C2.dat
[14:16:40.009] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C3.dat
[14:16:40.014] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C4.dat
[14:16:40.019] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C5.dat
[14:16:40.025] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C6.dat
[14:16:40.030] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C7.dat
[14:16:40.035] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C8.dat
[14:16:40.040] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C9.dat
[14:16:40.045] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C10.dat
[14:16:40.050] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C11.dat
[14:16:40.056] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C12.dat
[14:16:40.061] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C13.dat
[14:16:40.066] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C14.dat
[14:16:40.071] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//trimParameters35_C15.dat
[14:16:40.076] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-5-02_FPIXTest-17C-FNAL-160705-1225_2016-07-05_12h25m_1467739507/000_FPIXTest_p17//defaultMaskFile.dat
[14:16:40.087] INFO: enter test to run
[14:17:14.258] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:17:14.258] INFO: running: xray
[14:17:14.276] INFO: ----------------------------------------------------------------------
[14:17:14.276] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:17:14.276] INFO: ----------------------------------------------------------------------
[14:17:15.240] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:17:26.214] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[14:17:55.751] INFO: Resuming triggers.
[14:18:06.729] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[14:18:36.530] INFO: Resuming triggers.
[14:18:47.510] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[14:19:17.225] INFO: Resuming triggers.
[14:19:28.206] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[14:19:57.500] INFO: Resuming triggers.
[14:20:08.481] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[14:20:38.891] INFO: Resuming triggers.
[14:20:49.870] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[14:21:20.159] INFO: Resuming triggers.
[14:21:31.135] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[14:22:01.148] INFO: Resuming triggers.
[14:22:12.123] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[14:22:41.601] INFO: Resuming triggers.
[14:22:52.579] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[14:23:22.140] INFO: Resuming triggers.
[14:23:23.701] INFO: data taking finished, elapsed time: 100 seconds.
[14:23:28.207] INFO: PixTest:: pg_setup set to default.
[14:23:28.210] INFO: PixTestXray::doPhRun() done
[14:23:28.387] INFO: enter test to run
[14:23:58.679] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:23:58.679] INFO: running: xray
[14:23:58.680] INFO: ----------------------------------------------------------------------
[14:23:58.681] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:23:58.681] INFO: ----------------------------------------------------------------------
[14:23:59.653] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:24:05.920] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:24:36.117] INFO: Resuming triggers.
[14:24:42.385] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[14:25:12.431] INFO: Resuming triggers.
[14:25:18.699] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[14:25:48.741] INFO: Resuming triggers.
[14:25:55.011] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[14:26:25.109] INFO: Resuming triggers.
[14:26:31.380] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[14:27:01.474] INFO: Resuming triggers.
[14:27:07.747] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[14:27:37.927] INFO: Resuming triggers.
[14:27:44.199] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[14:28:14.344] INFO: Resuming triggers.
[14:28:20.619] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[14:28:50.650] INFO: Resuming triggers.
[14:28:56.922] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[14:29:27.105] INFO: Resuming triggers.
[14:29:33.381] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[14:30:04.166] INFO: Resuming triggers.
[14:30:10.436] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[14:30:41.251] INFO: Resuming triggers.
[14:30:47.525] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[14:31:18.289] INFO: Resuming triggers.
[14:31:24.558] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[14:31:55.156] INFO: Resuming triggers.
[14:32:01.425] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[14:32:32.949] INFO: Resuming triggers.
[14:32:39.215] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[14:33:11.067] INFO: Resuming triggers.
[14:33:17.332] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[14:33:47.537] INFO: Resuming triggers.
[14:33:47.566] INFO: data taking finished, elapsed time: 100 seconds.
[14:33:48.246] INFO: PixTest:: pg_setup set to default.
[14:33:48.249] INFO: PixTestXray::doPhRun() done
[14:33:48.405] INFO: enter test to run
[14:34:25.315] INFO: test: HighRate no parameter change
[14:34:25.315] INFO: running: highrate
[14:34:25.327] INFO: ----------------------------------------------------------------------
[14:34:25.327] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:34:25.327] INFO: ----------------------------------------------------------------------
[14:34:25.482] INFO: Expecting 768 events.
[14:34:26.616] INFO: 768 events read in total (418ms).
[14:34:26.617] INFO: Test took 1269ms.
[14:34:27.419] INFO: Expecting 41600 events.
[14:34:30.538] INFO: 41600 events read in total (2592ms).
[14:34:30.539] INFO: Test took 3914ms.
[14:34:30.577] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:31.293] INFO: Expecting 41600 events.
[14:34:34.516] INFO: 41600 events read in total (2696ms).
[14:34:34.517] INFO: Test took 3922ms.
[14:34:34.554] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:35.275] INFO: Expecting 41600 events.
[14:34:38.533] INFO: 41600 events read in total (2731ms).
[14:34:38.534] INFO: Test took 3962ms.
[14:34:38.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:39.288] INFO: Expecting 41600 events.
[14:34:42.556] INFO: 41600 events read in total (2741ms).
[14:34:42.557] INFO: Test took 3965ms.
[14:34:42.594] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:43.313] INFO: Expecting 41600 events.
[14:34:46.588] INFO: 41600 events read in total (2748ms).
[14:34:46.589] INFO: Test took 3977ms.
[14:34:46.627] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:47.346] INFO: Expecting 41600 events.
[14:34:50.607] INFO: 41600 events read in total (2734ms).
[14:34:50.608] INFO: Test took 3963ms.
[14:34:50.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:51.364] INFO: Expecting 41600 events.
[14:34:54.625] INFO: 41600 events read in total (2734ms).
[14:34:54.626] INFO: Test took 3961ms.
[14:34:54.664] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:55.379] INFO: Expecting 41600 events.
[14:34:58.659] INFO: 41600 events read in total (2753ms).
[14:34:58.660] INFO: Test took 3976ms.
[14:34:58.698] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:59.417] INFO: Expecting 41600 events.
[14:35:02.690] INFO: 41600 events read in total (2746ms).
[14:35:02.691] INFO: Test took 3974ms.
[14:35:02.729] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:03.444] INFO: Expecting 41600 events.
[14:35:06.724] INFO: 41600 events read in total (2753ms).
[14:35:06.725] INFO: Test took 3977ms.
[14:35:06.763] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:07.481] INFO: Expecting 41600 events.
[14:35:10.754] INFO: 41600 events read in total (2746ms).
[14:35:10.755] INFO: Test took 3972ms.
[14:35:10.792] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:11.511] INFO: Expecting 41600 events.
[14:35:14.789] INFO: 41600 events read in total (2751ms).
[14:35:14.790] INFO: Test took 3977ms.
[14:35:14.828] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:15.546] INFO: Expecting 41600 events.
[14:35:18.818] INFO: 41600 events read in total (2745ms).
[14:35:18.819] INFO: Test took 3971ms.
[14:35:18.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:19.573] INFO: Expecting 41600 events.
[14:35:22.850] INFO: 41600 events read in total (2750ms).
[14:35:22.851] INFO: Test took 3975ms.
[14:35:22.890] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:23.606] INFO: Expecting 41600 events.
[14:35:26.897] INFO: 41600 events read in total (2764ms).
[14:35:26.898] INFO: Test took 3988ms.
[14:35:26.934] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:27.655] INFO: Expecting 41600 events.
[14:35:30.927] INFO: 41600 events read in total (2745ms).
[14:35:30.928] INFO: Test took 3974ms.
[14:35:30.966] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:31.682] INFO: Expecting 41600 events.
[14:35:34.950] INFO: 41600 events read in total (2741ms).
[14:35:34.951] INFO: Test took 3965ms.
[14:35:34.988] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:35.709] INFO: Expecting 41600 events.
[14:35:38.982] INFO: 41600 events read in total (2746ms).
[14:35:38.983] INFO: Test took 3974ms.
[14:35:39.020] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:39.741] INFO: Expecting 41600 events.
[14:35:43.009] INFO: 41600 events read in total (2741ms).
[14:35:43.010] INFO: Test took 3971ms.
[14:35:43.048] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:43.764] INFO: Expecting 41600 events.
[14:35:46.946] INFO: 41600 events read in total (2655ms).
[14:35:46.947] INFO: Test took 3879ms.
[14:35:46.984] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:47.351] INFO: enter test to run
[14:36:14.306] INFO: test: HighRate no parameter change
[14:36:14.306] INFO: running: highrate
[14:36:14.307] INFO: ----------------------------------------------------------------------
[14:36:14.308] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:36:14.308] INFO: ----------------------------------------------------------------------
[14:36:14.930] INFO: Expecting 208000 events.
[14:36:26.987] INFO: 208000 events read in total (11530ms).
[14:36:26.991] INFO: Test took 12672ms.
[14:36:27.153] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:27.413] INFO: number of dead pixels (per ROC): 0 0 0 0 51 0 0 0 0 0 0 0 0 0 0 0
[14:36:27.413] INFO: number of red-efficiency pixels: 107 63 103 156 195 197 142 119 124 177 124 122 175 88 27 24
[14:36:27.413] INFO: number of X-ray hits detected: 71486 47997 73898 113439 123488 129790 124262 88833 89041 116207 115419 103865 106126 61269 23621 27295
[14:36:27.413] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:36:27.413] INFO: number of Vcal hits detected: 207889 207935 207892 207839 205254 207795 207853 207879 207872 207819 207875 207877 207818 207911 207973 207976
[14:36:27.413] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[14:36:27.413] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 98.7 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[14:36:27.413] INFO: X-ray hit rate [MHz/cm2]: 21.0 14.1 21.7 33.2 36.2 38.0 36.4 26.0 26.1 34.1 33.8 30.4 31.1 18.0 6.9 8.0
[14:36:27.413] INFO: PixTestHighRate::doXPixelAlive() done
[14:36:27.460] INFO: PixTest:: pg_setup set to default.
[14:36:27.474] INFO: enter test to run
[14:36:51.218] INFO: test: HighRate no parameter change
[14:36:51.218] INFO: running: highrate
[14:36:51.219] INFO: ----------------------------------------------------------------------
[14:36:51.219] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:36:51.219] INFO: ----------------------------------------------------------------------
[14:36:51.836] INFO: Expecting 208000 events.
[14:37:05.826] INFO: 208000 events read in total (13463ms).
[14:37:05.832] INFO: Test took 14602ms.
[14:37:06.148] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:06.460] INFO: number of dead pixels (per ROC): 0 0 0 0 52 0 0 0 0 0 0 0 0 0 0 0
[14:37:06.460] INFO: number of red-efficiency pixels: 273 151 367 547 608 711 487 374 337 582 450 346 475 255 63 82
[14:37:06.460] INFO: number of X-ray hits detected: 143161 96259 148898 227971 248893 261528 250451 178790 180583 235652 233548 208365 212685 122989 47502 54616
[14:37:06.460] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:37:06.460] INFO: number of Vcal hits detected: 207703 207846 207583 207402 204790 207191 207461 207593 207635 207354 207505 207618 207480 207728 207934 207915
[14:37:06.460] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.6 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[14:37:06.460] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 98.5 99.6 99.7 99.8 99.8 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[14:37:06.460] INFO: X-ray hit rate [MHz/cm2]: 42.0 28.2 43.6 66.8 73.0 76.7 73.4 52.4 52.9 69.1 68.5 61.1 62.3 36.0 13.9 16.0
[14:37:06.460] INFO: PixTestHighRate::doXPixelAlive() done
[14:37:06.515] INFO: PixTest:: pg_setup set to default.
[14:37:06.531] INFO: enter test to run
[14:37:39.858] INFO: test: HighRate no parameter change
[14:37:39.858] INFO: running: highrate
[14:37:39.859] INFO: ----------------------------------------------------------------------
[14:37:39.859] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:37:39.859] INFO: ----------------------------------------------------------------------
[14:37:40.480] INFO: Expecting 208000 events.
[14:37:56.724] INFO: 208000 events read in total (15717ms).
[14:37:56.732] INFO: Test took 16860ms.
[14:37:57.231] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:57.596] INFO: number of dead pixels (per ROC): 0 0 0 0 52 0 0 0 0 0 0 0 0 0 0 0
[14:37:57.596] INFO: number of red-efficiency pixels: 597 307 787 1331 1467 1650 1116 937 836 1416 1006 802 1160 608 95 109
[14:37:57.596] INFO: number of X-ray hits detected: 219834 146108 228194 348501 379117 397191 380019 273296 275845 359840 355204 318237 322898 188980 72889 84106
[14:37:57.596] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:37:57.596] INFO: number of Vcal hits detected: 207260 207647 206897 206239 203497 205629 206597 206752 206969 206076 206734 207005 206552 207225 207904 207889
[14:37:57.596] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.5 99.2 99.2 99.0 99.4 99.5 99.5 99.2 99.5 99.6 99.4 99.7 100.0 99.9
[14:37:57.596] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.5 99.2 97.8 98.9 99.3 99.4 99.5 99.1 99.4 99.5 99.3 99.6 100.0 99.9
[14:37:57.596] INFO: X-ray hit rate [MHz/cm2]: 64.4 42.8 66.9 102.1 111.1 116.4 111.4 80.1 80.9 105.5 104.1 93.3 94.6 55.4 21.4 24.7
[14:37:57.596] INFO: PixTestHighRate::doXPixelAlive() done
[14:37:57.644] INFO: PixTest:: pg_setup set to default.
[14:37:57.657] INFO: enter test to run
[14:38:42.249] INFO: test: exit no parameter change
[14:38:42.582] QUIET: Connection to board 33 closed.
[14:38:42.597] INFO: pXar: this is the end, my friend