[12:34:54.395] <TB1> INFO: *** Welcome to pxar ***
[12:34:54.395] <TB1> INFO: *** Today: 2016/09/29
[12:34:54.757] <TB1> INFO: *** Version: f5d3-dirty
[12:34:54.757] <TB1> INFO: readRocDacs: /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[12:34:54.758] <TB1> INFO: readTbmDacs: /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[12:34:54.758] <TB1> INFO: readMaskFile: /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[12:34:54.758] <TB1> INFO: readTrimFile: /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[12:34:54.829] <TB1> INFO: clk: 4
[12:34:54.829] <TB1> INFO: ctr: 4
[12:34:54.829] <TB1> INFO: sda: 19
[12:34:54.829] <TB1> INFO: tin: 9
[12:34:54.829] <TB1> INFO: level: 15
[12:34:54.829] <TB1> INFO: triggerdelay: 0
[12:34:54.829] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[12:34:54.829] <TB1> INFO: Log level: INFO
[12:34:54.843] <TB1> INFO: Found DTB DTB_WWXTQT
[12:34:54.852] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[12:34:54.854] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[12:34:54.855] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[12:34:56.384] <TB1> INFO: DUT info:
[12:34:56.384] <TB1> INFO: The DUT currently contains the following objects:
[12:34:56.384] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:34:56.384] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:34:56.384] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:34:56.384] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:34:56.384] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.384] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:56.785] <TB1> INFO: enter 'restricted' command line mode
[12:34:56.785] <TB1> INFO: enter test to run
[12:34:56.785] <TB1> INFO: test: HighRate no parameter change
[12:34:56.785] <TB1> INFO: running: highrate
[12:34:56.785] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[12:34:56.915] <TB1> INFO: ----------------------------------------------------------------------
[12:34:56.915] <TB1> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:34:56.915] <TB1> INFO: ----------------------------------------------------------------------
[12:34:57.010] <TB1> INFO: Expecting 768 events.
[12:34:57.999] <TB1> INFO: 768 events read in total (431ms).
[12:34:58.063] <TB1> INFO: Test took 1143ms.
[12:34:58.541] <TB1> INFO: Expecting 41600 events.
[12:35:01.286] <TB1> INFO: 41600 events read in total (2343ms).
[12:35:01.288] <TB1> INFO: Test took 3222ms.
[12:35:01.868] <TB1> INFO: Expecting 41600 events.
[12:35:04.705] <TB1> INFO: 41600 events read in total (2436ms).
[12:35:04.707] <TB1> INFO: Test took 3314ms.
[12:35:05.297] <TB1> INFO: Expecting 41600 events.
[12:35:08.509] <TB1> INFO: 41600 events read in total (2810ms).
[12:35:08.511] <TB1> INFO: Test took 3696ms.
[12:35:09.098] <TB1> INFO: Expecting 41600 events.
[12:35:12.173] <TB1> INFO: 41600 events read in total (2674ms).
[12:35:12.175] <TB1> INFO: Test took 3555ms.
[12:35:12.763] <TB1> INFO: Expecting 41600 events.
[12:35:15.708] <TB1> INFO: 41600 events read in total (2544ms).
[12:35:15.710] <TB1> INFO: Test took 3426ms.
[12:35:16.294] <TB1> INFO: Expecting 41600 events.
[12:35:17.669] <TB1> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[12:35:17.670] <TB1> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[12:35:17.670] <TB1> ERROR: <datapipe.cc/CheckEventID:L485> Channel 2 Event ID mismatch: local ID (126) != TBM ID (127)
[12:35:17.670] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!
[12:35:17.670] <TB1> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!
[12:35:17.670] <TB1> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!
[12:35:17.670] <TB1> ERROR: <datapipe.cc/CheckEventID:L485> Channel 3 Event ID mismatch: local ID (126) != TBM ID (127)
[12:35:17.670] <TB1> WARNING: Channel 3 ROC 0: Readback start marker after 15 readouts!
[12:35:17.670] <TB1> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[12:35:17.670] <TB1> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[12:35:17.670] <TB1> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a282 8001 40e0 151 22a8 40c0 151 228c 74d 2247 40e1 151 22ad 41c0 151 2288 559 284b e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a27c 80b1 40e0 4c 2442 151 22aa 41c0 b 2840 d 2a45 151 228c 41e0 151 22af 28c 2449 40c0 4b 204d 151 2288 354 224f 542 284f e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a27d 80c1 40c1 151 22a8 41e0 151 228d 162 2648 745 204e 40c1 1a 2862 14d 206c 14c 2886 151 22c3 41c3 15c 2040 151 228f e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a27e 8001 40e1 15b 286a 151 22a6 41b 2286 41c0 151 2283 84d 262e 653 2bfb 800 2000 688 27f9 151 2287 680 282f fc00 dc00
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a27f 8041 40e3 151 22ae 40c3 151 228a 4d3 2a2f 719 2449 40e3 82 2a6d 151 22c5 202 224f 41c0 151 2285 6cb 2681 e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a280 80b1 40e1 151 22a8 620 244e 41c1 151 2285 742 246a 41c1 5c 244f 151 22c2 2d0 2465 40e1 151 228a 291 2461 e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a281 80c1 40e0 151 22a5 40e0 51 2662 151 2289 41e0 151 22c0 41e1 151 228e 445 224f e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a382 8000 40e0 151 228e 848 204b 40c0 151 22c4 35c 202d 41c1 151 228a 41c1 e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a37c 80b0 40e0 151 228e 41e0 151 22af 41c1 151 2288 41e0 151 2283 e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a37d 80c0 40c0 151 228f 501 2a4e 41c0 151 22af 40c1 151 2286 522 2865 41e0 151 2281 e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a37e 8000 40e0 151 22a4 31d 2061 41c0 151 22ad 41c1 151 2283 41c1 5 2de5 aad 2353 fc00 dc00
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a37f 8040 40e2 c 2a4a 151 228a 41c3 c3 224f 151 22c1 40e3 151 2288 40e2 151 2283 e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a380 80b0 40e1 151 228f 600 2642 41c1 151 22af 40c1 151 228a 2cd 2868 514 2a46 41c1 151 2280 692 2443 e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a381 80c0 40e1 151 228c 2cc 2243 4c3 2460 55b 2260 41e1 c5 204a 151 22af 40e1 151 2288 41e1 151 2285 e002 c000
[12:35:17.670] <TB1> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a290 80b1 40e1 150 2684 41c1 a 2040 8 202f 150 2686 41c1 150 26a4 505 244f 41c1 18 264f 150 268d 25b 2068 354 2665 413 224f e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a28a 8001 40e0 150 2a82 40e0 150 2a6c 41e0 150 2aa9 40c1 44 2207 150 2a82 801 2a69 e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a28b 8041 40e0 150 2a8c 253 2aa0 41c0 1d 2426 18 2849 150 2a80 543 2446 850 2042 40e0 93 2488 150 2ac1 855 264a 40c0 150 2a6f e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a28c 80b1 40e0 4c 202a 150 2a8f 50c 262e 510 2a2a 60b 2042 40e0 150 2a6e 209 2a28 441 222f 6cc 2a22 759 264c 40c0 150 2aa9 698 2862 41e0 150 2a81 205 2829 e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a28d 80c1 41e1 158 244f 150 2aa1 34b 264a 861 224f 40e0 15a 284c 150 2a85 682 2a22 40c1 15 266a 150 2ac1 40c3 150 2a6f 49a 244a 699 2a4d e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a28e 8001 40e1 110 2446 150 2a8c 445 2065 40e0 150 2a65 480 2642 4da 242e 40e1 109 282f 150 2aa7 28c 286f 281 2a4a 40e1 150 2a6f 299 284f 754 2065 e002 c000
[12:35:17.670] <TB1> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a28f 8041 40c3 150 268c 4c1 284a 40e3 150 266d 41e3 150 26af 41c0 150 266d e002 c000
[12:35:19.327] <TB1> INFO: 41600 events read in total (2631ms).
[12:35:19.330] <TB1> INFO: Test took 3513ms.
[12:35:19.923] <TB1> INFO: Expecting 41600 events.
[12:35:22.925] <TB1> INFO: 41600 events read in total (2600ms).
[12:35:22.928] <TB1> INFO: Test took 3481ms.
[12:35:23.514] <TB1> INFO: Expecting 41600 events.
[12:35:26.459] <TB1> INFO: 41600 events read in total (2543ms).
[12:35:26.462] <TB1> INFO: Test took 3423ms.
[12:35:27.082] <TB1> INFO: Expecting 41600 events.
[12:35:30.354] <TB1> INFO: 41600 events read in total (2870ms).
[12:35:30.356] <TB1> INFO: Test took 3763ms.
[12:35:30.948] <TB1> INFO: Expecting 41600 events.
[12:35:33.851] <TB1> INFO: 41600 events read in total (2501ms).
[12:35:33.853] <TB1> INFO: Test took 3386ms.
[12:35:34.437] <TB1> INFO: Expecting 41600 events.
[12:35:37.370] <TB1> INFO: 41600 events read in total (2531ms).
[12:35:37.372] <TB1> INFO: Test took 3412ms.
[12:35:37.959] <TB1> INFO: Expecting 41600 events.
[12:35:40.913] <TB1> INFO: 41600 events read in total (2552ms).
[12:35:40.915] <TB1> INFO: Test took 3434ms.
[12:35:41.500] <TB1> INFO: Expecting 41600 events.
[12:35:44.553] <TB1> INFO: 41600 events read in total (2651ms).
[12:35:44.555] <TB1> INFO: Test took 3533ms.
[12:35:45.143] <TB1> INFO: Expecting 41600 events.
[12:35:48.073] <TB1> INFO: 41600 events read in total (2528ms).
[12:35:48.076] <TB1> INFO: Test took 3411ms.
[12:35:48.697] <TB1> INFO: Expecting 41600 events.
[12:35:51.870] <TB1> INFO: 41600 events read in total (2772ms).
[12:35:51.872] <TB1> INFO: Test took 3671ms.
[12:35:52.494] <TB1> INFO: Expecting 41600 events.
[12:35:55.455] <TB1> INFO: 41600 events read in total (2559ms).
[12:35:55.457] <TB1> INFO: Test took 3464ms.
[12:35:56.044] <TB1> INFO: Expecting 41600 events.
[12:35:58.987] <TB1> INFO: 41600 events read in total (2541ms).
[12:35:58.990] <TB1> INFO: Test took 3423ms.
[12:35:59.598] <TB1> INFO: Expecting 41600 events.
[12:36:02.616] <TB1> INFO: 41600 events read in total (2616ms).
[12:36:02.618] <TB1> INFO: Test took 3502ms.
[12:36:03.213] <TB1> INFO: Expecting 41600 events.
[12:36:06.154] <TB1> INFO: 41600 events read in total (2539ms).
[12:36:06.156] <TB1> INFO: Test took 3423ms.
[12:36:06.748] <TB1> INFO: Expecting 41600 events.
[12:36:09.759] <TB1> INFO: 41600 events read in total (2610ms).
[12:36:09.762] <TB1> INFO: Test took 3496ms.
[12:36:10.159] <TB1> INFO: enter test to run
[12:36:10.159] <TB1> INFO: test: Pretest no parameter change
[12:36:10.159] <TB1> INFO: running: pretest
[12:36:10.159] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[12:36:10.159] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[12:36:10.159] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[12:36:10.159] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[12:36:10.159] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[12:36:10.160] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[12:36:10.160] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[12:36:10.160] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[12:36:10.160] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[12:36:10.160] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[12:36:10.160] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[12:36:10.160] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[12:36:10.160] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[12:36:10.160] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[12:36:10.160] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[12:36:10.160] <TB1> INFO: write dac parameters into /usr/local/data/M2335_XrayQualification_2016-09-29_13h35m_1475148923//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[12:36:10.161] <TB1> INFO: enter test to run
[12:36:10.161] <TB1> INFO: test: no parameter change
[12:36:10.437] <TB1> QUIET: Connection to board 147 closed.
[12:36:10.517] <TB1> INFO: pXar: this is the end, my friend