[09:34:13.278] <TB0> INFO: *** Welcome to pxar ***
[09:34:13.278] <TB0> INFO: *** Today: 2016/01/19
[09:34:13.625] <TB0> INFO: *** Version: b2d3
[09:34:13.625] <TB0> INFO: readRocDacs: /usr/local/data/M2072_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2072_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//dacParameters35_C15.dat
[09:34:13.626] <TB0> INFO: readTbmDacs: /usr/local/data/M2072_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2072_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//tbmParameters_C0b.dat
[09:34:13.626] <TB0> INFO: readMaskFile: /usr/local/data/M2072_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//defaultMaskFile.dat
[09:34:13.626] <TB0> INFO: readTrimFile: /usr/local/data/M2072_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2072_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//trimParameters35_C15.dat
[09:34:13.694] <TB0> INFO: clk: 4
[09:34:13.694] <TB0> INFO: ctr: 4
[09:34:13.694] <TB0> INFO: sda: 19
[09:34:13.694] <TB0> INFO: tin: 9
[09:34:13.694] <TB0> INFO: level: 15
[09:34:13.694] <TB0> INFO: triggerdelay: 0
[09:34:13.694] <TB0> QUIET: Instanciating API for pxar prod-12
[09:34:13.694] <TB0> INFO: Log level: INFO
[09:34:13.707] <TB0> INFO: Found DTB DTB_WS6UZO
[09:34:13.719] <TB0> QUIET: Connection to board DTB_WS6UZO opened.
[09:34:13.722] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[09:34:13.724] <TB0> INFO: RPC call hashes of host and DTB match: 398089610
[09:34:15.260] <TB0> INFO: DUT info:
[09:34:15.260] <TB0> INFO: The DUT currently contains the following objects:
[09:34:15.260] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[09:34:15.260] <TB0> INFO: TBM Core alpha (0): 7 registers set
[09:34:15.260] <TB0> INFO: TBM Core beta (1): 7 registers set
[09:34:15.260] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:34:15.260] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.260] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:34:15.661] <TB0> INFO: enter 'restricted' command line mode
[09:34:15.661] <TB0> INFO: enter test to run
[09:34:15.661] <TB0> INFO: test: delay setting parameters: ->5<-
[09:34:15.661] <TB0> INFO: delay test by 5 seconds...
[09:34:20.661] <TB0> INFO: enter test to run
[09:34:20.661] <TB0> INFO: test: Xray no parameter change
[09:34:20.661] <TB0> INFO: running: xray
[09:34:20.661] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2072_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2072_XrayQualification_2016-01-19_10h15m_1453194949//004_HRData_150//phCalibrationFitErr35_C15.dat
[09:34:20.792] <TB0> INFO: ######################################################################
[09:34:20.792] <TB0> INFO: PixTestXray::doTest()
[09:34:20.792] <TB0> INFO: ######################################################################
[09:34:20.792] <TB0> INFO: ----------------------------------------------------------------------
[09:34:20.792] <TB0> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:34:20.792] <TB0> INFO: ----------------------------------------------------------------------
[09:34:21.758] <TB0> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:34:25.570] <TB0> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[09:34:56.698] <TB0> INFO: Resuming triggers.
[09:35:00.519] <TB0> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[09:35:31.440] <TB0> INFO: Resuming triggers.
[09:35:35.263] <TB0> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[09:36:05.991] <TB0> INFO: Resuming triggers.
[09:36:09.809] <TB0> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[09:36:39.884] <TB0> INFO: Resuming triggers.
[09:36:43.704] <TB0> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[09:37:13.563] <TB0> INFO: Resuming triggers.
[09:37:17.381] <TB0> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[09:37:47.659] <TB0> INFO: Resuming triggers.
[09:37:51.476] <TB0> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[09:38:21.723] <TB0> INFO: Resuming triggers.
[09:38:25.536] <TB0> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[09:38:55.607] <TB0> INFO: Resuming triggers.
[09:38:59.419] <TB0> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[09:39:29.582] <TB0> INFO: Resuming triggers.
[09:39:33.397] <TB0> INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[09:40:02.855] <TB0> INFO: Resuming triggers.
[09:40:06.674] <TB0> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[09:40:36.739] <TB0> INFO: Resuming triggers.
[09:40:40.544] <TB0> INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[09:41:11.013] <TB0> INFO: Resuming triggers.
[09:41:14.821] <TB0> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[09:41:46.643] <TB0> INFO: Resuming triggers.
[09:41:50.452] <TB0> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[09:42:20.718] <TB0> INFO: Resuming triggers.
[09:42:24.526] <TB0> INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[09:42:54.651] <TB0> INFO: Resuming triggers.
[09:42:58.455] <TB0> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[09:43:29.240] <TB0> INFO: Resuming triggers.
[09:43:33.042] <TB0> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[09:44:03.291] <TB0> INFO: Resuming triggers.
[09:44:07.095] <TB0> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[09:44:37.260] <TB0> INFO: Resuming triggers.
[09:44:41.062] <TB0> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[09:45:11.020] <TB0> INFO: Resuming triggers.
[09:45:14.824] <TB0> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[09:45:44.609] <TB0> INFO: Resuming triggers.
[09:45:48.412] <TB0> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[09:46:20.387] <TB0> INFO: Resuming triggers.
[09:46:24.195] <TB0> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[09:46:55.873] <TB0> INFO: Resuming triggers.
[09:46:59.678] <TB0> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[09:47:31.759] <TB0> INFO: Resuming triggers.
[09:47:35.561] <TB0> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[09:48:08.068] <TB0> INFO: Resuming triggers.
[09:48:11.874] <TB0> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[09:48:43.240] <TB0> INFO: Resuming triggers.
[09:48:47.041] <TB0> INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[09:48:53.414] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 7 readouts!
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15a 80c0 4878 2da 2464 350 2865 34a 244f 560 284f 800 264e 4878 d2 2a2c d9 264f 4cc 2845 4878 159 2065 14c 244a 4dc 2669 560 284e 619 2028 812 224f 4878 ca 2069 c0 2884 201 224d 2c2 2482 495 2482 492 2a66 758 2646 858 2664 e000 c000
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a154 8040 4878 4879 e2 2869 4c4 2464 50a 2640 4878 50c 2449 4879 345 2885 741 264f e000 c000
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a155 80b1 4878 a2 2480 448 204a 742 286c 4878 98 204d 109 2867 4878 215 224c 4878 302 2884 615 2263 61a 2661 e000 c000
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a156 80c0 4878 a 2444 71c 2669 4878 5c 2a42 84b 2a43 4878 204 2847 4878 c8 2246 699 206a 683 2065 862 266f e000 c000
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a157 8000 4878 21d 2262 4878 487b 222c 15 2843 1d 264a 24c 286e 850 224e 4878 352 244f 4878 708 2648 e000 c000
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a158 8040 4878 4878 362 244e 484 2645 820 262f 4878 d5 2069 d5 2262 102 2042 709 2840 4878 343 206a 60c 284a 6e0 2043 e000 c000
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a159 80b1 4878 459 2a2e 603 286f 4878 81a 268c 4878 4 2848 511 284f 80b 2aa5 812 2a80 4878 118 264a 684 284d e000 c000
[09:48:53.414] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 9 readouts!
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a163 8000 4878 58 2046 62 2661 dc 224f 4878 419 266d 684 286a 4879 4879 4a 2268 4d2 264e 804 2643 853 2a65 e000 c000
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15d 80b1 4878 2d1 2a4f 498 264f 4878 614 2269 4878 c 242e 9 264c 13 2662 449 2064 4878 8c 2665 8c 2a2f d2 2a6d 560 2666 e000 c000
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15e 80c0 4878 11d 2845 30a 2469 752 2463 4878 481 2864 843 224b 4878 4878 402 286d fff 2fff fff 2fff fff 2fff e000 c000
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15f 8000 4878 314 206c 711 2261 4878 4878 142 2046 851 244f 845 2a42 4878 5d 2a6f 482 2661 e000 c000
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a160 8040 487a 487a 2e0 226d 487a 487a 22 208c 2 2881 214 206d 250 2087 e000 c000
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a161 80b1 4878 18 2440 441 2863 614 2644 4878 11c 2468 4878 64d 264e 699 206e 4878 85 286d 24a 2285 440 2682 e000 c000
[09:48:53.414] <TB0> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a162 80c0 4879 42 2446 208 224b 4879 14c 2842 481 284f 4879 30a 204e 4ca 2064 542 2864 4879 21c 2862 48b 2444 51d 226b e000 c000
[09:49:17.974] <TB0> INFO: Resuming triggers.
[09:49:18.993] <TB0> INFO: data taking finished, elapsed time: 100 seconds.
[09:49:28.178] <TB0> INFO: PixTest:: pg_setup set to default.
[09:49:28.180] <TB0> INFO: PixTestXray::doPhRun() done
[09:49:28.180] <TB0> INFO: PixTestXray::doTest() done
[09:49:28.361] <TB0> INFO: enter test to run
[09:49:28.361] <TB0> INFO: test: no parameter change
[09:49:28.649] <TB0> QUIET: Connection to board 83 closed.
[09:49:28.728] <TB0> INFO: pXar: this is the end, my friend