[08:18:34.496] <TB2> INFO: *** Welcome to pxar ***
[08:18:34.496] <TB2> INFO: *** Today: 2016/05/04
[08:18:34.561] <TB2> INFO: *** Version: 2385
[08:18:34.561] <TB2> INFO: readRocDacs: /usr/local/data/M2220_XrayQualification_2016-05-04_10h00m_1462348822//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2220_XrayQualification_2016-05-04_10h00m_1462348822//005_HRData_150//dacParameters35_C15.dat
[08:18:34.562] <TB2> INFO: readTbmDacs: /usr/local/data/M2220_XrayQualification_2016-05-04_10h00m_1462348822//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2220_XrayQualification_2016-05-04_10h00m_1462348822//005_HRData_150//tbmParameters_C0b.dat
[08:18:34.562] <TB2> INFO: readMaskFile: /usr/local/data/M2220_XrayQualification_2016-05-04_10h00m_1462348822//005_HRData_150//defaultMaskFile.dat
[08:18:34.562] <TB2> INFO: readTrimFile: /usr/local/data/M2220_XrayQualification_2016-05-04_10h00m_1462348822//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2220_XrayQualification_2016-05-04_10h00m_1462348822//005_HRData_150//trimParameters35_C15.dat
[08:18:34.642] <TB2> INFO: clk: 4
[08:18:34.642] <TB2> INFO: ctr: 4
[08:18:34.642] <TB2> INFO: sda: 19
[08:18:34.642] <TB2> INFO: tin: 9
[08:18:34.642] <TB2> INFO: level: 15
[08:18:34.642] <TB2> INFO: triggerdelay: 0
[08:18:34.642] <TB2> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[08:18:34.642] <TB2> INFO: Log level: INFO
[08:18:34.655] <TB2> INFO: Found DTB DTB_WXENWR
[08:18:34.665] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[08:18:34.667] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[08:18:34.669] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[08:18:36.233] <TB2> INFO: DUT info:
[08:18:36.233] <TB2> INFO: The DUT currently contains the following objects:
[08:18:36.233] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:18:36.233] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:18:36.233] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:18:36.233] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:18:36.233] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.233] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.233] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.233] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.233] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.233] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.233] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.233] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.233] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.233] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.234] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.234] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.234] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.234] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.234] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.234] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:18:36.634] <TB2> INFO: enter 'restricted' command line mode
[08:18:36.634] <TB2> INFO: enter test to run
[08:18:36.634] <TB2> INFO: test: delay setting parameters: ->5<-
[08:18:36.634] <TB2> INFO: delay test by 5 seconds...
[08:18:41.635] <TB2> INFO: enter test to run
[08:18:41.635] <TB2> INFO: test: Xray no parameter change
[08:18:41.635] <TB2> INFO: running: xray
[08:18:41.635] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2220_XrayQualification_2016-05-04_10h00m_1462348822//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2220_XrayQualification_2016-05-04_10h00m_1462348822//005_HRData_150//phCalibrationFitErr35_C15.dat
[08:18:41.841] <TB2> INFO: ######################################################################
[08:18:41.841] <TB2> INFO: PixTestXray::doTest()
[08:18:41.841] <TB2> INFO: ######################################################################
[08:18:41.841] <TB2> INFO: ----------------------------------------------------------------------
[08:18:41.841] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:18:41.841] <TB2> INFO: ----------------------------------------------------------------------
[08:18:42.571] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:18:45.900] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[08:19:15.041] <TB2> INFO: Resuming triggers.
[08:19:18.384] <TB2> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[08:19:48.019] <TB2> INFO: Resuming triggers.
[08:19:51.360] <TB2> INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[08:20:20.699] <TB2> INFO: Resuming triggers.
[08:20:24.041] <TB2> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[08:20:53.677] <TB2> INFO: Resuming triggers.
[08:20:57.021] <TB2> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[08:21:25.872] <TB2> INFO: Resuming triggers.
[08:21:29.214] <TB2> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[08:21:58.124] <TB2> INFO: Resuming triggers.
[08:22:01.467] <TB2> INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[08:22:31.120] <TB2> INFO: Resuming triggers.
[08:22:34.464] <TB2> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[08:23:03.676] <TB2> INFO: Resuming triggers.
[08:23:07.015] <TB2> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[08:23:35.730] <TB2> INFO: Resuming triggers.
[08:23:39.069] <TB2> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[08:24:07.918] <TB2> INFO: Resuming triggers.
[08:24:11.256] <TB2> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[08:24:40.654] <TB2> INFO: Resuming triggers.
[08:24:43.992] <TB2> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[08:25:13.046] <TB2> INFO: Resuming triggers.
[08:25:16.384] <TB2> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[08:25:46.612] <TB2> INFO: Resuming triggers.
[08:25:49.948] <TB2> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[08:26:19.067] <TB2> INFO: Resuming triggers.
[08:26:22.403] <TB2> INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[08:26:52.997] <TB2> INFO: Resuming triggers.
[08:26:56.333] <TB2> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[08:27:26.485] <TB2> INFO: Resuming triggers.
[08:27:29.821] <TB2> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[08:27:59.809] <TB2> INFO: Resuming triggers.
[08:28:03.158] <TB2> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[08:28:33.445] <TB2> INFO: Resuming triggers.
[08:28:36.780] <TB2> INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[08:29:07.060] <TB2> INFO: Resuming triggers.
[08:29:10.394] <TB2> INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[08:29:40.172] <TB2> INFO: Resuming triggers.
[08:29:43.508] <TB2> INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[08:30:14.371] <TB2> INFO: Resuming triggers.
[08:30:17.704] <TB2> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[08:30:47.825] <TB2> INFO: Resuming triggers.
[08:30:51.160] <TB2> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[08:31:12.180] <TB2> WARNING: Detected DESER400 trailer error bits: "IDLE DATA"
[08:31:12.180] <TB2> ERROR: <datapipe.cc/CheckEventID:L469> Channel 1 Event ID mismatch: local ID (129) != TBM ID (130)
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a185 80c0 40e0 8a 2864 20c 284d 4d9 2063 521 2282 552 282a 609 206d 850 2665 40e0 249 246d 322 2865 322 2468 40e0 290 226d 48b 2285 521 2241 819 224f 40e0 20d 2684 494 2068 618 2463 e000 c000
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a17f 8040 4062 415 2864 442 224d 744 2649 843 2a4f 40e2 861 2460 84a 2a4f 4062 250 22a8 40e2 162 206d 405 226c 453 2681 845 2665 e000 c000
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a180 80b0 40e0 498 2265 610 2a67 4060 4060 4060 31b 2288 31d 228c 840 2447 84b 2661 fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a181 80c0 40e1 1c 282c 2c9 2649 608 2461 67f 2cd4 430 2930 3fc 2a2c a42 230c c24 2bfc a68 2517 b4a 2413 bfc 2a28 d26 2325 436 2b22 c26 2331 533 2b65 c34 2b61 b37 2bff 0 207f fff 2fff f2ff d2ff
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a182 8000 40e0 34c 244f 458 264d 613 206f 40e0 289 2a81 319 288c 65b 2665 40e1 15b 206d 640 2a2c 709 224f 4061 e000 c000
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a183 8040 40e0 345 2a64 40e1 445 2466 40e0 4061 a2 2863 292 2689 445 2861 440 2a67 602 244d 85c 204b e000 c000
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a184 80b0 40e0 30a 2669 65a 288e 40e0 15b 2465 361 24a6 54d 2249 64c 266f 64c 2a4a 40e0 315 204d 491 2468 64d 2a4b 40e0 c5 2a80 100 2a6c 309 2a64 483 228c 680 2446 710 2861 e000 c000
[08:31:12.180] <TB2> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!
[08:31:12.180] <TB2> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!
[08:31:12.180] <TB2> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!
[08:31:12.180] <TB2> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a192 8000 40e0 21b 244f 2cb 286c 495 266d 40e0 454 2a4f 6c5 2a49 40e1 10b 2682 84d 2047 840 242d 40e1 101 286d 2e2 286d 492 2265 509 2a84 561 2485 558 286d e000 c000
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a18c 80b0 40e0 821 244c 40e0 109 204f 251 2266 444 2861 493 248c 503 2280 692 2866 40e0 110 284c 110 244e 44c 224d 48b 2681 40e0 51d 206f 508 204f e000 c000
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a18d 80c0 40e0 81d 2464 802 2843 40e0 82 2244 2d9 244d 40e0 145 2a89 40e0 160 2266 300 2a4f fff 2fff fff 2fff e000 c000
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a18e 8000 40e0 120 206c 280 2460 40e0 c9 2069 145 2682 502 2463 40e0 149 2868 50a 242d 50a 204f 40e0 302 2a69 352 206d e000 c000
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a18f 8040 4062 748 2649 803 224d 85b 2a6b 40e2 619 2a6c 80a 2647 80b 2a65 40e2 102 2a4f 701 2449 40e2 d8 206c 35a 248f 34c 2264 843 246d e000 c000
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a190 80b0 40e0 55b 2044 40e0 64a 2847 40e0 0 264f d 2666 54b 2a49 40e0 144 244d 28a 2863 4c3 2245 510 284e 510 2461 749 2683 e000 c000
[08:31:12.180] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a191 80c0 4061 4d4 2645 71c 2045 40e1 80 262d 40e1 80c 2661 40e1 fff 2fff fff 2fff fff 2fff e000 c000
[08:31:21.880] <TB2> INFO: Resuming triggers.
[08:31:25.210] <TB2> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[08:31:55.923] <TB2> INFO: Resuming triggers.
[08:31:59.255] <TB2> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[08:32:28.514] <TB2> INFO: Resuming triggers.
[08:32:31.846] <TB2> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[08:33:00.172] <TB2> INFO: Resuming triggers.
[08:33:03.502] <TB2> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[08:33:32.953] <TB2> INFO: Resuming triggers.
[08:33:36.284] <TB2> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[08:34:06.752] <TB2> INFO: Resuming triggers.
[08:34:10.083] <TB2> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[08:34:41.654] <TB2> INFO: Resuming triggers.
[08:34:44.932] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[08:35:12.305] <TB2> INFO: PixTest:: pg_setup set to default.
[08:35:12.307] <TB2> INFO: PixTestXray::doPhRun() done
[08:35:12.307] <TB2> INFO: PixTestXray::doTest() done
[08:35:12.485] <TB2> INFO: enter test to run
[08:35:12.485] <TB2> INFO: test: no parameter change
[08:35:12.804] <TB2> QUIET: Connection to board 162 closed.
[08:35:12.887] <TB2> INFO: pXar: this is the end, my friend