[09:39:28.258] <TB2> INFO: *** Welcome to pxar ***
[09:39:28.258] <TB2> INFO: *** Today: 2016/04/14
[09:39:28.321] <TB2> INFO: *** Version: 9751
[09:39:28.321] <TB2> INFO: readRocDacs: /usr/local/data/M2202_XrayQualification_2016-04-14_11h21m_1460625704//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2202_XrayQualification_2016-04-14_11h21m_1460625704//005_HRData_150//dacParameters35_C15.dat
[09:39:28.322] <TB2> INFO: readTbmDacs: /usr/local/data/M2202_XrayQualification_2016-04-14_11h21m_1460625704//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2202_XrayQualification_2016-04-14_11h21m_1460625704//005_HRData_150//tbmParameters_C0b.dat
[09:39:28.322] <TB2> INFO: readMaskFile: /usr/local/data/M2202_XrayQualification_2016-04-14_11h21m_1460625704//005_HRData_150//defaultMaskFile.dat
[09:39:28.322] <TB2> INFO: readTrimFile: /usr/local/data/M2202_XrayQualification_2016-04-14_11h21m_1460625704//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2202_XrayQualification_2016-04-14_11h21m_1460625704//005_HRData_150//trimParameters35_C15.dat
[09:39:28.405] <TB2> INFO: clk: 4
[09:39:28.405] <TB2> INFO: ctr: 4
[09:39:28.405] <TB2> INFO: sda: 19
[09:39:28.405] <TB2> INFO: tin: 9
[09:39:28.405] <TB2> INFO: level: 15
[09:39:28.405] <TB2> INFO: triggerdelay: 0
[09:39:28.405] <TB2> QUIET: Instanciating API for pxar v2.7.5+30~g833b08f
[09:39:28.405] <TB2> INFO: Log level: INFO
[09:39:28.418] <TB2> INFO: Found DTB DTB_WXENWR
[09:39:28.427] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[09:39:28.429] <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:
------------------------------------------------------
[09:39:28.431] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[09:39:29.972] <TB2> INFO: DUT info:
[09:39:29.972] <TB2> INFO: The DUT currently contains the following objects:
[09:39:29.972] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[09:39:29.972] <TB2> INFO: TBM Core alpha (0): 7 registers set
[09:39:29.972] <TB2> INFO: TBM Core beta (1): 7 registers set
[09:39:29.972] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:39:29.972] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:29.972] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:39:30.373] <TB2> INFO: enter 'restricted' command line mode
[09:39:30.374] <TB2> INFO: enter test to run
[09:39:30.374] <TB2> INFO: test: delay setting parameters: ->5<-
[09:39:30.374] <TB2> INFO: delay test by 5 seconds...
[09:39:35.374] <TB2> INFO: enter test to run
[09:39:35.374] <TB2> INFO: test: Xray no parameter change
[09:39:35.374] <TB2> INFO: running: xray
[09:39:35.374] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2202_XrayQualification_2016-04-14_11h21m_1460625704//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2202_XrayQualification_2016-04-14_11h21m_1460625704//005_HRData_150//phCalibrationFitErr35_C15.dat
[09:39:35.514] <TB2> INFO: ######################################################################
[09:39:35.514] <TB2> INFO: PixTestXray::doTest()
[09:39:35.514] <TB2> INFO: ######################################################################
[09:39:35.514] <TB2> INFO: ----------------------------------------------------------------------
[09:39:35.514] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:39:35.514] <TB2> INFO: ----------------------------------------------------------------------
[09:39:36.244] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:39:39.460] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[09:40:07.719] <TB2> INFO: Resuming triggers.
[09:40:10.950] <TB2> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[09:40:39.466] <TB2> INFO: Resuming triggers.
[09:40:42.696] <TB2> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[09:41:11.410] <TB2> INFO: Resuming triggers.
[09:41:14.641] <TB2> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[09:41:43.587] <TB2> INFO: Resuming triggers.
[09:41:46.812] <TB2> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[09:42:14.970] <TB2> INFO: Resuming triggers.
[09:42:18.196] <TB2> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[09:42:46.273] <TB2> INFO: Resuming triggers.
[09:42:49.501] <TB2> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[09:43:17.294] <TB2> INFO: Resuming triggers.
[09:43:20.528] <TB2> INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[09:43:48.949] <TB2> INFO: Resuming triggers.
[09:43:52.180] <TB2> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[09:44:20.951] <TB2> INFO: Resuming triggers.
[09:44:24.182] <TB2> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[09:44:53.562] <TB2> INFO: Resuming triggers.
[09:44:56.793] <TB2> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[09:45:26.171] <TB2> INFO: Resuming triggers.
[09:45:29.397] <TB2> INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[09:45:58.602] <TB2> INFO: Resuming triggers.
[09:46:01.833] <TB2> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[09:46:30.131] <TB2> INFO: Resuming triggers.
[09:46:33.360] <TB2> INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[09:47:01.941] <TB2> INFO: Resuming triggers.
[09:47:05.170] <TB2> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[09:47:34.635] <TB2> INFO: Resuming triggers.
[09:47:37.867] <TB2> INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[09:48:07.276] <TB2> INFO: Resuming triggers.
[09:48:10.505] <TB2> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[09:48:39.483] <TB2> INFO: Resuming triggers.
[09:48:42.710] <TB2> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[09:49:11.863] <TB2> INFO: Resuming triggers.
[09:49:15.088] <TB2> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[09:49:44.356] <TB2> INFO: Resuming triggers.
[09:49:47.584] <TB2> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[09:50:17.976] <TB2> INFO: Resuming triggers.
[09:50:21.202] <TB2> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[09:50:50.816] <TB2> INFO: Resuming triggers.
[09:50:54.043] <TB2> INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[09:51:23.298] <TB2> INFO: Resuming triggers.
[09:51:26.522] <TB2> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[09:51:55.956] <TB2> INFO: Resuming triggers.
[09:51:59.180] <TB2> INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[09:52:29.378] <TB2> INFO: Resuming triggers.
[09:52:32.602] <TB2> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[09:52:47.383] <TB2> WARNING: Detected DESER400 trailer error bits: "IDLE DATA"
[09:52:47.383] <TB2> ERROR: <datapipe.cc/CheckEventID:L469> Channel 2 Event ID mismatch: local ID (40) != TBM ID (41)
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a22c 80b1 41c0 153 2868 70b 224c 81a 2a2f 41c0 98 2682 25b 266a 4180 b 2861 505 226c 41c0 89 2269 30d 2267 54b 266f 55c 2281 848 2a41 e000 c000
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a226 8001 4180 54c 2a44 41c0 213 2482 248 2a48 40d 2244 4dc 2242 41c0 481 204d 50d 286e 50d 248c 70c 2445 801 2849 41c0 82 286f 843 284d e000 c000
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a227 8041 41c0 605 2042 605 2229 41c0 34a 2648 660 2042 75b 284a 41c0 84 284a 41c0 62 2669 142 208a 359 2089 e000 c000
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a228 80b1 41c0 208 2862 25d 24a0 48d 2464 504 284d 508 266c 41c0 5c 2042 27f 2cf7 a8c 2c34 422 2917 3fc 23fc 36c 2824 bff 2000 7f 2fff fff 2fff f2ff d2ff
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a229 80c1 4180 d 2448 143 2884 308 2842 490 2064 488 2261 41c0 410 2062 409 246d 4180 644 2429 41c0 44b 2285 e000 c000
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a22a 8001 41c0 655 26a0 745 2a47 41c0 289 2868 31b 2081 492 2468 844 2248 852 2229 41c0 280 2a69 618 2a41 6c2 2244 71a 2645 4180 1d 2068 283 2089 e000 c000
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a22b 8041 41c0 88 2462 82 2882 290 224e 40a 2286 448 2640 4180 43 244f 43 2662 e0 2449 d3 2642 dd 2269 80c 2869 4180 300 2449 619 264a 652 2028 4180 209 2669 259 2469 35a 246c 614 2643 e000 c000
[09:52:47.384] <TB2> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!
[09:52:47.384] <TB2> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!
[09:52:47.384] <TB2> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!
[09:52:47.384] <TB2> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L170> Dumping the flawed event +- 3 events:
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a232 8000 41c0 320 2448 70a 266f 81b 204e 41c0 53 2044 155 208a 21c 244f 4181 315 2848 522 288f 818 2063 4181 88 2269 e000 c000
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a22c 80b1 41c0 153 2868 70b 224c 81a 2a2f 41c0 98 2682 25b 266a 4180 b 2861 505 226c 41c0 89 2269 30d 2267 54b 266f 55c 2281 848 2a41 e000 c000
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a22d 80c1 41c0 89 2865 501 2462 4180 158 2865 159 228a 4cd 2a4f 4180 c1 246c 862 2086 41c1 e000 c000
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a22e 8001 41c0 4da 244c 4cd 2263 683 2687 4180 4c3 2248 41c0 114 286f 105 268f 2d0 2665 30b 2a81 711 2642 41c0 35d 284f e000 c000
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a22f 8040 41c2 118 222e 355 2a60 4d4 2a62 608 224c 41c2 1b 2049 10 204d 21 2a6b 500 266d 540 266a 613 224e 41c2 101 228d 4182 419 2a6f e000 c000
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a230 80b1 4181 40a 266d 444 204f 84c 262d 4181 322 266a 41c1 551 286a 80c 200f 84c 222a 41c1 255 2865 e000 c000
[09:52:47.384] <TB2> ERROR: <datapipe.cc/Read:L172> ====== 0 ====== a231 80c1 41c0 49b 2683 41c0 805 2469 41c0 605 284f 81c 2040 810 244b 41c0 219 2249 28c 2483 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e000 c000
[09:53:02.499] <TB2> INFO: Resuming triggers.
[09:53:05.721] <TB2> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[09:53:35.423] <TB2> INFO: Resuming triggers.
[09:53:38.643] <TB2> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[09:54:07.935] <TB2> INFO: Resuming triggers.
[09:54:11.157] <TB2> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[09:54:41.236] <TB2> INFO: Resuming triggers.
[09:54:44.459] <TB2> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[09:55:14.510] <TB2> INFO: Resuming triggers.
[09:55:17.733] <TB2> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[09:55:45.148] <TB2> INFO: Resuming triggers.
[09:55:48.367] <TB2> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[09:56:15.122] <TB2> INFO: Resuming triggers.
[09:56:15.151] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[09:56:15.780] <TB2> INFO: PixTest:: pg_setup set to default.
[09:56:15.781] <TB2> INFO: PixTestXray::doPhRun() done
[09:56:15.781] <TB2> INFO: PixTestXray::doTest() done
[09:56:15.956] <TB2> INFO: enter test to run
[09:56:15.957] <TB2> INFO: test: no parameter change
[09:56:16.264] <TB2> QUIET: Connection to board 162 closed.
[09:56:16.345] <TB2> INFO: pXar: this is the end, my friend