[13:41:24.118] <TB2> INFO: *** Welcome to pxar ***
[13:41:24.118] <TB2> INFO: *** Today: 2016/09/05
[13:41:24.184] <TB2> INFO: *** Version: f5d3-dirty
[13:41:24.184] <TB2> INFO: readRocDacs: /usr/local/data/M2306_XrayQualification_2016-09-05_15h24m_1473081863//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2306_XrayQualification_2016-09-05_15h24m_1473081863//005_HRData_150//dacParameters35_C15.dat
[13:41:24.185] <TB2> INFO: readTbmDacs: /usr/local/data/M2306_XrayQualification_2016-09-05_15h24m_1473081863//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2306_XrayQualification_2016-09-05_15h24m_1473081863//005_HRData_150//tbmParameters_C0b.dat
[13:41:24.185] <TB2> INFO: readMaskFile: /usr/local/data/M2306_XrayQualification_2016-09-05_15h24m_1473081863//005_HRData_150//defaultMaskFile.dat
[13:41:24.185] <TB2> INFO: readTrimFile: /usr/local/data/M2306_XrayQualification_2016-09-05_15h24m_1473081863//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2306_XrayQualification_2016-09-05_15h24m_1473081863//005_HRData_150//trimParameters35_C15.dat
[13:41:24.266] <TB2> INFO: clk: 4
[13:41:24.266] <TB2> INFO: ctr: 4
[13:41:24.266] <TB2> INFO: sda: 19
[13:41:24.266] <TB2> INFO: tin: 9
[13:41:24.266] <TB2> INFO: level: 15
[13:41:24.266] <TB2> INFO: triggerdelay: 0
[13:41:24.266] <TB2> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[13:41:24.266] <TB2> INFO: Log level: INFO
[13:41:24.280] <TB2> INFO: Found DTB DTB_WXENWR
[13:41:24.293] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[13:41:24.295] <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:
------------------------------------------------------
[13:41:24.297] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[13:41:25.852] <TB2> INFO: DUT info:
[13:41:25.852] <TB2> INFO: The DUT currently contains the following objects:
[13:41:25.852] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[13:41:25.852] <TB2> INFO: TBM Core alpha (0): 7 registers set
[13:41:25.852] <TB2> INFO: TBM Core beta (1): 7 registers set
[13:41:25.852] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:41:25.852] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:25.852] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:41:26.253] <TB2> INFO: enter 'restricted' command line mode
[13:41:26.253] <TB2> INFO: enter test to run
[13:41:26.253] <TB2> INFO: test: delay setting parameters: ->5<-
[13:41:26.253] <TB2> INFO: delay test by 5 seconds...
[13:41:31.253] <TB2> INFO: enter test to run
[13:41:31.253] <TB2> INFO: test: Xray no parameter change
[13:41:31.253] <TB2> INFO: running: xray
[13:41:31.254] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2306_XrayQualification_2016-09-05_15h24m_1473081863//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2306_XrayQualification_2016-09-05_15h24m_1473081863//005_HRData_150//phCalibrationFitErr35_C15.dat
[13:41:31.385] <TB2> INFO: ######################################################################
[13:41:31.385] <TB2> INFO: PixTestXray::doTest()
[13:41:31.385] <TB2> INFO: ######################################################################
[13:41:31.385] <TB2> INFO: ----------------------------------------------------------------------
[13:41:31.385] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:41:31.386] <TB2> INFO: ----------------------------------------------------------------------
[13:41:32.108] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:41:35.396] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[13:42:04.408] <TB2> INFO: Resuming triggers.
[13:42:07.699] <TB2> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:42:36.726] <TB2> INFO: Resuming triggers.
[13:42:40.018] <TB2> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[13:43:09.244] <TB2> INFO: Resuming triggers.
[13:43:12.534] <TB2> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[13:43:42.834] <TB2> INFO: Resuming triggers.
[13:43:46.126] <TB2> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[13:44:14.572] <TB2> INFO: Resuming triggers.
[13:44:17.863] <TB2> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[13:44:46.594] <TB2> INFO: Resuming triggers.
[13:44:49.886] <TB2> INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:45:18.327] <TB2> INFO: Resuming triggers.
[13:45:21.617] <TB2> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[13:45:49.980] <TB2> INFO: Resuming triggers.
[13:45:53.268] <TB2> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[13:46:21.108] <TB2> INFO: Resuming triggers.
[13:46:24.398] <TB2> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[13:46:52.368] <TB2> INFO: Resuming triggers.
[13:46:55.659] <TB2> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[13:47:24.152] <TB2> INFO: Resuming triggers.
[13:47:27.437] <TB2> INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[13:47:57.658] <TB2> INFO: Resuming triggers.
[13:48:00.943] <TB2> INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[13:48:29.527] <TB2> INFO: Resuming triggers.
[13:48:32.814] <TB2> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[13:49:01.655] <TB2> INFO: Resuming triggers.
[13:49:04.940] <TB2> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[13:49:33.580] <TB2> INFO: Resuming triggers.
[13:49:36.867] <TB2> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[13:50:05.443] <TB2> INFO: Resuming triggers.
[13:50:08.729] <TB2> INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[13:50:37.996] <TB2> INFO: Resuming triggers.
[13:50:41.278] <TB2> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[13:51:11.950] <TB2> INFO: Resuming triggers.
[13:51:15.233] <TB2> INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[13:51:43.649] <TB2> INFO: Resuming triggers.
[13:51:46.927] <TB2> INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[13:52:16.503] <TB2> INFO: Resuming triggers.
[13:52:19.780] <TB2> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[13:52:47.255] <TB2> INFO: Resuming triggers.
[13:52:50.536] <TB2> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[13:53:18.466] <TB2> INFO: Resuming triggers.
[13:53:21.743] <TB2> INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[13:53:49.766] <TB2> INFO: Resuming triggers.
[13:53:53.049] <TB2> INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[13:54:22.430] <TB2> INFO: Resuming triggers.
[13:54:25.710] <TB2> INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:54:54.795] <TB2> INFO: Resuming triggers.
[13:54:58.074] <TB2> INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[13:55:26.482] <TB2> INFO: Resuming triggers.
[13:55:29.760] <TB2> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[13:55:58.960] <TB2> INFO: Resuming triggers.
[13:56:02.238] <TB2> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[13:56:30.241] <TB2> INFO: Resuming triggers.
[13:56:33.520] <TB2> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[13:57:03.524] <TB2> INFO: Resuming triggers.
[13:57:06.800] <TB2> INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[13:57:25.177] <TB2> WARNING: Detected DESER400 trailer error bits: "IDLE DATA"
[13:57:25.177] <TB2> ERROR: <datapipe.cc/CheckEventID:L469> Channel 1 Event ID mismatch: local ID (199) != TBM ID (200)
[13:57:25.177] <TB2> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[13:57:25.177] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1cb 8041 40c0 40c0 13 264c 18 2a6f 40c0 151 2267 40c0 291 264f e000 c000
[13:57:25.177] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1c5 80c1 40c0 10a 2044 648 2043 40c0 40c0 149 2a45 550 2462 548 2a41 655 2664 6db 264a 40c0 402 2264 548 2a47 e000 c000
[13:57:25.177] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1c6 8001 40c0 20c 264c 84c 2269 40c0 24b 2482 2dc 284f 40c0 108 2048 31c 2a82 508 2a42 40c1 fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[13:57:25.177] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1c7 8041 40c0 614 264a 67f 2cf7 b6a 2322 c26 2b41 bfc 2121 827 2342 b37 2bfc 11 2233 149 2234 9ae 2227 b08 2c27 bfc 20ae 936 2a86 524 2424 d31 2bff 0 207f fff 2fff f2ff d2ff
[13:57:25.177] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1c8 80b1 40c0 18 266e 604 2a4f 690 2a68 6c4 2a66 40c0 14 2049 c 2066 5 224f c3 266a 203 286f 4dd 2649 40c0 108 2842 29c 204a 50c 204f 712 242a 710 282b 862 2a4f 40c0 6dc 2669 e000 c000
[13:57:25.177] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1c9 80c1 40c0 11b 266f 4c4 286f 508 2460 60c 264c 64b 228e 40c0 83 2866 6cc 202e 40c0 6d3 2467 853 2666 40c0 71d 224f e000 c000
[13:57:25.177] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1ca 8001 40c0 24a 286e 319 2486 719 2642 40c0 40c0 413 226d 40c0 110 2662 21d 266e 542 2a4f 69c 286a 6c9 2863 fff 2fff fff 2fff fff 2fff e000 c000
[13:57:25.177] <TB2> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!
[13:57:25.177] <TB2> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!
[13:57:25.177] <TB2> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!
[13:57:25.177] <TB2> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!
[13:57:25.178] <TB2> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[13:57:25.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1d2 8001 40c0 211 2680 40c0 9c 2482 8a 2064 40c1 2cb 288c 35c 206e 40c1 645 206b fff 2fff fff 2fff fff 2fff e000 c000
[13:57:25.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1cc 80b1 40c0 d 206c 453 2486 40c0 43 2062 644 2648 840 264a 841 264a 40c0 652 2445 40c0 245 224b 660 2045 e000 c000
[13:57:25.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1cd 80c1 40c0 254 2842 44b 26a1 4a0 2264 80b 2489 840 266c 40c0 40c0 698 282e 40c0 804 2065 e000 c000
[13:57:25.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1ce 8001 40c0 40c0 64b 2081 40c0 40a 2668 41d 2a69 448 2842 645 2862 649 224a 649 264f 74c 2240 860 244f 40c0 c9 224b 242 2a64 2d1 266f 50a 224e 645 2a43 e000 c000
[13:57:25.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1cf 8041 40c2 70b 266f 40c2 8b 2a6c 40c2 88 2a6d 75b 28a0 40c2 1c 286f 153 2682 160 2688 45a 2264 48a 2a68 61d 2680 840 2880 858 2681 e000 c000
[13:57:25.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1d0 80b1 40c0 d 2448 162 2a6f 4d9 206f 740 262e 854 2a82 40c0 75d 2268 40c0 114 2a45 811 2840 40c0 310 206e 305 2488 4d9 2a62 760 2082 842 204b e000 c000
[13:57:25.178] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a1d1 80c1 40c1 60 2062 814 2040 40c1 40c1 40c1 71b 266d fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[13:57:34.927] <TB2> INFO: Resuming triggers.
[13:57:36.483] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[13:57:49.483] <TB2> INFO: PixTest:: pg_setup set to default.
[13:57:49.484] <TB2> INFO: PixTestXray::doPhRun() done
[13:57:49.484] <TB2> INFO: PixTestXray::doTest() done
[13:57:49.664] <TB2> INFO: enter test to run
[13:57:49.664] <TB2> INFO: test: no parameter change
[13:57:49.976] <TB2> QUIET: Connection to board 162 closed.
[13:57:50.056] <TB2> INFO: pXar: this is the end, my friend