[13:13:57.229] INFO: *** Welcome to pxar ***
[13:13:57.229] INFO: *** Today: 2016/09/13
[13:13:58.445] INFO: *** Version: v1.9.0-818-g96727
[13:13:58.445] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//dacParameters35_C15.dat
[13:13:58.468] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:13:58.468] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//defaultMaskFile.dat
[13:13:58.474] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C15.dat
[13:13:58.573] INFO: clk: 4
[13:13:58.573] INFO: ctr: 4
[13:13:58.573] INFO: sda: 19
[13:13:58.573] INFO: tin: 9
[13:13:58.573] INFO: level: 15
[13:13:58.573] INFO: triggerdelay: 0
[13:13:58.573] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:13:58.573] INFO: Log level: INFO
[13:13:58.617] QUIET: Connection to board DTB_WREKRL opened.
[13:13:58.620] 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:13:58.623] INFO: RPC call hashes of host and DTB match: 398089610
[13:14:00.155] INFO: DUT info:
[13:14:00.155] INFO: The DUT currently contains the following objects:
[13:14:00.156] INFO: 2 TBM Cores tbm08c (2 ON)
[13:14:00.156] INFO: TBM Core alpha (0): 7 registers set
[13:14:00.156] INFO: TBM Core beta (1): 7 registers set
[13:14:00.156] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:14:00.156] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.156] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:14:00.567] INFO: enter 'restricted' command line mode
[13:14:00.567] INFO: enter test to run
[13:14:09.819] INFO: test: PixelAlive no parameter change
[13:14:09.819] INFO: running: pixelalive
[13:14:09.855] INFO: ----------------------------------------------------------------------
[13:14:09.855] 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:14:09.855] INFO: ----------------------------------------------------------------------
[13:14:10.169] INFO: Expecting 41600 events.
[13:14:14.488] INFO: 41600 events read in total (3601ms).
[13:14:14.656] INFO: Test took 4799ms.
[13:14:14.668] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:15.217] INFO: PixTestAlive::aliveTest() done
[13:14:15.217] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:14:15.263] INFO: enter test to run
[13:14:44.939] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:14:44.939] INFO: running: highrate
[13:14:44.939] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:14:45.206] INFO: ----------------------------------------------------------------------
[13:14:45.206] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:14:45.206] INFO: ----------------------------------------------------------------------
[13:14:45.206] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:14:45.206] INFO: edge/corner pixel THR is adjusted
[13:14:45.206] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:14:46.186] INFO: Collecting data for 5 seconds...
[13:14:51.203] INFO: Done with hot pixel readout
[13:15:02.459] INFO: PixTest:: pg_setup set to default.
[13:15:02.460] INFO: 14 hot pixels found in step 0
[13:15:03.470] INFO: Collecting data for 5 seconds...
[13:15:08.487] INFO: Done with hot pixel readout
[13:15:19.800] INFO: PixTest:: pg_setup set to default.
[13:15:19.801] INFO: 11 hot pixels found in step 1
[13:15:20.788] INFO: Collecting data for 5 seconds...
[13:15:25.805] INFO: Done with hot pixel readout
[13:15:37.073] INFO: PixTest:: pg_setup set to default.
[13:15:37.074] INFO: 10 hot pixels found in step 2
[13:15:38.061] INFO: Collecting data for 5 seconds...
[13:15:43.078] INFO: Done with hot pixel readout
[13:15:54.267] INFO: PixTest:: pg_setup set to default.
[13:15:54.268] INFO: 11 hot pixels found in step 3
[13:15:55.256] INFO: Collecting data for 5 seconds...
[13:16:00.272] INFO: Done with hot pixel readout
[13:16:11.461] INFO: PixTest:: pg_setup set to default.
[13:16:11.462] INFO: 4 hot pixels found in step 4
[13:16:12.449] INFO: Collecting data for 5 seconds...
[13:16:17.466] INFO: Done with hot pixel readout
[13:16:28.467] INFO: PixTest:: pg_setup set to default.
[13:16:28.467] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:16:28.467] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:16:28.468] INFO: 11 hot pixels found in step 5
[13:16:29.456] INFO: Collecting data for 5 seconds...
[13:16:34.473] INFO: Done with hot pixel readout
[13:16:45.521] INFO: PixTest:: pg_setup set to default.
[13:16:45.521] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:16:45.522] INFO: 8 hot pixels found in step 6
[13:16:46.508] INFO: Collecting data for 5 seconds...
[13:16:51.525] INFO: Done with hot pixel readout
[13:17:02.534] INFO: PixTest:: pg_setup set to default.
[13:17:02.534] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:17:02.534] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:17:02.535] INFO: 8 hot pixels found in step 7
[13:17:03.523] INFO: Collecting data for 5 seconds...
[13:17:08.539] INFO: Done with hot pixel readout
[13:17:19.660] INFO: PixTest:: pg_setup set to default.
[13:17:19.660] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:17:19.661] INFO: 7 hot pixels found in step 8
[13:17:20.648] INFO: Collecting data for 5 seconds...
[13:17:25.665] INFO: Done with hot pixel readout
[13:17:36.760] INFO: PixTest:: pg_setup set to default.
[13:17:36.760] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:17:36.760] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:17:36.761] INFO: 6 hot pixels found in step 9
[13:17:37.748] INFO: Collecting data for 5 seconds...
[13:17:42.764] INFO: Done with hot pixel readout
[13:17:53.846] INFO: PixTest:: pg_setup set to default.
[13:17:53.846] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:17:53.847] INFO: 4 hot pixels found in step 10
[13:17:54.834] INFO: Collecting data for 5 seconds...
[13:17:59.850] INFO: Done with hot pixel readout
[13:18:10.003] INFO: PixTest:: pg_setup set to default.
[13:18:10.003] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:18:10.004] INFO: 8 hot pixels found in step 11
[13:18:11.990] INFO: Collecting data for 5 seconds...
[13:18:17.008] INFO: Done with hot pixel readout
[13:18:28.182] INFO: PixTest:: pg_setup set to default.
[13:18:28.182] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:18:28.182] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:18:28.183] INFO: 2 hot pixels found in step 12
[13:18:28.214] INFO: 2 hot pixels could not be trimmed and have been masked.
[13:18:28.218] INFO: PixTest::trimHotPixels() done
[13:18:28.235] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C0.dat
[13:18:28.247] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C1.dat
[13:18:28.254] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C2.dat
[13:18:28.259] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C3.dat
[13:18:28.265] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C4.dat
[13:18:28.270] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C5.dat
[13:18:28.275] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C6.dat
[13:18:28.281] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C7.dat
[13:18:28.286] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C8.dat
[13:18:28.291] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C9.dat
[13:18:28.296] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C10.dat
[13:18:28.302] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C11.dat
[13:18:28.307] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C12.dat
[13:18:28.312] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C13.dat
[13:18:28.318] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C14.dat
[13:18:28.323] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C15.dat
[13:18:28.328] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//defaultMaskFile.dat
[13:18:28.343] INFO: enter test to run
[13:19:07.473] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:19:07.473] INFO: running: highrate
[13:19:07.478] INFO: ----------------------------------------------------------------------
[13:19:07.478] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:19:07.478] INFO: ----------------------------------------------------------------------
[13:19:07.478] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:19:07.478] INFO: edge/corner pixel THR is adjusted
[13:19:07.478] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:19:08.436] INFO: Collecting data for 1 seconds...
[13:19:09.440] INFO: Done with hot pixel readout
[13:19:09.722] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:19:09.722] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:19:09.722] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a044 80b1 43c8 43c8 43c8 43c8 a2 2462 8d 2083 14a 2462 850 2069 84d 284a 43c8 5a 2662 30c 2a40 43c8 43c8 4c 244d 43c8 6c2 2445 e000 c000
[13:19:09.722] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03e 8000 4388 415 2468 414 2262 69a 2647 4389 4388 438a 295 286f 549 2064 4388 d 2a4c 4388 34b 204a 75d 244e 852 2041 438a 744 204f 4388 88 244d 348 262d e000 c000
[13:19:09.722] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03f 8040 438a 35c 2066 85c 222d 4388 438a 4388 438a 438a 4388 28d 2647 40c 2a4f 438a e000 c000
[13:19:09.722] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a040 80b1 4388 41d 2064 4388 158 2a4c 4388 64d 2a42 4388 6cc 262c 4388 4388 689 2640 4389 2d0 2429 4388 413 2069 e000 c000
[13:19:09.723] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a041 80c0 4388 250 2841 413 226c 4388 4388 649 2a4e 814 284f 814 2440 81d 2662 4389 15a 284d 4389 148 284c 34b 244b 34a 2449 419 226e 751 204f 4389 49 284c d0 204d 11d 306d 45a 2a62 615 2f0f 809 225c 861 43c9 13 2865 452 246f e000 c000
[13:19:09.723] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a042 8000 43c8 35b 2a69 43c8 43c9 2dd 2461 84b 2247 43c9 15d 2a41 43c8 45b 244d 55b 2064 80c 222f 43c8 db 2465 43c8 2cd 2247 84b 2462 842 2843 842 2a4b 43c9 e000 c000
[13:19:09.723] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a043 8040 43c8 48b 2465 43c8 dd 2447 355 2042 514 2626 43c8 43c8 43c8 43c9 64a 2249 43c8 20a 2249 761 244f 43c9 293 2a65 e000 c000
[13:19:13.333] INFO: PixTest:: pg_setup set to default.
[13:19:13.333] INFO: 0 hot pixels found in step 0
[13:19:13.338] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:19:13.431] INFO: PixTest::trimHotPixels() done
[13:19:13.431] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C0.dat
[13:19:13.439] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C1.dat
[13:19:13.446] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C2.dat
[13:19:13.451] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C3.dat
[13:19:13.456] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C4.dat
[13:19:13.462] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C5.dat
[13:19:13.467] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C6.dat
[13:19:13.472] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C7.dat
[13:19:13.477] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C8.dat
[13:19:13.483] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C9.dat
[13:19:13.488] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C10.dat
[13:19:13.493] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C11.dat
[13:19:13.498] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C12.dat
[13:19:13.503] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C13.dat
[13:19:13.509] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C14.dat
[13:19:13.514] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C15.dat
[13:19:13.519] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-A-07_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//defaultMaskFile.dat
[13:19:13.528] INFO: enter test to run
[13:21:14.192] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:21:14.192] INFO: running: xray
[13:21:14.193] INFO: ----------------------------------------------------------------------
[13:21:14.193] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:21:14.193] INFO: ----------------------------------------------------------------------
[13:21:15.200] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:21:26.834] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:21:26.902] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 8 ROCs were found
[13:21:26.902] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 8 ROCs were found
[13:21:26.902] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:21:26.902] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a2 8000 43c8 43c8 43c9 43c9 43c8 43c8 20a 2249 609 2049 43c8 43c9 418 284f e000 c000
[13:21:26.902] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09c 80b1 4388 4388 4388 4388 6c8 262a 4388 4388 41c 282d 4388 4388 e000 c000
[13:21:26.902] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09d 80c0 4388 4388 4388 4388 4388 4388 418 222f 549 2863 4388 349 2a6f 4388 e000 c000
[13:21:26.902] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09e 8000 4388 4389 4388 438a 4388 2dd 2045 4388 438a 2d1 224c 4388 e000 c000
[13:21:26.903] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a09f 8040 438a 4388 438a 4388 438a 438a 4388 160d 2847 43ca 7ff 2fff cff 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 fff 2fff fff
[13:21:26.903] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a0 80b1 43c8 550 2864 43c8 43c8 43c8 112 2244 64c 244f 43c8 43c8 43c9 1d 2429 43c8 e000 c000
[13:21:26.903] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a1 80c0 43c8 258 2629 43c8 43c8 43c9 43c9 43c9 44a 2445 43c9 dd 2a43 43c9 e000 c000
[13:21:26.903] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:21:26.903] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a2 8000 43c8 43c8 43c9 43c9 43c8 43c8 43c8 43c9 e000 c000
[13:21:26.903] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19c 80b1 4388 85c 2a2c 4388 6da 2240 4388 4388 34a 264c 4388 438a 14a 242f 438a 4388 e000 c000
[13:21:26.903] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19d 80c0 4388 4388 4388 445 2a61 4dc 2869 4388 4388 4389 4389 4388 e000 c000
[13:21:26.903] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19e 8000 4388 4388 14b 2641 28b 204b 4388 48a 2062 4388 4388 4389 4389 4388 e000 c000
[13:21:26.903] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a19f 8040 438a 551 2660 438a 438a 438a 43ca 53c8 43c9 43ca 7ff 2fff 957 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 fff 2fff
[13:21:26.903] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a0 80b1 43c9 43c9 43c9 43c9 43c9 43c9 43c8 43c9 e000 c000
[13:21:26.903] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a1 80c0 43c8 43c8 43c8 311 2642 4d0 2647 43c8 43c9 43c8 43c8 43c9 e000 c000
[13:21:53.577] INFO: Resuming triggers.
[13:22:05.209] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:22:32.359] INFO: Resuming triggers.
[13:22:43.992] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:23:12.285] INFO: Resuming triggers.
[13:23:23.919] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[13:23:52.734] INFO: Resuming triggers.
[13:24:04.368] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[13:24:04.582] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:24:04.582] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[13:24:04.582] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:24:04.582] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bd 80c0 43c8 118 206b 43c8 21d 264d 43c8 43c8 4dd 264e 43c8 43c8 43c8 43c8 e000 c000
[13:24:04.583] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b7 8040 4388 22 2046 4388 21d 2a62 4388 4388 760 2644 4388 4388 4388 413 264d 4388 e000 c000
[13:24:04.583] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b8 80b1 4388 4388 4388 848 2645 4388 4388 4388 493 2041 4388 4388 e000 c000
[13:24:04.583] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0b9 80c0 4388 4388 4388 4388 70a 2225 4388 4388 4388 4388 54a 262c 6c4 2a42 e000 c000
[13:24:04.583] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ba 8000 4388 438a 43c8 40b 2245 43c9 517 2fff ff8 43c8 43c8 22 2241 43c8 e000 c000
[13:24:04.583] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bb 8040 43c8 153 2245 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:24:04.583] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bc 80b1 43c8 43c8 43c8 43c8 112 2a4f 43c8 861 2445 43c8 43c8 43c8 253 204f e000 c000
[13:24:04.583] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:24:04.583] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bd 80c0 43c8 43c8 43c8 285 2647 43c8 43c8 43c9 43c9 51d 284d 43c8 e000 c000
[13:24:04.583] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b7 8040 4388 640 262d 4388 1a 2442 6d1 2867 4388 4388 4388 4388 4388 4388 e000 c000
[13:24:04.583] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b8 80b1 4388 4388 4388 4cc 224a 4388 4388 4388 4388 4388 561 2a4c e000 c000
[13:24:04.583] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b9 80c0 4388 4388 4388 4388 358 2a40 4388 51a 2862 4388 4388 4388 e000 c000
[13:24:04.583] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ba 8000 4388 358 242e 43c8 43c8 43c9 9ee 2b6e 43c8 43c8 43c8 e000 c000
[13:24:04.583] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bb 8040 43c8 43c8 61a 2265 43c8 9d 2640 43c8 43c8 43c8 43c8 43c8 e000 c000
[13:24:04.583] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bc 80b1 43c8 43c8 43c8 4ca 2649 43c8 359 2040 43c8 43ca 43ca 43c8 e000 c000
[13:24:33.151] INFO: Resuming triggers.
[13:24:44.779] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[13:25:13.167] INFO: Resuming triggers.
[13:25:24.798] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[13:25:53.109] INFO: Resuming triggers.
[13:26:04.736] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[13:26:33.083] INFO: Resuming triggers.
[13:26:40.362] INFO: data taking finished, elapsed time: 100 seconds.
[13:26:58.528] INFO: PixTest:: pg_setup set to default.
[13:26:58.532] INFO: PixTestXray::doPhRun() done
[13:26:58.802] INFO: enter test to run
[13:27:55.110] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:27:55.110] INFO: running: xray
[13:27:55.111] INFO: ----------------------------------------------------------------------
[13:27:55.111] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:27:55.111] INFO: ----------------------------------------------------------------------
[13:27:56.074] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:28:02.858] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:28:32.905] INFO: Resuming triggers.
[13:28:39.688] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[13:28:39.721] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:28:39.722] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c3 8040 43c8 35a 2446 414 2449 43c9 50c 2641 43c8 150 262f 31b 2265 43c9 31a 2a4d 43c8 249 2669 509 28a5 845 226a 43c8 43c8 2a0 2441 43c9 852 222c e000 c000
[13:28:39.722] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bd 80c0 4388 553 2864 4388 6d5 2065 80b 244f 84a 284f 4388 511 224b 753 2887 4388 d 2045 601 244f 708 244b 4388 4389 4389 4388 e000 c000
[13:28:39.722] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1be 8000 4388 292 284f 74d 244f 4388 150 222e 4388 4c2 268d 4dd 2a83 4388 4388 93 224f 542 2a4f 840 2641 4389 31c 2245 34c 2a45 4389 4388 494 2a4b e000 c000
[13:28:39.722] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bf 8040 438a 4cc 2a2d 438a 438a 20b 2a6d 444 266d 438a e2 2664 54d 2467 710 2a4b 438a 11 2a45 35d 2a4a 4388 20b 206a 4389 438a e000 c000
[13:28:39.722] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c0 80b1 4389 2cc 204f 4389 15c 2240 4389 14c 2a2e 4389 4389 94 2249 43c9 43c8 53c9 19 2f82 fea e000 c000
[13:28:39.722] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c1 80c0 43c8 41b 2665 4cd 2446 751 224a 43c8 43c8 2d8 204d 513 2449 750 2449 851 224f 43c8 8 2042 318 2660 43c9 6d5 286f 43c8 43c8 43c9 6d5 264b e000 c000
[13:28:39.722] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c2 8000 43c8 299 2662 43c8 48b 284d 50a 262f 722 2a4e 43c9 145 2a48 148 222c 440 264a 854 2861 43c9 43c8 143 224e 43c8 43c8 43c9 e000 c000
[13:29:09.639] INFO: Resuming triggers.
[13:29:16.424] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[13:29:46.365] INFO: Resuming triggers.
[13:29:53.148] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[13:30:22.793] INFO: Resuming triggers.
[13:30:29.576] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:30:59.322] INFO: Resuming triggers.
[13:31:06.109] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[13:31:36.072] INFO: Resuming triggers.
[13:31:42.859] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:31:42.968] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:31:42.968] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (5) != Token Chain Length (8)
[13:31:42.968] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:31:42.968] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08c 80b1 43c8 43c8 43c8 43c8 94 224c 51b 244c 43c8 14a 2847 4c2 226b 43c8 24b 2068 319 286d 43c8 15c 2040 25c 2421 284 2042 84c 2a4c 43c8 5a 2447 51a 2864 e000 c000
[13:31:42.968] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a086 8000 4388 4388 4388 6d3 244f 4388 315 224d 408 282c 4388 4388 4d3 2a47 4388 504 2246 649 204f 742 224f 4388 418 2a64 e000 c000
[13:31:42.968] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a087 8040 4388 4388 560 2225 4388 4388 4388 65a 2065 821 2868 4388 284 262f 4388 2d8 264d 400 2667 4388 560 2042 e000 c000
[13:31:42.968] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a088 80b1 4388 12 2842 4388 1b 2442 4388 80d 2441 4388 4388 9 2067 4388 85 284c 48a 2449 4a1 2a6c 609 2242 850 202a 4388 75c 2461 4388 e000 c000
[13:31:42.968] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a089 80c0 4388 20 2062 258 2a2a 4388 4388 4388 2c2 284f 70d 2849 4388 4d9 2a45 712 2463 751 3865 43c8 848 202a 2ae 3fc3 fff 2f05 73f 2fca 533 2ff8 252 284c e000 c000
[13:31:42.968] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08a 8000 43c8 154 2645 293 2863 858 2642 43ca 43c8 45a 2468 852 2241 43c8 43c8 245 2669 440 2649 43c8 18 2847 40d 2069 4d4 2665 719 204b 43c8 4a 2a65 21a 2445 20d 2066 759 2269 43c8 d3 264f 222 2865 208 2045 e000 c000
[13:31:42.968] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a08b 8040 43c8 43c8 43c8 43c8 15a 244d 43c8 304 2640 30b 2a47 710 2065 43c8 749 206d 43c8 442 2845 684 202f 43c8 e000 c000
[13:31:42.968] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:31:42.969] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a18c 80b1 43c8 802 224f 43c8 21b 2648 43c8 43c8 15 224f 43c8 59 2441 4d2 262a 43ca 211 2464 2e1 224f 43ca 43c8 e000 c000
[13:31:42.969] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a186 8000 4388 64d 264a 71a 2225 4388 34b 224f 60d 204d 71b 2441 811 284c 4388 20 2862 4388 4388 40d 2245 460 244a 60d 2640 4388 28b 224f 4388 4388 e000 c000
[13:31:42.969] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a187 8040 4388 311 2246 4388 4388 60 2a49 cc 2040 50d 2040 4388 50d 264f 541 2a64 6d4 2866 4388 4388 4388 4388 e000 c000
[13:31:42.969] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a188 80b1 4388 4388 92 264f 553 2448 4388 705 2246 855 284f 4388 853 264f 4388 13 206d 805 206a 4388 2da 202a 404 2263 4388 4388 e000 c000
[13:31:42.969] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a189 80c0 4388 203 2664 31c 284a 4388 4388 4da 2468 805 2863 4388 d 2846 19 2a62 2da 2665 53c8 d8 2a2b 84f 200f 1026 2f6a e7b 2fea bde 2a82 e000 c000
[13:31:42.969] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a18a 8000 43c8 4da 224f 43c8 1c 226e 2da 2267 43c8 43c8 43c8 222 202c 24a 2a4d 305 2a49 420 284f 84d 226f 43c8 249 2048 349 2a4f 43c8 860 2647 43c8 19 2a4b e000 c000
[13:31:42.969] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a18b 8040 43c8 11b 266c 43c8 43c8 43c8 6d2 2447 43c8 721 2646 742 2a42 43c8 43c8 51c 2845 51c 2447 43c8 e000 c000
[13:32:12.548] INFO: Resuming triggers.
[13:32:19.335] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[13:32:49.553] INFO: Resuming triggers.
[13:32:56.341] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[13:32:56.522] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[13:32:56.522] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a087 8040 43c8 35b 2a67 43c8 2c2 2249 43c8 55b 2449 43c8 43c8 498 284d 43c8 43c8 51 2845 318 226c 702 224d 43c8 c 222b 411 2869 e000 c000
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a081 80c0 4388 4388 4388 70d 282f 4389 214 2845 4389 19 2062 552 2461 4389 4389 208 2829 4389 e000 c000
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a082 8000 4388 283 2a65 4388 292 2a45 4389 4389 41a 2245 614 2467 4388 4388 81c 2661 4388 4cd 2867 4cc 204a 4389 8c 2841 e000 c000
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a083 8040 4388 158 2246 4388 4388 4388 415 202f 4388 2c9 2465 4389 6cc 220f 4388 110 262d 4389 e000 c000
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a084 80b1 4388 2d9 2649 4388 19 2061 28d 2849 4388 4388 60a 2649 4388 618 2662 755 204b 4388 4388 53 284f 2d0 2445 1650 2045 6af 2020 262 e000 c000
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a085 80c0 43c8 152 2842 213 2082 43c8 43c8 43c8 212 2064 6d3 2265 43c8 84c 2042 43c8 43c8 43c8 88 224a 99 2a80 544 222c e000 c000
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a086 8000 43c8 4d3 2064 43c8 43c8 43c8 815 2240 43c8 5b 2a4f 451 224d 454 262f 43c8 119 242e 105 2a45 40b 2463 494 222e 70b 224c 43c8 459 2645 502 264d 43c8 b 2665 58 2645 e000 c000
[13:32:56.522] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a187 8040 43c8 70b 2a40 75a 224a 43c8 91 244f 6cc 204c 43c8 222 226b 54c 264b 43c8 551 2645 43c8 0 242b 60b 264f 43c8 c9 2a41 43c8 43c8 e000 c000
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a181 80c0 4388 4388 4cb 2646 4388 a 264b 4d2 244f 744 2446 4388 4389 19 244f 4388 284 2042 4388 4389 e000 c000
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a182 8000 4388 15 2445 553 248c 712 2642 85b 224e 4388 2cd 2a62 315 244f 4389 503 2a65 4389 4388 282 2047 4388 105 264f 4388 85c 2229 4389 e000 c000
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a183 8040 4388 80a 224f 4389 9a 2865 4dc 2247 641 284f 64a 226e 4388 4389 4388 4388 4388 304 2a24 4389 1a 2842 e000 c000
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a184 80b1 4388 4388 4388 4c 222d 4388 31a 2865 460 206f 44b 248c 4388 20 2064 2d8 2044 70b 284c 4388 450 2845 43c8 53c8 e020 c0ff
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a185 80c0 43c8 43c8 43c8 43c8 43c8 250 2061 43c8 43c8 85b 2442 43c8 e000 c000
[13:32:56.522] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a186 8000 43c8 8b 2244 c9 2a41 483 2061 43c8 43c8 4d 2a48 43c8 43c8 54a 284c 43c8 49b 2a85 43c8 43c8 e000 c000
[13:33:25.862] INFO: Resuming triggers.
[13:33:32.651] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[13:34:01.819] INFO: Resuming triggers.
[13:34:08.607] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[13:34:37.987] INFO: Resuming triggers.
[13:34:44.776] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[13:34:44.922] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:34:44.923] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a053 8040 43c8 62 2068 29c 2847 43c8 43c8 49b 2262 43c8 84 2061 43c8 15 2268 c5 2a40 285 244f 43c9 203 246b 4d3 2247 43c8 43c9 e000 c000
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04d 80c0 4388 4388 14a 264f 4388 819 2242 4388 4388 31c 204f 85c 2041 858 2a4e 4388 40c 264f 484 2641 55a 2448 74c 2440 4388 4388 e000 c000
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04e 8000 4388 65a 284a 4389 4388 489 222d 438a 6d4 222c 4388 4388 74b 2a49 438a 4388 259 2069 35d 2081 e000 c000
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04f 8040 438a 4388 241 284f 438a 4388 438a 413 2485 438a 8a 224e c8 2640 4388 65d 284d 438a 10a 2620 31a 2269 e000 c000
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a050 80b1 4388 10d 2265 210 2065 4da 2460 4388 4388 35b 2a4d 54b 284f 61c 2641 4388 4388 4c8 2a23 4cc 2642 43c8 60b 2046 455 43c8 ca 2645 e000 c000
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a051 80c0 43c8 158 202c 54b 2461 43c8 43c8 511 282d 512 2a40 43c9 43c9 649 2449 43c9 19 204d 60b 244f 712 2229 43c9 21b 2046 280 262f 485 2645 43c9 e000 c000
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a052 8000 43c8 492 224d 43c8 43c9 43c9 43c8 43c8 4c 222c 65a 2a40 715 2846 708 2242 43c8 302 264d 449 2841 43c9 152 20a5 e000 c000
[13:34:44.923] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a153 8040 43c8 43c9 1c 2480 608 2645 43c8 50a 244f 50a 2047 43c9 43c8 4d4 2026 43c8 240 2441 43c8 43c9 e000 c000
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a14d 80c0 4388 4388 4388 321 2247 343 2084 4388 21b 202f 4388 702 2840 4389 c8 2045 4389 4388 661 2265 e000 c000
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a14e 8000 4388 2cb 2264 4388 14b 2a25 61b 2867 4388 494 284f 754 2065 751 2a89 852 2a2c 4388 159 264c 314 2a48 499 2648 4388 4389 254 2a40 340 2a4d 4389 4388 22 2a4f e000 c000
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a14f 8040 438a 93 2a46 458 2a2d 438a 41d 2845 438a 510 2641 438a 48c 2044 438a 4388 4389 861 2665 438a e000 c000
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a150 80b1 4389 21c 2827 2dc 2269 303 266f 554 2669 848 222d 4389 4389 319 284f 4389 4389 4389 4388 4389 f000 c000
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a151 80c0 43c8 21c 2826 401 204b 43c8 54b 244d 43c8 9d 264d 43c8 52 2647 43c9 15a 242c 280 2662 501 266d 651 2a4a 708 2041 43c8 43c8 43c9 e000 c000
[13:34:44.923] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a152 8000 43c8 43c8 43c9 14 2041 43c9 43c8 43c8 249 2a49 49a 226d 43c8 6db 2645 43c9 e000 c000
[13:35:13.694] INFO: Resuming triggers.
[13:35:20.485] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[13:35:49.588] INFO: Resuming triggers.
[13:35:56.377] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[13:35:56.594] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 3 ROCs were found
[13:35:56.594] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (4) != Token Chain Length (8)
[13:35:56.595] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (118) != TBM ID (60)
[13:35:56.595] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[13:35:56.595] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (61) != TBM ID (118)
[13:35:56.595] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a178 80b1 43c8 43c8 45d 284d 43c8 43c8 211 262a 43c8 714 282c 43c8 9a 2040 45a 284c 43c8 43c8 e000 c000
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a172 8000 4388 4388 5 2047 59 2245 4389 85c 224a 4389 19 2644 4388 4388 4388 4389 e000 c000
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a173 8040 4388 2c1 284f 494 204e 493 2861 653 2866 4389 30b 224f 499 244f 560 2a45 70d 2224 820 2842 4388 818 2063 4389 252 224d 348 2840 85b 2865 4388 13 2842 4388 20d 264c 4388 4389 e000 c000
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a174 80b1 4388 553 2262 4388 480 282f 652 2862 4388 93 2845 619 2649 4388 54a 2044 713 2a81 81d 244f 84b 2864 4388 460 2064 51d 2867 4388 353 2447 4388 4388 e000 c000
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a175 80c0 4388 703 2044 43c8 1051 206f 43ca 20aa 7 20af 9 240c 2400 836 24ca a24 43c8 43c8 e000 c000
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a176 8000 43c8 400 2448 45a 264b 43c8 64c 2a4f 43c8 9a 2a49 220 226d 24b 2064 358 222f 815 2262 43c8 6d1 284d 849 2a4d 43c8 54 2021 452 244f 44a 2040 689 2045 85a 244f 43c8 4a0 2044 43c8 43c8 e000 c000
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a177 8040 43c8 259 2684 43c8 510 2a41 43c8 43c8 820 2485 43c8 43c8 43c8 43c8 50a 2465 e000 c000
[13:35:56.595] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a079 80c0 43c8 43c8 43c8 dc 2245 819 2647 43c8 220 2869 34b 286d 43c8 4c8 2444 751 2a64 43c8 43c8 4d2 224f 550 242f 43c8 e000 c000
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a074 80b1 4388 120 2649 4388 4388 4388 60 2840 55d 226d 608 2462 61d 2a69 4388 53 204f 4d 2465 95 2465 519 2065 4388 49 2640 422 244f 4388 2cd 2045 48b 246d 4388 34c 2645 e000 c000
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a075 80c0 4388 4388 121 2a4d 53c8 7ed 35bf
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== b03c 8028 abf 2553 fe1 2012 1a2 2006 43c8 e e000 c000
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a076 8000 43c8 43c8 43c8 4d2 284b 43c8 289 2485 43c8 30a 2642 508 2464 551 2840 848 2a65 43c8 13 2a4d 91 264f 109 2a62 6d5 284a 43c8 cd 262b 159 2445 2db 2068 45d 264f 43c8 e000 c000
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a077 8040 43c8 458 2265 43c8 14 242b 43c8 43c8 43c8 408 202f 405 2861 408 2a4a 682 2040 43c8 dc 244a cc 204a 30b 2864 43c8 551 2043 43c8 703 206c e000 c000
[13:35:56.595] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a078 80b1 43c8 e2 266a 43c8 43c8 340 2a25 492 2049 43c8 55 2a44 508 284b 69c 2042 74a 2a46 758 2a42 43c8 43c8 43c8 10a 2241 20a 2043 300 2a4f 85b 2668 43c8 e000 c000
[13:36:26.208] INFO: Resuming triggers.
[13:36:31.528] INFO: data taking finished, elapsed time: 100 seconds.
[13:36:55.125] INFO: PixTest:: pg_setup set to default.
[13:36:55.128] INFO: PixTestXray::doPhRun() done
[13:36:55.281] INFO: enter test to run
[13:37:44.354] INFO: test: HighRate no parameter change
[13:37:44.354] INFO: running: highrate
[13:37:44.445] INFO: ----------------------------------------------------------------------
[13:37:44.445] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:37:44.445] INFO: ----------------------------------------------------------------------
[13:37:44.770] INFO: Expecting 768 events.
[13:37:45.931] INFO: 768 events read in total (446ms).
[13:37:45.932] INFO: Test took 1316ms.
[13:37:46.734] INFO: Expecting 41600 events.
[13:37:49.842] INFO: 41600 events read in total (2581ms).
[13:37:49.843] INFO: Test took 3774ms.
[13:37:49.876] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:50.600] INFO: Expecting 41600 events.
[13:37:53.712] INFO: 41600 events read in total (2585ms).
[13:37:53.713] INFO: Test took 3820ms.
[13:37:53.747] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:54.470] INFO: Expecting 41600 events.
[13:37:57.705] INFO: 41600 events read in total (2708ms).
[13:37:57.706] INFO: Test took 3940ms.
[13:37:57.741] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:58.458] INFO: Expecting 41600 events.
[13:38:01.659] INFO: 41600 events read in total (2674ms).
[13:38:01.661] INFO: Test took 3902ms.
[13:38:01.699] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:02.415] INFO: Expecting 41600 events.
[13:38:05.553] INFO: 41600 events read in total (2612ms).
[13:38:05.554] INFO: Test took 3836ms.
[13:38:05.588] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:06.308] INFO: Expecting 41600 events.
[13:38:09.448] INFO: 41600 events read in total (2614ms).
[13:38:09.449] INFO: Test took 3842ms.
[13:38:09.483] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:10.208] INFO: Expecting 41600 events.
[13:38:13.360] INFO: 41600 events read in total (2625ms).
[13:38:13.361] INFO: Test took 3860ms.
[13:38:13.395] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:14.111] INFO: Expecting 41600 events.
[13:38:17.287] INFO: 41600 events read in total (2650ms).
[13:38:17.288] INFO: Test took 3874ms.
[13:38:17.322] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:18.034] INFO: Expecting 41600 events.
[13:38:21.239] INFO: 41600 events read in total (2678ms).
[13:38:21.240] INFO: Test took 3901ms.
[13:38:21.278] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:21.993] INFO: Expecting 41600 events.
[13:38:25.185] INFO: 41600 events read in total (2665ms).
[13:38:25.186] INFO: Test took 3887ms.
[13:38:25.220] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:25.939] INFO: Expecting 41600 events.
[13:38:29.087] INFO: 41600 events read in total (2621ms).
[13:38:29.088] INFO: Test took 3851ms.
[13:38:29.122] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:29.842] INFO: Expecting 41600 events.
[13:38:33.010] INFO: 41600 events read in total (2641ms).
[13:38:33.011] INFO: Test took 3871ms.
[13:38:33.045] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:33.767] INFO: Expecting 41600 events.
[13:38:36.974] INFO: 41600 events read in total (2681ms).
[13:38:36.975] INFO: Test took 3912ms.
[13:38:37.009] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:37.734] INFO: Expecting 41600 events.
[13:38:40.812] INFO: 41600 events read in total (2551ms).
[13:38:40.812] INFO: Test took 3785ms.
[13:38:40.848] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:41.571] INFO: Expecting 41600 events.
[13:38:44.727] INFO: 41600 events read in total (2629ms).
[13:38:44.728] INFO: Test took 3861ms.
[13:38:44.762] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:45.489] INFO: Expecting 41600 events.
[13:38:48.658] INFO: 41600 events read in total (2642ms).
[13:38:48.659] INFO: Test took 3880ms.
[13:38:48.693] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:49.415] INFO: Expecting 41600 events.
[13:38:52.575] INFO: 41600 events read in total (2633ms).
[13:38:52.576] INFO: Test took 3864ms.
[13:38:52.610] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:53.336] INFO: Expecting 41600 events.
[13:38:56.483] INFO: 41600 events read in total (2620ms).
[13:38:56.484] INFO: Test took 3857ms.
[13:38:56.518] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:57.241] INFO: Expecting 41600 events.
[13:39:00.383] INFO: 41600 events read in total (2615ms).
[13:39:00.384] INFO: Test took 3847ms.
[13:39:00.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:01.143] INFO: Expecting 41600 events.
[13:39:04.194] INFO: 41600 events read in total (2524ms).
[13:39:04.195] INFO: Test took 3755ms.
[13:39:04.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:04.595] INFO: enter test to run
[13:39:17.625] INFO: test: HighRate no parameter change
[13:39:17.625] INFO: running: highrate
[13:39:17.625] INFO: ----------------------------------------------------------------------
[13:39:17.625] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:39:17.625] INFO: ----------------------------------------------------------------------
[13:39:18.296] INFO: Expecting 208000 events.
[13:39:29.833] INFO: 208000 events read in total (11010ms).
[13:39:29.836] INFO: Test took 12144ms.
[13:39:29.978] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:30.231] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:39:30.231] INFO: number of red-efficiency pixels: 78 55 94 110 118 144 146 82 96 134 120 101 88 58 23 18
[13:39:30.231] INFO: number of X-ray hits detected: 65602 42185 63379 100556 106419 106641 107216 76853 74651 104067 103469 82000 85689 53720 19811 22124
[13:39:30.231] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:39:30.231] INFO: number of Vcal hits detected: 207922 207943 207903 207889 207880 207851 207848 207917 207900 207864 207874 207899 207912 207941 207977 207982
[13:39:30.231] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[13:39:30.231] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[13:39:30.231] INFO: X-ray hit rate [MHz/cm2]: 19.2 12.4 18.6 29.5 31.2 31.3 31.4 22.5 21.9 30.5 30.3 24.0 25.1 15.7 5.8 6.5
[13:39:30.231] INFO: PixTestHighRate::doXPixelAlive() done
[13:39:30.280] INFO: PixTest:: pg_setup set to default.
[13:39:30.293] INFO: enter test to run
[13:40:28.760] INFO: test: HighRate no parameter change
[13:40:28.760] INFO: running: highrate
[13:40:28.762] INFO: ----------------------------------------------------------------------
[13:40:28.762] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:40:28.762] INFO: ----------------------------------------------------------------------
[13:40:29.391] INFO: Expecting 208000 events.
[13:40:32.868] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (187) != TBM ID (188)
[13:40:32.868] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (187) != TBM ID (0)
[13:40:32.868] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[13:40:32.868] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (1) != TBM ID (188)
[13:40:32.868] WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!
[13:40:32.868] WARNING: Channel 0 ROC 6: Readback start marker after 15 readouts!
[13:40:32.869] WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!
[13:40:32.869] WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!
[13:40:32.869] WARNING: Channel 0 ROC 4: Readback start marker after 15 readouts!
[13:40:32.869] WARNING: Channel 0 ROC 5: Readback start marker after 15 readouts!
[13:40:32.869] WARNING: Channel 0 ROC 7: Readback start marker after 15 readouts!
[13:40:32.869] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:40:32.869] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bf 8040 43ca 15 2087 43ca 15 208f 11a 2a49 43ca 15 2082 43ca 15 2085 320 2a61 43ca 15 206f 43c8 15 2085 43c9 15 2085 43ca 15 2089 e002 c000
[13:40:32.869] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b9 80c0 4389 15 208c 159 2061 4389 15 208d 418 2845 81c 204b 4389 15 2083 4389 15 206f 4389 15 2082 85c 2041 4389 15 208d 40d 244f 40b 244d 4389 15 206f 4389 15 208d 251 2247 e002 c000
[13:40:32.869] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ba 8000 4388 15 2084 158 2081 4388 15 20a3 50b 244d 68d 262f 4388 15 20a1 498 2645 4388 298 2a4f 4388 15 208f 4388 15 2085 4388 15 2081 4388 15 2087 e002 c000
[13:40:32.869] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a100 807e 10 202a 2020 2a 43c8 15 208a cc 2a41 40c 260f 4c9 2880 43c8 18 204d 15 208b 699 2248 43c8 15 2087 103 2649 445 2265 692 2247 43c8 15 2089 43c8 15 206f 43c8 15 2085 e002 c000
[13:40:32.869] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bc 80b1 43c9 15 2089 4d0 2640 43c9 15 20a1 64c 2665 43c9 15 208f 43c9 15 2087 43c9 15 208b 43ca 15 208d a1 2247 43ca 15 2081 43c9 15 2083 e002 c000
[13:40:32.869] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bd 80c0 43c8 15 208d c3 224a 844 282d 43c8 15 208a 9 2449 43c8 15 208b 28a 2665 43c8 15 208b 61d 22a1 43c8 15 2085 43c9 15 208c 152 2244 43c9 15 2085 43c8 15 208d e002 c000
[13:40:32.869] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1be 8000 43c8 15 2082 43c8 15 208d 43c8 15 208a 43c8 15 206f 43c8 15 2089 151 2a47 43c9 15 208b 350 2669 43c9 15 2081 43c8 15 2089 e002 c000
[13:40:32.869] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:40:32.869] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c2 8000 43c8 15 2088 43c8 15 2089 51 2242 43c9 15 2088 54a 2249 43c9 15 20ad 94 2048 89 2261 34c 244d 43c8 15 208f 2d4 2665 493 204f 64d 2067 43c8 15 20a1 85c 2a28 43c8 15 2084 800 2449 850 2667 43c9 15 208b 35d 204f 45b 264f e002 c000
[13:40:32.869] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bc 80b1 43c9 15 208a 43c8 15 208d 43c9 15 208d 43c8 15 20a8 61a 2061 43c9 15 20a7 151 204e 820 244a 43c9 15 2085 855 222e 43c8 15 208d 43c9 15 208a 14 204a e002 c000
[13:40:32.869] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bd 80c0 43c8 15 208a 35b 2682 4c9 2a4f 43c8 15 208f 43c8 15 208d 43c8 15 208f 43c8 15 208d 59 286c 520 206b 43c8 15 208d 51a 204b 43c8 15 2085 483 244f 43c8 15 208c 14 2842 e002 c000
[13:40:32.869] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0be 8000 43c8 15 208a 43c9 15 20a1 43c8 15 208f 708 2244 85b 204d 43ca 15 20a4 c 2067 241 2a4f 43c8 15 20a0 84d 2a4f 43c8 15 20a3 615 224c 43ca 15 2083 43c8 15 2089 e002 c000
[13:40:32.870] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bf 8040 43ca 15 2082 43c8 15 2085 43ca 15 2089 441 2a67 501 2045 43c8 15 20a5 43ca 1a 2045 15 2087 860 226b 43ca 15 20a5 43c8 15 2088 43ca 15 208d e002 c000
[13:40:32.870] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c0 80b1 43c8 15 2084 6d9 224f 43c8 15 208a 43c8 15 208d 15c 2a6d 43c8 15 208f 85 20a1 4c5 2a47 43c8 15 2089 43c8 15 20a3 260 206c 721 2266 43c9 15 2089 34b 2481 43c8 15 20a7 e002 c000
[13:40:32.870] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c1 80c0 43c8 15 208a 43c8 19 202f 15 208f 43c8 15 2086 70c 242d 43c9 15 208f 50 2043 118 264f 30c 224d 43c9 15 20aa a 284e 43c9 15 208f 89 2846 349 2a65 43c9 15 2082 15a 2269 43c9 15 208b e002 c000
[13:40:32.870] WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!
[13:40:32.870] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:40:32.870] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cd 80c0 43c8 15 2088 212 266a 43c8 15 208f 43c8 15 208d 43c8 15 20a5 43c8 15 20a9 43c8 15 20a2 0 2846 43c8 15 206d 613 284d 719 204f 43c8 15 20a3 e002 c000
[13:40:32.870] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c7 8040 43c8 15 2089 701 224f 43c9 15 208f 43c8 15 2089 43c9 15 20ab 104 284f 43c8 15 20a5 43c8 15 208d 21c 246c 43c9 15 2085 688 2a4c 701 2449 851 264c 43c8 15 2089 e002 c000
[13:40:32.870] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c8 80b1 43c9 15 208d 43c8 15 208d 253 204f 43c9 15 208d 85c 2649 43c9 15 2087 753 286a 43c9 15 20a4 2dc 2643 505 266d 803 264f 43c9 15 208f 31c 2848 54b 2064 43c9 15 2081 43c9 15 20a0 15c 2861 e002 c000
[13:40:32.870] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c9 80c0 43c9 15 208a 43c8 15 2089 1d 224f 112 264f 43c9 15 208d 43c8 15 20a5 5b 2261 50d 2444 43c9 15 208d 845 2a4d 43c9 15 208d 699 244f 43c8 15 2089 43c9 15 20aa 41a 2a4c e002 c000
[13:40:32.870] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ca 8000 43c8 15 2081 43ca 15 2088 252 2a64 488 222f 43c8 15 208d 43c8 15 20a5 43c8 15 20a5 14 2847 312 224f 50c 2a65 43c8 15 2089 43c8 15 20a1 814 2661 43c8 15 208c e002 c000
[13:40:32.870] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cb 8040 43c8 15 2088 91 2845 43c8 15 208c 43c8 15 208f 43c9 15 20ab 64a 2861 43c8 15 20a3 43c8 15 208d 80d 2a64 43c9 15 2086 108 2a49 409 2664 43c8 15 208b 41b 266d 64c 2049 e002 c000
[13:40:32.870] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0cc 80b1 43c9 15 208d 43c8 15 208d 43c9 15 208b 755 264c 43c8 15 20a5 91 2265 43c9 15 208d 43c9 15 20a5 43c8 15 2081 43c9 15 20aa e002 c000
[13:40:42.004] INFO: 208000 events read in total (13086ms).
[13:40:43.009] INFO: Test took 14238ms.
[13:40:43.300] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:43.601] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:40:43.601] INFO: number of red-efficiency pixels: 4142 4132 4131 4141 4134 4142 4136 4139 242 426 380 345 257 212 42 46
[13:40:43.601] INFO: number of X-ray hits detected: 140977 91060 136185 212754 224262 224712 223464 163356 155265 216141 215153 170259 178122 111139 41225 46338
[13:40:43.601] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:40:43.601] INFO: number of Vcal hits detected: 203572 203746 203594 203530 203514 203472 203385 203588 207753 207543 207588 207633 207725 207774 207957 207952
[13:40:43.601] INFO: Vcal hit fiducial efficiency (%): 97.9 97.9 97.9 97.9 97.8 97.8 97.8 97.9 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[13:40:43.601] INFO: Vcal hit overall efficiency (%): 97.9 98.0 97.9 97.9 97.8 97.8 97.8 97.9 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[13:40:43.601] INFO: X-ray hit rate [MHz/cm2]: 41.3 26.7 39.9 62.4 65.7 65.9 65.5 47.9 45.5 63.4 63.1 49.9 52.2 32.6 12.1 13.6
[13:40:43.601] INFO: PixTestHighRate::doXPixelAlive() done
[13:40:43.646] INFO: PixTest:: pg_setup set to default.
[13:40:43.661] INFO: enter test to run
[13:41:12.296] INFO: test: HighRate no parameter change
[13:41:12.296] INFO: running: highrate
[13:41:12.297] INFO: ----------------------------------------------------------------------
[13:41:12.297] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:41:12.297] INFO: ----------------------------------------------------------------------
[13:41:12.914] INFO: Expecting 208000 events.
[13:41:28.165] INFO: 208000 events read in total (14724ms).
[13:41:28.173] INFO: Test took 15867ms.
[13:41:28.603] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:28.952] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:41:28.952] INFO: number of red-efficiency pixels: 557 279 581 771 835 877 1081 704 615 1130 829 742 615 506 73 90
[13:41:28.952] INFO: number of X-ray hits detected: 205558 132053 200088 315085 334319 335346 334001 241456 235199 326637 325898 258149 269304 168144 63430 70106
[13:41:28.952] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:41:28.952] INFO: number of Vcal hits detected: 207289 207691 207230 207112 207020 206957 206656 207137 207300 206558 206991 207099 207309 207385 207922 207910
[13:41:28.952] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.7 99.6 99.6 99.5 99.4 99.6 99.7 99.4 99.6 99.6 99.7 99.7 100.0 100.0
[13:41:28.952] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.6 99.6 99.5 99.5 99.4 99.6 99.7 99.3 99.5 99.6 99.7 99.7 100.0 100.0
[13:41:28.953] INFO: X-ray hit rate [MHz/cm2]: 60.3 38.7 58.6 92.4 98.0 98.3 97.9 70.8 68.9 95.7 95.5 75.7 78.9 49.3 18.6 20.5
[13:41:28.953] INFO: PixTestHighRate::doXPixelAlive() done
[13:41:28.002] INFO: PixTest:: pg_setup set to default.
[13:41:29.020] INFO: enter test to run
[13:41:56.672] INFO: test: exit no parameter change
[13:41:57.426] QUIET: Connection to board 33 closed.
[13:41:57.540] INFO: pXar: this is the end, my friend