[13:08:10.573] <TB3> INFO: *** Welcome to pxar ***
[13:08:10.573] <TB3> INFO: *** Today: 2015/10/07
[13:08:10.639] <TB3> INFO: *** Version: 7db0-dirty
[13:08:10.639] <TB3> INFO: readRocDacs: /usr/local/data/M2114_XrayQualificationExt_2015-10-07_14h47m_1444222028//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2114_XrayQualificationExt_2015-10-07_14h47m_1444222028//005_HRData_150//dacParameters35_C15.dat
[13:08:10.640] <TB3> INFO: readTbmDacs: /usr/local/data/M2114_XrayQualificationExt_2015-10-07_14h47m_1444222028//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2114_XrayQualificationExt_2015-10-07_14h47m_1444222028//005_HRData_150//tbmParameters_C0b.dat
[13:08:10.640] <TB3> INFO: readMaskFile: /usr/local/data/M2114_XrayQualificationExt_2015-10-07_14h47m_1444222028//005_HRData_150//defaultMaskFile.dat
[13:08:10.640] <TB3> INFO: readTrimFile: /usr/local/data/M2114_XrayQualificationExt_2015-10-07_14h47m_1444222028//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2114_XrayQualificationExt_2015-10-07_14h47m_1444222028//005_HRData_150//trimParameters35_C15.dat
[13:08:10.724] <TB3> INFO: clk: 4
[13:08:10.724] <TB3> INFO: ctr: 4
[13:08:10.724] <TB3> INFO: sda: 19
[13:08:10.724] <TB3> INFO: tin: 9
[13:08:10.724] <TB3> INFO: level: 15
[13:08:10.724] <TB3> INFO: triggerdelay: 0
[13:08:10.724] <TB3> QUIET: Instanciating API for pxar v2.6.0+21~ga0339e2
[13:08:10.724] <TB3> INFO: Log level: INFO
[13:08:10.738] <TB3> INFO: Found DTB DTB_WWV6Z5
[13:08:10.750] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[13:08:10.753] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[13:08:10.756] <TB3> INFO: RPC call hashes of host and DTB match: 398089610
[13:08:12.270] <TB3> INFO: DUT info:
[13:08:12.270] <TB3> INFO: The DUT currently contains the following objects:
[13:08:12.270] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[13:08:12.270] <TB3> INFO: TBM Core alpha (0): 7 registers set
[13:08:12.270] <TB3> INFO: TBM Core beta (1): 7 registers set
[13:08:12.270] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:08:12.271] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.271] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:12.672] <TB3> INFO: enter 'restricted' command line mode
[13:08:12.672] <TB3> INFO: enter test to run
[13:08:12.672] <TB3> INFO: test: delay setting parameters: ->5<-
[13:08:12.672] <TB3> INFO: delay test by 5 seconds...
[13:08:17.672] <TB3> INFO: enter test to run
[13:08:17.672] <TB3> INFO: test: Xray no parameter change
[13:08:17.672] <TB3> INFO: running: xray
[13:08:17.673] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2114_XrayQualificationExt_2015-10-07_14h47m_1444222028//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2114_XrayQualificationExt_2015-10-07_14h47m_1444222028//005_HRData_150//phCalibrationFitErr35_C15.dat
[13:08:17.840] <TB3> INFO: ######################################################################
[13:08:17.840] <TB3> INFO: PixTestXray::doTest()
[13:08:17.840] <TB3> INFO: ######################################################################
[13:08:17.840] <TB3> INFO: ----------------------------------------------------------------------
[13:08:17.840] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:08:17.840] <TB3> INFO: ----------------------------------------------------------------------
[13:08:18.808] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:08:22.428] <TB3> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[13:08:52.671] <TB3> INFO: Resuming triggers.
[13:08:56.306] <TB3> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[13:09:25.266] <TB3> INFO: Resuming triggers.
[13:09:28.902] <TB3> INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[13:09:58.829] <TB3> INFO: Resuming triggers.
[13:10:02.466] <TB3> INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[13:10:32.157] <TB3> INFO: Resuming triggers.
[13:10:35.791] <TB3> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[13:11:05.990] <TB3> INFO: Resuming triggers.
[13:11:09.627] <TB3> INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[13:11:40.884] <TB3> INFO: Resuming triggers.
[13:11:44.522] <TB3> INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[13:12:15.101] <TB3> INFO: Resuming triggers.
[13:12:18.737] <TB3> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[13:12:48.783] <TB3> INFO: Resuming triggers.
[13:12:52.417] <TB3> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[13:13:22.105] <TB3> INFO: Resuming triggers.
[13:13:25.737] <TB3> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[13:13:56.135] <TB3> INFO: Resuming triggers.
[13:13:59.773] <TB3> INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[13:14:30.211] <TB3> INFO: Resuming triggers.
[13:14:33.848] <TB3> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[13:14:41.603] <TB3> WARNING: Channel 1 ROC 2: Readback start marker after 13 readouts!
[13:14:41.604] <TB3> WARNING: Channel 1 ROC 2: Readback start marker after 3 readouts!
[13:14:41.604] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:14:41.604] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a182 8000 4078 414 206f 682 2427 69c 2222 858 282d 4078 d 2062 d2 2269 4079 111 2261 413 246f 64b 2248 4079 e000 c000
[13:14:41.604] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17c 80b1 4078 353 284d 35c 2a4a 71a 2a45 4078 407b 2a6f 162 2485 4078 4078 c3 2a4f 154 224d 401 204f 404 262c 444 244a 71d 2a65 80c 2827 e000 c000
[13:14:41.604] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17d 80c0 4078 40 284a 6cb 2669 4078 83 284c 620 2261 649 2a65 4078 80b 224f 4078 e000 c000
[13:14:41.604] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17e 8000 4078 4078 89 2061 290 2064 4078 291 2443 4078 402 282d 445 204d 561 2669 fff 2fff fff 2fff e000 c000
[13:14:41.604] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17f 8040 407a 407a 407a 407a fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[13:14:41.604] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a180 80b1 4078 4078 61 2869 403 244f 48b 284f 521 2047 55c 2a62 68c 2023 4078 c3 264f 310 244f 801 2629 4078 a 2864 c5 2861 25b 2662 541 2044 814 2045 e000 c000
[13:14:41.604] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a181 80c0 4079 103 2469 4079 491 2049 4079 d 224f 14 2243 110 2a64 65d 2869 4079 e000 c000
[13:15:03.669] <TB3> INFO: Resuming triggers.
[13:15:07.301] <TB3> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:15:37.370] <TB3> INFO: Resuming triggers.
[13:15:41.008] <TB3> INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[13:16:11.402] <TB3> INFO: Resuming triggers.
[13:16:15.036] <TB3> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[13:16:47.069] <TB3> INFO: Resuming triggers.
[13:16:50.699] <TB3> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[13:17:20.505] <TB3> INFO: Resuming triggers.
[13:17:24.141] <TB3> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[13:17:55.006] <TB3> INFO: Resuming triggers.
[13:17:58.639] <TB3> INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[13:18:28.475] <TB3> INFO: Resuming triggers.
[13:18:32.112] <TB3> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[13:19:01.489] <TB3> INFO: Resuming triggers.
[13:19:05.125] <TB3> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[13:19:34.330] <TB3> INFO: Resuming triggers.
[13:19:37.961] <TB3> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[13:20:07.225] <TB3> INFO: Resuming triggers.
[13:20:10.859] <TB3> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[13:20:41.280] <TB3> INFO: Resuming triggers.
[13:20:44.914] <TB3> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[13:21:14.628] <TB3> INFO: Resuming triggers.
[13:21:18.258] <TB3> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[13:21:48.991] <TB3> INFO: Resuming triggers.
[13:21:52.622] <TB3> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[13:22:21.925] <TB3> INFO: Resuming triggers.
[13:22:25.560] <TB3> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[13:22:55.758] <TB3> INFO: Resuming triggers.
[13:22:59.391] <TB3> INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[13:23:29.220] <TB3> INFO: Resuming triggers.
[13:23:31.191] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[13:23:48.143] <TB3> INFO: PixTest:: pg_setup set to default.
[13:23:48.144] <TB3> INFO: PixTestXray::doPhRun() done
[13:23:48.144] <TB3> INFO: PixTestXray::doTest() done
[13:23:48.365] <TB3> INFO: enter test to run
[13:23:48.365] <TB3> INFO: test: no parameter change
[13:23:48.633] <TB3> QUIET: Connection to board 121 closed.
[13:23:48.715] <TB3> INFO: pXar: this is the end, my friend