Client 1488437292.666223725 TimeTrace.cc:172 in printInternal NOTICE[1]: 6695409.0 ns (+ 155.9 ns): received 1 packets at polling iteration 23956651 1488437292.666226058 TimeTrace.cc:172 in printInternal NOTICE[1]: 6696032.5 ns (+ 623.5 ns): sendRequest invoked, sequence 50835, length 42 1488437292.666228364 TimeTrace.cc:172 in printInternal NOTICE[1]: 6696254.0 ns (+ 221.5 ns): transmitQueueSpace 3000, maxBytes 3000 1488437292.666230835 TimeTrace.cc:172 in printInternal NOTICE[1]: 6696473.5 ns (+ 219.5 ns): sent data, sequence 50835, offset 0, length 42, priority 7 1488437292.666233135 TimeTrace.cc:172 in printInternal NOTICE[1]: 6697351.2 ns (+ 877.6 ns): DpdkDriver received 3 packets 1488437292.666235629 TimeTrace.cc:172 in printInternal NOTICE[1]: 6698114.5 ns (+ 763.3 ns): client received DATA, sequence 50823, offset 107310, length 1500, flags 0 1488437292.666238005 TimeTrace.cc:172 in printInternal NOTICE[1]: 6698171.1 ns (+ 56.6 ns): client sending GRANT, sequence 50823, offset 117600, priority 0 1488437292.666240318 TimeTrace.cc:172 in printInternal NOTICE[1]: 6698330.0 ns (+ 158.9 ns): client received GRANT, sequence 50826, offset 122010 1488437292.666242617 TimeTrace.cc:172 in printInternal NOTICE[1]: 6698376.1 ns (+ 46.1 ns): client received GRANT, sequence 50826, offset 123480 1488437292.666247429 TimeTrace.cc:172 in printInternal NOTICE[1]: 6698432.3 ns (+ 56.1 ns): received 3 packets at polling iteration 23956652 1488437292.666249718 TimeTrace.cc:172 in printInternal NOTICE[1]: 6698494.9 ns (+ 62.7 ns): transmitQueueSpace 3000, maxBytes 2940 1488437292.666252150 TimeTrace.cc:172 in printInternal NOTICE[1]: 6699053.3 ns (+ 558.3 ns): sent data, sequence 50826, offset 120540, length 2940, priority 0 1488437292.666254409 TimeTrace.cc:172 in printInternal NOTICE[1]: 6700475.7 ns (+1422.4 ns): DpdkDriver received 4 packets 1488437292.666256939 TimeTrace.cc:172 in printInternal NOTICE[1]: 6701266.1 ns (+ 790.4 ns): client received DATA, sequence 50823, offset 108780, length 1500, flags 0 1488437292.666259299 TimeTrace.cc:172 in printInternal NOTICE[1]: 6701332.8 ns (+ 66.7 ns): client sending GRANT, sequence 50823, offset 119070, priority 0 1488437292.666261573 TimeTrace.cc:172 in printInternal NOTICE[1]: 6701487.2 ns (+ 154.4 ns): client received GRANT, sequence 50826, offset 124950 1488437292.666263957 TimeTrace.cc:172 in printInternal NOTICE[1]: 6701531.8 ns (+ 44.6 ns): client received GRANT, sequence 50826, offset 126420 1488437292.666266160 TimeTrace.cc:172 in printInternal NOTICE[1]: 6701574.9 ns (+ 43.1 ns): client received GRANT, sequence 50826, offset 127890 1488437292.666268339 TimeTrace.cc:172 in printInternal NOTICE[1]: 6701708.7 ns (+ 133.8 ns): received 4 packets at polling iteration 23956656 1488437292.666270600 TimeTrace.cc:172 in printInternal NOTICE[1]: 6701784.4 ns (+ 75.7 ns): transmitQueueSpace 3000, maxBytes 3000 1488437292.666272961 TimeTrace.cc:172 in printInternal NOTICE[1]: 6702330.7 ns (+ 546.3 ns): sent data, sequence 50826, offset 123480, length 2940, priority 0 1488437292.666275157 TimeTrace.cc:172 in printInternal NOTICE[1]: 6702618.9 ns (+ 288.2 ns): DpdkDriver received 1 packets 1488437292.666277578 TimeTrace.cc:172 in printInternal NOTICE[1]: 6702987.3 ns (+ 368.4 ns): client received DATA, sequence 50823, offset 110250, length 1500, flags 0 1488437292.666279948 TimeTrace.cc:172 in printInternal NOTICE[1]: 6703043.4 ns (+ 56.1 ns): client sending GRANT, sequence 50823, offset 120540, priority 0 1488437292.666282174 TimeTrace.cc:172 in printInternal NOTICE[1]: 6703200.8 ns (+ 157.4 ns): received 1 packets at polling iteration 23956657 1488437292.666284416 TimeTrace.cc:172 in printInternal NOTICE[1]: 6703287.0 ns (+ 86.2 ns): transmitQueueSpace 3000, maxBytes 1470 1488437292.666286715 TimeTrace.cc:172 in printInternal NOTICE[1]: 6703570.7 ns (+ 283.7 ns): sent data, sequence 50826, offset 126420, length 1470, priority 0 1488437292.666288926 TimeTrace.cc:172 in printInternal NOTICE[1]: 6703857.4 ns (+ 286.7 ns): DpdkDriver received 1 packets 1488437292.666291294 TimeTrace.cc:172 in printInternal NOTICE[1]: 6704153.1 ns (+ 295.7 ns): client received DATA, sequence 50823, offset 111720, length 1500, flags 0 1488437292.666293654 TimeTrace.cc:172 in printInternal NOTICE[1]: 6704207.7 ns (+ 54.6 ns): client sending GRANT, sequence 50823, offset 122010, priority 0 1488437292.666295890 TimeTrace.cc:172 in printInternal NOTICE[1]: 6704368.1 ns (+ 160.4 ns): received 1 packets at polling iteration 23956658 1488437292.666298140 TimeTrace.cc:172 in printInternal NOTICE[1]: 6705247.2 ns (+ 879.1 ns): DpdkDriver received 2 packets 1488437292.666300544 TimeTrace.cc:172 in printInternal NOTICE[1]: 6705728.4 ns (+ 481.2 ns): client received DATA, sequence 50823, offset 113190, length 1500, flags 0 1488437292.666302959 TimeTrace.cc:172 in printInternal NOTICE[1]: 6705777.5 ns (+ 49.1 ns): client sending GRANT, sequence 50823, offset 123480, priority 0 1488437292.666305173 TimeTrace.cc:172 in printInternal NOTICE[1]: 6705945.4 ns (+ 167.9 ns): client received GRANT, sequence 50826, offset 129360 1488437292.666307456 TimeTrace.cc:172 in printInternal NOTICE[1]: 6705988.5 ns (+ 43.1 ns): received 2 packets at polling iteration 23956661 1488437292.666309618 TimeTrace.cc:172 in printInternal NOTICE[1]: 6706055.2 ns (+ 66.7 ns): transmitQueueSpace 3000, maxBytes 1470 1488437292.666311999 TimeTrace.cc:172 in printInternal NOTICE[1]: 6706353.9 ns (+ 298.7 ns): sent data, sequence 50826, offset 127890, length 1470, priority 0 1488437292.666314205 TimeTrace.cc:172 in printInternal NOTICE[1]: 6719741.7 ns (+13387.8 ns): DpdkDriver received 1 packets 1488437292.666316681 TimeTrace.cc:172 in printInternal NOTICE[1]: 6720144.2 ns (+ 402.5 ns): client received ALL_DATA, sequence 50835, length 58 1488437292.666319205 TimeTrace.cc:172 in printInternal NOTICE[1]: 6720222.9 ns (+ 78.7 ns): BAD TAIL LATENCY 24.22 us 1488437292.666321434 TimeTrace.cc:172 in printInternal NOTICE[1]: 6720402.3 ns (+ 179.4 ns): deleted client RPC, sequence 50835 Server 1488437291.432582121 TimeTrace.cc:172 in printInternal NOTICE[7]: 5268023.3 ns (+ 250.1 ns): DpdkDriver received 3 packets 1488437291.432584293 TimeTrace.cc:172 in printInternal NOTICE[7]: 5268780.1 ns (+ 756.8 ns): server received DATA, sequence 50826, offset 119070, length 1500, flags 1 1488437291.432586499 TimeTrace.cc:172 in printInternal NOTICE[7]: 5268838.8 ns (+ 58.6 ns): server sending GRANT, sequence 50826, offset 129360, priority 0 1488437291.432588613 TimeTrace.cc:172 in printInternal NOTICE[7]: 5269011.7 ns (+ 172.9 ns): server received GRANT, sequence 50823, offset 116130 1488437291.432590733 TimeTrace.cc:172 in printInternal NOTICE[7]: 5269108.9 ns (+ 97.2 ns): server received ALL_DATA, sequence 50835, length 42 1488437291.432592814 TimeTrace.cc:172 in printInternal NOTICE[7]: 5286483.7 ns (+17374.8 ns): sendReply invoked, sequence 50835, length 38 1488437291.432595165 TimeTrace.cc:172 in printInternal NOTICE[7]: 5286578.9 ns (+ 95.2 ns): transmitQueueSpace 3000, maxBytes 3000 1488437291.432597412 TimeTrace.cc:172 in printInternal NOTICE[7]: 5286773.3 ns (+ 194.5 ns): sent data, sequence 50835, offset 0, length 38, priority 7 1488437291.432599549 TimeTrace.cc:172 in printInternal NOTICE[7]: 5287091.1 ns (+ 317.7 ns): deleted server RPC, sequence 50835 Client 1488437293.636370340 TimeTrace.cc:172 in printInternal NOTICE[1]: 126042247.5 ns (+ 157.4 ns): received 1 packets at polling iteration 24210201 1488437293.636372778 TimeTrace.cc:172 in printInternal NOTICE[1]: 126043128.1 ns (+ 880.6 ns): sendRequest invoked, sequence 51451, length 42 1488437293.636375151 TimeTrace.cc:172 in printInternal NOTICE[1]: 126043291.5 ns (+ 163.4 ns): transmitQueueSpace 3000, maxBytes 3000 1488437293.636377675 TimeTrace.cc:172 in printInternal NOTICE[1]: 126043454.9 ns (+ 163.4 ns): sent data, sequence 51451, offset 0, length 42, priority 7 1488437293.636380054 TimeTrace.cc:172 in printInternal NOTICE[1]: 126045120.9 ns (+1666.0 ns): DpdkDriver received 4 packets 1488437293.636382585 TimeTrace.cc:172 in printInternal NOTICE[1]: 126046329.9 ns (+1208.9 ns): client received DATA, sequence 51440, offset 154350, length 1500, flags 0 1488437293.636385094 TimeTrace.cc:172 in printInternal NOTICE[1]: 126046393.5 ns (+ 63.7 ns): client sending GRANT, sequence 51440, offset 164640, priority 0 1488437293.636387604 TimeTrace.cc:172 in printInternal NOTICE[1]: 126046601.5 ns (+ 208.0 ns): client received DATA, sequence 51440, offset 155820, length 1500, flags 0 1488437293.636389942 TimeTrace.cc:172 in printInternal NOTICE[1]: 126046664.2 ns (+ 62.7 ns): client sending GRANT, sequence 51440, offset 166110, priority 0 1488437293.636392439 TimeTrace.cc:172 in printInternal NOTICE[1]: 126046858.6 ns (+ 194.5 ns): client received DATA, sequence 51440, offset 157290, length 1500, flags 0 1488437293.636394877 TimeTrace.cc:172 in printInternal NOTICE[1]: 126046922.3 ns (+ 63.7 ns): client sending GRANT, sequence 51440, offset 167580, priority 0 1488437293.636397207 TimeTrace.cc:172 in printInternal NOTICE[1]: 126047066.6 ns (+ 144.3 ns): client received GRANT, sequence 51448, offset 167580 1488437293.636399547 TimeTrace.cc:172 in printInternal NOTICE[1]: 126047162.9 ns (+ 96.2 ns): DpdkDriver received 3 packets 1488437293.636401874 TimeTrace.cc:172 in printInternal NOTICE[1]: 126047827.0 ns (+ 664.1 ns): client received GRANT, sequence 51448, offset 169050 1488437293.636404343 TimeTrace.cc:172 in printInternal NOTICE[1]: 126047887.6 ns (+ 60.6 ns): client received DATA, sequence 51440, offset 158760, length 1500, flags 0 1488437293.636406789 TimeTrace.cc:172 in printInternal NOTICE[1]: 126047936.7 ns (+ 49.1 ns): client sending GRANT, sequence 51440, offset 169050, priority 0 1488437293.636409181 TimeTrace.cc:172 in printInternal NOTICE[1]: 126048100.1 ns (+ 163.4 ns): client received GRANT, sequence 51448, offset 170520 1488437293.636411577 TimeTrace.cc:172 in printInternal NOTICE[1]: 126048202.4 ns (+ 102.2 ns): received 7 packets at polling iteration 24210203 1488437293.636413912 TimeTrace.cc:172 in printInternal NOTICE[1]: 126048266.5 ns (+ 64.2 ns): transmitQueueSpace 3000, maxBytes 3000 1488437293.636416468 TimeTrace.cc:172 in printInternal NOTICE[1]: 126048853.0 ns (+ 586.4 ns): sent data, sequence 51448, offset 166110, length 2940, priority 0 1488437293.636418860 TimeTrace.cc:172 in printInternal NOTICE[1]: 126049267.5 ns (+ 414.5 ns): DpdkDriver received 2 packets 1488437293.636421303 TimeTrace.cc:172 in printInternal NOTICE[1]: 126049733.6 ns (+ 466.1 ns): client received DATA, sequence 51440, offset 160230, length 1500, flags 0 1488437293.636423722 TimeTrace.cc:172 in printInternal NOTICE[1]: 126049809.3 ns (+ 75.7 ns): client sending GRANT, sequence 51440, offset 170520, priority 0 1488437293.636426040 TimeTrace.cc:172 in printInternal NOTICE[1]: 126049960.6 ns (+ 151.4 ns): client received GRANT, sequence 51448, offset 171990 1488437293.636428373 TimeTrace.cc:172 in printInternal NOTICE[1]: 126050011.3 ns (+ 50.6 ns): received 2 packets at polling iteration 24210204 1488437293.636430591 TimeTrace.cc:172 in printInternal NOTICE[1]: 126050060.4 ns (+ 49.1 ns): transmitQueueSpace 3000, maxBytes 2940 1488437293.636432971 TimeTrace.cc:172 in printInternal NOTICE[1]: 126050611.2 ns (+ 550.8 ns): sent data, sequence 51448, offset 169050, length 2940, priority 0 1488437293.636435206 TimeTrace.cc:172 in printInternal NOTICE[1]: 126064586.0 ns (+13974.8 ns): DpdkDriver received 1 packets 1488437293.636437811 TimeTrace.cc:172 in printInternal NOTICE[1]: 126064829.5 ns (+ 243.6 ns): client received GRANT, sequence 51448, offset 173460 1488437293.636440184 TimeTrace.cc:172 in printInternal NOTICE[1]: 126064866.6 ns (+ 37.1 ns): received 1 packets at polling iteration 24210265 1488437293.636442447 TimeTrace.cc:172 in printInternal NOTICE[1]: 126064943.8 ns (+ 77.2 ns): transmitQueueSpace 3000, maxBytes 1470 1488437293.636444891 TimeTrace.cc:172 in printInternal NOTICE[1]: 126065211.0 ns (+ 267.1 ns): sent data, sequence 51448, offset 171990, length 1470, priority 0 1488437293.636447178 TimeTrace.cc:172 in printInternal NOTICE[1]: 126065431.0 ns (+ 220.0 ns): DpdkDriver received 2 packets 1488437293.636449503 TimeTrace.cc:172 in printInternal NOTICE[1]: 126065863.0 ns (+ 432.0 ns): client received GRANT, sequence 51448, offset 174930 1488437293.636451861 TimeTrace.cc:172 in printInternal NOTICE[1]: 126065962.3 ns (+ 99.2 ns): client received ALL_DATA, sequence 51451, length 58 1488437293.636454310 TimeTrace.cc:172 in printInternal NOTICE[1]: 126066029.4 ns (+ 67.2 ns): BAD TAIL LATENCY 22.92 us 1488437293.636456601 TimeTrace.cc:172 in printInternal NOTICE[1]: 126066243.0 ns (+ 213.5 ns): deleted client RPC, sequence 51451 1488437293.636459047 TimeTrace.cc:172 in printInternal NOTICE[1]: 126066308.6 ns (+ 65.7 ns): received 2 packets at polling iteration 24210266 Server 1488437292.360483782 TimeTrace.cc:172 in printInternal NOTICE[7]: 124604306.5 ns (+ 145.3 ns): received 1 packets at polling iteration 47159814 1488437292.360485916 TimeTrace.cc:172 in printInternal NOTICE[7]: 124604929.5 ns (+ 623.0 ns): DpdkDriver received 4 packets 1488437292.360488238 TimeTrace.cc:172 in printInternal NOTICE[7]: 124622737.8 ns (+17808.3 ns): server received DATA, sequence 51448, offset 163170, length 1500, flags 1 1488437292.360490745 TimeTrace.cc:172 in printInternal NOTICE[7]: 124622785.4 ns (+ 47.6 ns): server sending GRANT, sequence 51448, offset 173460, priority 0 1488437292.360492984 TimeTrace.cc:172 in printInternal NOTICE[7]: 124622979.8 ns (+ 194.5 ns): server received GRANT, sequence 51440, offset 163170 1488437292.360495412 TimeTrace.cc:172 in printInternal NOTICE[7]: 124623041.0 ns (+ 61.1 ns): server received DATA, sequence 51448, offset 164640, length 1500, flags 1 1488437292.360497558 TimeTrace.cc:172 in printInternal NOTICE[7]: 124623094.1 ns (+ 53.1 ns): server sending GRANT, sequence 51448, offset 174930, priority 0 1488437292.360499717 TimeTrace.cc:172 in printInternal NOTICE[7]: 124623274.0 ns (+ 179.9 ns): server received ALL_DATA, sequence 51451, length 42 1488437292.360501972 TimeTrace.cc:172 in printInternal NOTICE[7]: 124623727.1 ns (+ 453.1 ns): sendReply invoked, sequence 51451, length 38 1488437292.360504158 TimeTrace.cc:172 in printInternal NOTICE[7]: 124623783.2 ns (+ 56.1 ns): transmitQueueSpace 3000, maxBytes 3000 1488437292.360506486 TimeTrace.cc:172 in printInternal NOTICE[7]: 124623945.1 ns (+ 161.9 ns): sent data, sequence 51451, offset 0, length 38, priority 7 1488437292.360508696 TimeTrace.cc:172 in printInternal NOTICE[7]: 124624091.0 ns (+ 145.8 ns): deleted server RPC, sequence 51451 Client 1488437293.503122252 TimeTrace.cc:172 in printInternal NOTICE[1]: 111900351.6 ns (+5185.5 ns): sendRequest invoked, sequence 51378, length 42 1488437293.503124764 TimeTrace.cc:172 in printInternal NOTICE[1]: 111900564.1 ns (+ 212.5 ns): transmitQueueSpace 3000, maxBytes 3000 1488437293.503127283 TimeTrace.cc:172 in printInternal NOTICE[1]: 111900764.6 ns (+ 200.5 ns): sent data, sequence 51378, offset 0, length 42, priority 7 1488437293.503129539 TimeTrace.cc:172 in printInternal NOTICE[1]: 111901969.0 ns (+1204.4 ns): DpdkDriver received 1 packets 1488437293.503132081 TimeTrace.cc:172 in printInternal NOTICE[1]: 111902300.3 ns (+ 331.3 ns): client received DATA, sequence 51352, offset 335160, length 1500, flags 0 1488437293.503134491 TimeTrace.cc:172 in printInternal NOTICE[1]: 111902360.9 ns (+ 60.6 ns): client sending GRANT, sequence 51352, offset 345450, priority 0 1488437293.503136770 TimeTrace.cc:172 in printInternal NOTICE[1]: 111902505.3 ns (+ 144.3 ns): received 1 packets at polling iteration 24187587 1488437293.503139016 TimeTrace.cc:172 in printInternal NOTICE[1]: 111902823.1 ns (+ 317.8 ns): DpdkDriver received 4 packets 1488437293.503141436 TimeTrace.cc:172 in printInternal NOTICE[1]: 111903767.3 ns (+ 944.3 ns): client received DATA, sequence 51352, offset 336630, length 1500, flags 0 1488437293.503143852 TimeTrace.cc:172 in printInternal NOTICE[1]: 111903819.5 ns (+ 52.1 ns): client sending GRANT, sequence 51352, offset 346920, priority 0 1488437293.503146132 TimeTrace.cc:172 in printInternal NOTICE[1]: 111904006.4 ns (+ 187.0 ns): client received GRANT, sequence 51358, offset 349860 1488437293.503148451 TimeTrace.cc:172 in printInternal NOTICE[1]: 111904051.0 ns (+ 44.6 ns): client received GRANT, sequence 51358, offset 351330 1488437293.503150683 TimeTrace.cc:172 in printInternal NOTICE[1]: 111904095.6 ns (+ 44.6 ns): client received GRANT, sequence 51358, offset 352800 1488437293.503152833 TimeTrace.cc:172 in printInternal NOTICE[1]: 111904168.3 ns (+ 72.7 ns): DpdkDriver received 2 packets 1488437293.503155066 TimeTrace.cc:172 in printInternal NOTICE[1]: 111904576.8 ns (+ 408.5 ns): client received GRANT, sequence 51358, offset 354270 1488437293.503157377 TimeTrace.cc:172 in printInternal NOTICE[1]: 111904612.4 ns (+ 35.6 ns): client received GRANT, sequence 51358, offset 355740 1488437293.503159679 TimeTrace.cc:172 in printInternal NOTICE[1]: 111904680.5 ns (+ 68.2 ns): received 6 packets at polling iteration 24187588 1488437293.503161906 TimeTrace.cc:172 in printInternal NOTICE[1]: 111904820.4 ns (+ 139.8 ns): transmitQueueSpace 3000, maxBytes 3000 1488437293.503164408 TimeTrace.cc:172 in printInternal NOTICE[1]: 111905374.2 ns (+ 553.8 ns): sent data, sequence 51358, offset 348390, length 2940, priority 0 1488437293.503166777 TimeTrace.cc:172 in printInternal NOTICE[1]: 111906742.0 ns (+1367.8 ns): transmitQueueSpace 1815, maxBytes 1815 1488437293.503169326 TimeTrace.cc:172 in printInternal NOTICE[1]: 111907016.7 ns (+ 274.7 ns): sent data, sequence 51358, offset 351330, length 1470, priority 0 1488437293.503171735 TimeTrace.cc:172 in printInternal NOTICE[1]: 111907716.4 ns (+ 699.7 ns): transmitQueueSpace 1543, maxBytes 1543 1488437293.503174220 TimeTrace.cc:172 in printInternal NOTICE[1]: 111908010.1 ns (+ 293.7 ns): sent data, sequence 51358, offset 352800, length 1470, priority 0 1488437293.503176507 TimeTrace.cc:172 in printInternal NOTICE[1]: 111908985.9 ns (+ 975.9 ns): transmitQueueSpace 1623, maxBytes 1470 1488437293.503179069 TimeTrace.cc:172 in printInternal NOTICE[1]: 111909262.1 ns (+ 276.2 ns): sent data, sequence 51358, offset 354270, length 1470, priority 0 1488437293.503181315 TimeTrace.cc:172 in printInternal NOTICE[1]: 111920205.5 ns (+10943.4 ns): DpdkDriver received 1 packets 1488437293.503183835 TimeTrace.cc:172 in printInternal NOTICE[1]: 111920446.1 ns (+ 240.6 ns): client received GRANT, sequence 51358, offset 357210 1488437293.503186201 TimeTrace.cc:172 in printInternal NOTICE[1]: 111920483.2 ns (+ 37.1 ns): received 1 packets at polling iteration 24187631 1488437293.503188438 TimeTrace.cc:172 in printInternal NOTICE[1]: 111920563.4 ns (+ 80.2 ns): transmitQueueSpace 3000, maxBytes 1470 1488437293.503190828 TimeTrace.cc:172 in printInternal NOTICE[1]: 111920848.6 ns (+ 285.2 ns): sent data, sequence 51358, offset 355740, length 1470, priority 0 1488437293.503193103 TimeTrace.cc:172 in printInternal NOTICE[1]: 111923732.6 ns (+2884.0 ns): DpdkDriver received 1 packets 1488437293.503195676 TimeTrace.cc:172 in printInternal NOTICE[1]: 111924050.3 ns (+ 317.8 ns): client received DATA, sequence 51352, offset 338100, length 1500, flags 0 1488437293.503198082 TimeTrace.cc:172 in printInternal NOTICE[1]: 111924099.4 ns (+ 49.1 ns): client sending GRANT, sequence 51352, offset 348390, priority 0 1488437293.503200404 TimeTrace.cc:172 in printInternal NOTICE[1]: 111924262.8 ns (+ 163.4 ns): received 1 packets at polling iteration 24187640 1488437293.503202759 TimeTrace.cc:172 in printInternal NOTICE[1]: 111924899.9 ns (+ 637.0 ns): DpdkDriver received 4 packets 1488437293.503205235 TimeTrace.cc:172 in printInternal NOTICE[1]: 111925797.0 ns (+ 897.2 ns): client received DATA, sequence 51352, offset 339570, length 1500, flags 0 1488437293.503207731 TimeTrace.cc:172 in printInternal NOTICE[1]: 111925857.7 ns (+ 60.6 ns): client sending GRANT, sequence 51352, offset 349860, priority 0 1488437293.503209994 TimeTrace.cc:172 in printInternal NOTICE[1]: 111926034.6 ns (+ 176.9 ns): client received GRANT, sequence 51358, offset 358680 1488437293.503212358 TimeTrace.cc:172 in printInternal NOTICE[1]: 111926082.2 ns (+ 47.6 ns): client received GRANT, sequence 51358, offset 360150 1488437293.503214709 TimeTrace.cc:172 in printInternal NOTICE[1]: 111926132.3 ns (+ 50.1 ns): client received GRANT, sequence 51358, offset 361620 1488437293.503216975 TimeTrace.cc:172 in printInternal NOTICE[1]: 111926198.0 ns (+ 65.7 ns): DpdkDriver received 3 packets 1488437293.503219244 TimeTrace.cc:172 in printInternal NOTICE[1]: 111926665.6 ns (+ 467.6 ns): client received GRANT, sequence 51358, offset 363090 1488437293.503221615 TimeTrace.cc:172 in printInternal NOTICE[1]: 111926701.2 ns (+ 35.6 ns): client received GRANT, sequence 51358, offset 364560 1488437293.503223915 TimeTrace.cc:172 in printInternal NOTICE[1]: 111926775.4 ns (+ 74.2 ns): client received ALL_DATA, sequence 51378, length 58 1488437293.503226465 TimeTrace.cc:172 in printInternal NOTICE[1]: 111926842.6 ns (+ 67.2 ns): BAD TAIL LATENCY 26.52 us 1488437293.503228784 TimeTrace.cc:172 in printInternal NOTICE[1]: 111927022.0 ns (+ 179.4 ns): deleted client RPC, sequence 51378 1488437293.503231172 TimeTrace.cc:172 in printInternal NOTICE[1]: 111927115.7 ns (+ 93.7 ns): received 7 packets at polling iteration 24187642 Server 1488437292.242591429 TimeTrace.cc:172 in printInternal NOTICE[7]: 110462142.8 ns (+ 253.1 ns): DpdkDriver received 2 packets 1488437292.242593587 TimeTrace.cc:172 in printInternal NOTICE[7]: 110462552.7 ns (+ 410.0 ns): server received GRANT, sequence 51352, offset 343980 1488437292.242595905 TimeTrace.cc:172 in printInternal NOTICE[7]: 110479244.4 ns (+16691.7 ns): server received DATA, sequence 51358, offset 346920, length 1500, flags 1 1488437292.242598327 TimeTrace.cc:172 in printInternal NOTICE[7]: 110479486.5 ns (+ 242.1 ns): server sending GRANT, sequence 51358, offset 357210, priority 0 1488437292.242600534 TimeTrace.cc:172 in printInternal NOTICE[7]: 110479704.5 ns (+ 218.0 ns): received 10 packets at polling iteration 47115771 1488437292.242602693 TimeTrace.cc:172 in printInternal NOTICE[7]: 110479756.6 ns (+ 52.1 ns): transmitQueueSpace 3000, maxBytes 3000 1488437292.242604884 TimeTrace.cc:172 in printInternal NOTICE[7]: 110480331.5 ns (+ 574.8 ns): sent data, sequence 51352, offset 338100, length 2940, priority 0 1488437292.242606983 TimeTrace.cc:172 in printInternal NOTICE[7]: 110480491.8 ns (+ 160.4 ns): DpdkDriver received 4 packets 1488437292.242609112 TimeTrace.cc:172 in printInternal NOTICE[7]: 110481409.5 ns (+ 917.7 ns): server received ALL_DATA, sequence 51378, length 42 1488437292.242611333 TimeTrace.cc:172 in printInternal NOTICE[7]: 110482092.6 ns (+ 683.1 ns): sendReply invoked, sequence 51378, length 38 1488437292.242613442 TimeTrace.cc:172 in printInternal NOTICE[7]: 110482211.4 ns (+ 118.8 ns): server received GRANT, sequence 51352, offset 345450 1488437292.242615631 TimeTrace.cc:172 in printInternal NOTICE[7]: 110482272.0 ns (+ 60.6 ns): server received GRANT, sequence 51352, offset 346920 1488437292.242617794 TimeTrace.cc:172 in printInternal NOTICE[7]: 110482361.2 ns (+ 89.2 ns): server received DATA, sequence 51358, offset 348390, length 1500, flags 1 1488437292.242619947 TimeTrace.cc:172 in printInternal NOTICE[7]: 110482416.4 ns (+ 55.1 ns): server sending GRANT, sequence 51358, offset 358680, priority 0 1488437292.242622032 TimeTrace.cc:172 in printInternal NOTICE[7]: 110482692.5 ns (+ 276.1 ns): DpdkDriver received 4 packets 1488437292.242624331 TimeTrace.cc:172 in printInternal NOTICE[7]: 110483741.0 ns (+1048.5 ns): server received DATA, sequence 51358, offset 349860, length 1500, flags 1 1488437292.242627409 TimeTrace.cc:172 in printInternal NOTICE[7]: 110483816.6 ns (+ 75.7 ns): server sending GRANT, sequence 51358, offset 360150, priority 0 1488437292.242631520 TimeTrace.cc:172 in printInternal NOTICE[7]: 110484034.6 ns (+ 218.0 ns): server received DATA, sequence 51358, offset 351330, length 1500, flags 1 1488437292.242635266 TimeTrace.cc:172 in printInternal NOTICE[7]: 110484091.3 ns (+ 56.6 ns): server sending GRANT, sequence 51358, offset 361620, priority 0 1488437292.242639212 TimeTrace.cc:172 in printInternal NOTICE[7]: 110484297.8 ns (+ 206.5 ns): server received DATA, sequence 51358, offset 352800, length 1500, flags 1 1488437292.242643076 TimeTrace.cc:172 in printInternal NOTICE[7]: 110484356.9 ns (+ 59.1 ns): server sending GRANT, sequence 51358, offset 363090, priority 0 1488437292.242646816 TimeTrace.cc:172 in printInternal NOTICE[7]: 110484558.9 ns (+ 202.0 ns): server received DATA, sequence 51358, offset 354270, length 1500, flags 1 1488437292.242650162 TimeTrace.cc:172 in printInternal NOTICE[7]: 110484615.5 ns (+ 56.6 ns): server sending GRANT, sequence 51358, offset 364560, priority 0 1488437292.242652278 TimeTrace.cc:172 in printInternal NOTICE[7]: 110484912.2 ns (+ 296.7 ns): received 8 packets at polling iteration 47115772 1488437292.242654394 TimeTrace.cc:172 in printInternal NOTICE[7]: 110484992.4 ns (+ 80.2 ns): transmitQueueSpace 3000, maxBytes 3000 1488437292.242656685 TimeTrace.cc:172 in printInternal NOTICE[7]: 110485163.3 ns (+ 170.9 ns): sent data, sequence 51378, offset 0, length 38, priority 7 1488437292.242658782 TimeTrace.cc:172 in printInternal NOTICE[7]: 110485421.9 ns (+ 258.6 ns): deleted server RPC, sequence 51378 Client 1488437292.816254778 TimeTrace.cc:172 in printInternal NOTICE[1]: 26271713.9 ns (+ 609.0 ns): sendRequest invoked, sequence 50936, length 42 1488437292.816257209 TimeTrace.cc:172 in printInternal NOTICE[1]: 26271890.8 ns (+ 176.9 ns): transmitQueueSpace 3000, maxBytes 3000 1488437292.816259720 TimeTrace.cc:172 in printInternal NOTICE[1]: 26272061.2 ns (+ 170.4 ns): sent data, sequence 50936, offset 0, length 42, priority 7 1488437292.816262023 TimeTrace.cc:172 in printInternal NOTICE[1]: 26272611.0 ns (+ 549.8 ns): DpdkDriver received 1 packets 1488437292.816264422 TimeTrace.cc:172 in printInternal NOTICE[1]: 26272998.5 ns (+ 387.4 ns): client received DATA, sequence 50926, offset 157290, length 1500, flags 0 1488437292.816266926 TimeTrace.cc:172 in printInternal NOTICE[1]: 26273053.6 ns (+ 55.1 ns): client sending GRANT, sequence 50926, offset 167580, priority 0 1488437292.816269222 TimeTrace.cc:172 in printInternal NOTICE[1]: 26273208.0 ns (+ 154.4 ns): received 1 packets at polling iteration 24002483 1488437292.816271565 TimeTrace.cc:172 in printInternal NOTICE[1]: 26273568.8 ns (+ 360.9 ns): DpdkDriver received 2 packets 1488437292.816274026 TimeTrace.cc:172 in printInternal NOTICE[1]: 26274127.2 ns (+ 558.3 ns): client received DATA, sequence 50926, offset 158760, length 1500, flags 0 1488437292.816276470 TimeTrace.cc:172 in printInternal NOTICE[1]: 26274176.3 ns (+ 49.1 ns): client sending GRANT, sequence 50926, offset 169050, priority 0 1488437292.816278785 TimeTrace.cc:172 in printInternal NOTICE[1]: 26274360.2 ns (+ 183.9 ns): client received GRANT, sequence 50929, offset 176400 1488437292.816281153 TimeTrace.cc:172 in printInternal NOTICE[1]: 26274400.3 ns (+ 40.1 ns): received 2 packets at polling iteration 24002484 1488437292.816283369 TimeTrace.cc:172 in printInternal NOTICE[1]: 26274448.0 ns (+ 47.6 ns): transmitQueueSpace 3000, maxBytes 1470 1488437292.816285899 TimeTrace.cc:172 in printInternal NOTICE[1]: 26274713.6 ns (+ 265.6 ns): sent data, sequence 50929, offset 174930, length 1470, priority 0 1488437292.816288275 TimeTrace.cc:172 in printInternal NOTICE[1]: 26274932.1 ns (+ 218.5 ns): DpdkDriver received 1 packets 1488437292.816290741 TimeTrace.cc:172 in printInternal NOTICE[1]: 26275199.3 ns (+ 267.1 ns): client received DATA, sequence 50926, offset 160230, length 1500, flags 0 1488437292.816293211 TimeTrace.cc:172 in printInternal NOTICE[1]: 26275251.4 ns (+ 52.1 ns): client sending GRANT, sequence 50926, offset 170520, priority 0 1488437292.816295514 TimeTrace.cc:172 in printInternal NOTICE[1]: 26275408.8 ns (+ 157.4 ns): received 1 packets at polling iteration 24002485 1488437292.816297861 TimeTrace.cc:172 in printInternal NOTICE[1]: 26290733.3 ns (+15324.5 ns): DpdkDriver received 3 packets 1488437292.816300455 TimeTrace.cc:172 in printInternal NOTICE[1]: 26291469.6 ns (+ 736.3 ns): client received DATA, sequence 50926, offset 161700, length 1500, flags 0 1488437292.816302987 TimeTrace.cc:172 in printInternal NOTICE[1]: 26291530.7 ns (+ 61.1 ns): client sending GRANT, sequence 50926, offset 171990, priority 0 1488437292.816305258 TimeTrace.cc:172 in printInternal NOTICE[1]: 26291723.7 ns (+ 193.0 ns): client received GRANT, sequence 50929, offset 177870 1488437292.816307707 TimeTrace.cc:172 in printInternal NOTICE[1]: 26291769.8 ns (+ 46.1 ns): client received GRANT, sequence 50929, offset 179340 1488437292.816309995 TimeTrace.cc:172 in printInternal NOTICE[1]: 26291820.4 ns (+ 50.6 ns): received 3 packets at polling iteration 24002556 1488437292.816312264 TimeTrace.cc:172 in printInternal NOTICE[1]: 26291873.5 ns (+ 53.1 ns): transmitQueueSpace 3000, maxBytes 2940 1488437292.816314756 TimeTrace.cc:172 in printInternal NOTICE[1]: 26292403.8 ns (+ 530.3 ns): sent data, sequence 50929, offset 176400, length 2940, priority 0 1488437292.816317071 TimeTrace.cc:172 in printInternal NOTICE[1]: 26292614.8 ns (+ 211.0 ns): DpdkDriver received 4 packets 1488437292.816319402 TimeTrace.cc:172 in printInternal NOTICE[1]: 26293361.6 ns (+ 746.8 ns): client received GRANT, sequence 50929, offset 180810 1488437292.816321794 TimeTrace.cc:172 in printInternal NOTICE[1]: 26293416.8 ns (+ 55.1 ns): client received GRANT, sequence 50929, offset 182280 1488437292.816324124 TimeTrace.cc:172 in printInternal NOTICE[1]: 26293456.9 ns (+ 40.1 ns): client received GRANT, sequence 50929, offset 183750 1488437292.816326430 TimeTrace.cc:172 in printInternal NOTICE[1]: 26293522.0 ns (+ 65.2 ns): client received ALL_DATA, sequence 50936, length 58 1488437292.816328931 TimeTrace.cc:172 in printInternal NOTICE[1]: 26293600.7 ns (+ 78.7 ns): BAD TAIL LATENCY 21.91 us 1488437292.816331186 TimeTrace.cc:172 in printInternal NOTICE[1]: 26293779.1 ns (+ 178.4 ns): deleted client RPC, sequence 50936 Server 1488437291.576465582 TimeTrace.cc:172 in printInternal NOTICE[7]: 24835723.1 ns (+ 114.3 ns): DpdkDriver received 1 packets 1488437291.576467751 TimeTrace.cc:172 in printInternal NOTICE[7]: 24852997.6 ns (+17274.5 ns): server received GRANT, sequence 50926, offset 163170 1488437291.576470136 TimeTrace.cc:172 in printInternal NOTICE[7]: 24853046.8 ns (+ 49.1 ns): received 1 packets at polling iteration 46801935 1488437291.576472209 TimeTrace.cc:172 in printInternal NOTICE[7]: 24853088.4 ns (+ 41.6 ns): transmitQueueSpace 1613, maxBytes 1470 1488437291.576474506 TimeTrace.cc:172 in printInternal NOTICE[7]: 24853351.0 ns (+ 262.6 ns): sent data, sequence 50926, offset 161700, length 1470, priority 0 1488437291.576476643 TimeTrace.cc:172 in printInternal NOTICE[7]: 24853480.3 ns (+ 129.3 ns): DpdkDriver received 4 packets 1488437291.576478882 TimeTrace.cc:172 in printInternal NOTICE[7]: 24854527.2 ns (+1047.0 ns): server received DATA, sequence 50929, offset 167580, length 1500, flags 1 1488437291.576481242 TimeTrace.cc:172 in printInternal NOTICE[7]: 24854579.4 ns (+ 52.1 ns): server sending GRANT, sequence 50929, offset 177870, priority 0 1488437291.576483496 TimeTrace.cc:172 in printInternal NOTICE[7]: 24854733.7 ns (+ 154.4 ns): server received DATA, sequence 50929, offset 169050, length 1500, flags 1 1488437291.576485758 TimeTrace.cc:172 in printInternal NOTICE[7]: 24854784.3 ns (+ 50.6 ns): server sending GRANT, sequence 50929, offset 179340, priority 0 1488437291.576488015 TimeTrace.cc:172 in printInternal NOTICE[7]: 24854954.7 ns (+ 170.4 ns): server received DATA, sequence 50929, offset 170520, length 1500, flags 1 1488437291.576490278 TimeTrace.cc:172 in printInternal NOTICE[7]: 24855006.9 ns (+ 52.1 ns): server sending GRANT, sequence 50929, offset 180810, priority 0 1488437291.576492403 TimeTrace.cc:172 in printInternal NOTICE[7]: 24855168.7 ns (+ 161.9 ns): server received GRANT, sequence 50926, offset 164640 1488437291.576494559 TimeTrace.cc:172 in printInternal NOTICE[7]: 24855245.9 ns (+ 77.2 ns): DpdkDriver received 4 packets 1488437291.576496804 TimeTrace.cc:172 in printInternal NOTICE[7]: 24856135.5 ns (+ 889.6 ns): server received DATA, sequence 50929, offset 171990, length 1500, flags 1 1488437291.576499029 TimeTrace.cc:172 in printInternal NOTICE[7]: 24856187.6 ns (+ 52.1 ns): server sending GRANT, sequence 50929, offset 182280, priority 0 1488437291.576501707 TimeTrace.cc:172 in printInternal NOTICE[7]: 24856356.5 ns (+ 168.9 ns): server received GRANT, sequence 50926, offset 166110 1488437291.576505461 TimeTrace.cc:172 in printInternal NOTICE[7]: 24856414.7 ns (+ 58.1 ns): server received DATA, sequence 50929, offset 173460, length 1500, flags 1 1488437291.576508604 TimeTrace.cc:172 in printInternal NOTICE[7]: 24856465.3 ns (+ 50.6 ns): server sending GRANT, sequence 50929, offset 183750, priority 0 1488437291.576512161 TimeTrace.cc:172 in printInternal NOTICE[7]: 24856667.3 ns (+ 202.0 ns): server received ALL_DATA, sequence 50936, length 42 1488437291.576514815 TimeTrace.cc:172 in printInternal NOTICE[7]: 24857174.9 ns (+ 507.7 ns): sendReply invoked, sequence 50936, length 38 1488437291.576517008 TimeTrace.cc:172 in printInternal NOTICE[7]: 24857243.1 ns (+ 68.2 ns): transmitQueueSpace 3000, maxBytes 3000 1488437291.576519264 TimeTrace.cc:172 in printInternal NOTICE[7]: 24857420.0 ns (+ 176.9 ns): sent data, sequence 50936, offset 0, length 38, priority 7 1488437291.576521420 TimeTrace.cc:172 in printInternal NOTICE[7]: 24857610.0 ns (+ 189.9 ns): deleted server RPC, sequence 50936