[10:07:43.686] <TB1> INFO: *** Welcome to pxar ***
[10:07:43.686] <TB1> INFO: *** Today: 2015/11/04
[10:07:44.086] <TB1> INFO: *** Version: 7db0-dirty
[10:07:44.086] <TB1> INFO: readRocDacs: /usr/local/data/M3521_XrayQualification_2015-11-04_10h51m_1446630662//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M3521_XrayQualification_2015-11-04_10h51m_1446630662//005_HRData_150//dacParameters35_C15.dat
[10:07:44.087] <TB1> INFO: readTbmDacs: /usr/local/data/M3521_XrayQualification_2015-11-04_10h51m_1446630662//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M3521_XrayQualification_2015-11-04_10h51m_1446630662//005_HRData_150//tbmParameters_C0b.dat
[10:07:44.087] <TB1> INFO: readMaskFile: /usr/local/data/M3521_XrayQualification_2015-11-04_10h51m_1446630662//005_HRData_150//defaultMaskFile.dat
[10:07:44.087] <TB1> INFO: readTrimFile: /usr/local/data/M3521_XrayQualification_2015-11-04_10h51m_1446630662//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M3521_XrayQualification_2015-11-04_10h51m_1446630662//005_HRData_150//trimParameters35_C15.dat
[10:07:44.164] <TB1> INFO: clk: 4
[10:07:44.164] <TB1> INFO: ctr: 4
[10:07:44.164] <TB1> INFO: sda: 19
[10:07:44.164] <TB1> INFO: tin: 9
[10:07:44.164] <TB1> INFO: level: 15
[10:07:44.164] <TB1> INFO: triggerdelay: 0
[10:07:44.164] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[10:07:44.165] <TB1> INFO: Log level: INFO
[10:07:44.178] <TB1> INFO: Found DTB DTB_WWXTQT
[10:07:44.189] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[10:07:44.192] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[10:07:44.194] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[10:07:45.734] <TB1> INFO: DUT info:
[10:07:45.734] <TB1> INFO: The DUT currently contains the following objects:
[10:07:45.734] <TB1> INFO: 2 TBM Cores tbm08c (2 ON)
[10:07:45.734] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:07:45.734] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:07:45.734] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:07:45.734] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.734] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:46.135] <TB1> INFO: enter 'restricted' command line mode
[10:07:46.135] <TB1> INFO: enter test to run
[10:07:46.135] <TB1> INFO: test: delay setting parameters: ->5<-
[10:07:46.135] <TB1> INFO: delay test by 5 seconds...
[10:07:51.135] <TB1> INFO: enter test to run
[10:07:51.135] <TB1> INFO: test: Xray no parameter change
[10:07:51.135] <TB1> INFO: running: xray
[10:07:51.138] <TB1> INFO: readGainPedestalParameters /usr/local/data/M3521_XrayQualification_2015-11-04_10h51m_1446630662//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M3521_XrayQualification_2015-11-04_10h51m_1446630662//005_HRData_150//phCalibrationFitErr35_C15.dat
[10:07:51.278] <TB1> INFO: ######################################################################
[10:07:51.278] <TB1> INFO: PixTestXray::doTest()
[10:07:51.278] <TB1> INFO: ######################################################################
[10:07:51.278] <TB1> INFO: ----------------------------------------------------------------------
[10:07:51.278] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:07:51.278] <TB1> INFO: ----------------------------------------------------------------------
[10:07:52.242] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:07:56.378] <TB1> INFO: run duration 4 seconds, buffer almost full (81%), pausing triggers.
[10:08:27.460] <TB1> INFO: Resuming triggers.
[10:08:31.610] <TB1> INFO: run duration 8 seconds, buffer almost full (81%), pausing triggers.
[10:08:59.740] <TB1> INFO: Resuming triggers.
[10:09:03.892] <TB1> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:09:31.451] <TB1> INFO: Resuming triggers.
[10:09:35.603] <TB1> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[10:10:03.623] <TB1> INFO: Resuming triggers.
[10:10:07.774] <TB1> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[10:10:36.200] <TB1> INFO: Resuming triggers.
[10:10:40.355] <TB1> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[10:11:10.402] <TB1> INFO: Resuming triggers.
[10:11:14.550] <TB1> INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[10:11:46.031] <TB1> INFO: Resuming triggers.
[10:11:50.181] <TB1> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:12:22.844] <TB1> INFO: Resuming triggers.
[10:12:26.994] <TB1> INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[10:12:58.947] <TB1> INFO: Resuming triggers.
[10:13:03.096] <TB1> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[10:13:34.706] <TB1> INFO: Resuming triggers.
[10:13:38.851] <TB1> INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:14:11.012] <TB1> INFO: Resuming triggers.
[10:14:15.160] <TB1> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[10:14:46.338] <TB1> INFO: Resuming triggers.
[10:14:50.486] <TB1> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[10:15:21.547] <TB1> INFO: Resuming triggers.
[10:15:25.690] <TB1> INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:15:56.783] <TB1> INFO: Resuming triggers.
[10:16:00.928] <TB1> INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[10:16:31.452] <TB1> INFO: Resuming triggers.
[10:16:35.600] <TB1> INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[10:17:04.459] <TB1> INFO: Resuming triggers.
[10:17:08.599] <TB1> INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[10:17:37.712] <TB1> INFO: Resuming triggers.
[10:17:41.856] <TB1> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[10:18:10.377] <TB1> INFO: Resuming triggers.
[10:18:14.520] <TB1> INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[10:18:44.937] <TB1> INFO: Resuming triggers.
[10:18:49.085] <TB1> INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[10:18:58.701] <TB1> WARNING: Channel 1 ROC 2: Readback start marker after 32 readouts!
[10:18:58.701] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:18:58.702] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a162 8000 4078 44b 2662 645 2247 812 2666 851 2a60 4078 104 2042 289 284f 34d 2460 4d8 222c 802 2240 4079 299 246e 301 2463 35d 226a 508 224a 6d1 248c 4079 6da 242c 4078 4078 4079 4079 e000 c000
[10:18:58.702] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15c 80b1 4078 90 2060 25d 2283 34b 2662 4cb 268f 50b 2061 6d1 2669 74d 2045 4078 35d 286f 641 204d 4078 294 264f 515 2245 4078 48b 2a88 561 2069 6c5 2a44 741 2880 819 2a4f 4078 152 2061 350 2a4f 444 2227 50a 244d 61d 2482 4078 4078 a2 284f 4078 754 2061 e000 c000
[10:18:58.702] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15d 80c0 4078 42 2046 8a 224f 480 264d 50a 244a 605 206b 745 2a2c 840 2442 4078 12 2482 4cc 286a 708 244f 822 226e 85a 2a2f 860 2a68 4078 292 262f 2cb 2885 448 2843 602 2a6c 70b 2a49 4078 245 2866 4d5 2440 4078 514 2661 545 2464 748 2041 4078 cc 2a49 319 2886 4078 202 242a 51a 2848 502 2264 4078 e000 c000
[10:18:58.702] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15e 8000 4078 44 206a 15c 2463 150 2262 680 2427 812 2888 85c 246d 4078 8d 266f 2c5 2465 450 2860 6e0 2063 709 204c 708 244f 752 244f 4078 419 2a42 655 2a47 808 2a42 4078 a2 2282 152 2a4f 218 2289 25a 264f 298 2a49 60c 2449 4078 704 244c 4078 4078 dc 2641 81c 202f 4078 70d 2a2f e000 c000
[10:18:58.702] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15f 8040 407a 83 264f 850 224c 407a 254 2049 248 2482 2c5 2640 489 2a8a 4cc 2a4a 600 286d 407a 411 2a4f 448 2263 60c 2a2f 407a 2cb 224d 808 2a44 407a 351 2069 695 2a4e 69c 2a40 841 282f 407a 18 2049 8c 282d 407a 458 2629 407a 458 2045 e000 c000
[10:18:58.702] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a160 80b1 4079 12 288c 503 2869 651 2061 4079 321 2a6d 449 206a 68d 2226 4079 30b 2244 414 284b 611 2266 85d 2246 4079 55b 244e 713 264a 4079 5d 2248 61a 284f 4079 19 244d 49 202d 4079 4079 560 2048 e000 c000
[10:18:58.702] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a161 80c0 4078 81 244f 119 204e 240 246f 292 2a65 2c3 28a0 448 2a6a 611 2460 75b 264c 4078 20 2266 35b 2465 34a 2863 75b 242f 4078 251 2a4f 749 202f 4078 4c5 2225 862 284a 84d 224c 4079 44b 2a4d 4079 81 244c 311 2881 4079 d8 222f 4079 4 242d e000 c000
[10:19:16.943] <TB1> INFO: Resuming triggers.
[10:19:21.087] <TB1> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[10:19:49.725] <TB1> INFO: Resuming triggers.
[10:19:53.871] <TB1> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[10:20:22.428] <TB1> INFO: Resuming triggers.
[10:20:26.572] <TB1> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[10:20:56.862] <TB1> INFO: Resuming triggers.
[10:21:01.000] <TB1> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[10:21:30.485] <TB1> INFO: Resuming triggers.
[10:21:31.135] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[10:21:35.665] <TB1> INFO: PixTest:: pg_setup set to default.
[10:21:35.667] <TB1> INFO: PixTestXray::doPhRun() done
[10:21:35.667] <TB1> INFO: PixTestXray::doTest() done
[10:21:35.858] <TB1> INFO: enter test to run
[10:21:35.858] <TB1> INFO: test: no parameter change
[10:21:35.964] <TB1> QUIET: Connection to board 147 closed.
[10:21:36.043] <TB1> INFO: pXar: this is the end, my friend