Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Performance issue in NodeJS >= 10.0.0 #27667

Closed
hellivan opened this issue May 13, 2019 · 7 comments
Closed

Performance issue in NodeJS >= 10.0.0 #27667

hellivan opened this issue May 13, 2019 · 7 comments
Labels
confirmed-bug Issues with confirmed bugs. performance Issues and PRs related to the performance of Node.js. v8 engine Issues and PRs related to the V8 dependency.

Comments

@hellivan
Copy link

Not sure if this is the right place but I may have found a possible performance issue in NodeJS JavaScript execution.
While execution of this example script on a NodeJS runtime <10.0.0 runs smoothly, it freezes on NodeJS runtimes >= 10.0.0 for an indefinite amount of time.
I tested the script for all latest major releases of NodeJS and got the following results:

  • 8.16.0: Runs smoothly
  • 9.11.2: Runs smoothly
  • 10.0.0: Freeze
  • 10.15.3: Freeze
  • 11.15.0: Freeze
  • 12.2.0: Freeze

I tested the script on different machines (including windows hosts), always getting the same result.
Due to the facts that the example-script is a pure JavaScript file with no dependencies on any NodeJS or external modules, and that the issue occurred first in NodeJS version 10.0.0 I assume that it has to do something with the upgrade of V8 Runtime from 6.2 to 6.6, which was listed in the changelog ov NodeJS version 10.0.0.

NB: I discovered this issue after upgrading a service to newest NodeJS runtime. The service is responsible for validating arbitrary Data against a defined JSONSchema. Validation is implemented using an external library called AJV. After the update, the service started to freeze sporadically during validation process. The provided example script therefore contains an example of a validation function generated by AJV library and some sample data (array of 1000 elements) for which validation will freeze after some time (on my machine, depending on NodeJs version at element no ~594 or no ~610).

@ZYSzys ZYSzys added the performance Issues and PRs related to the performance of Node.js. label May 13, 2019
@Fishrock123 Fishrock123 added the v8 engine Issues and PRs related to the V8 dependency. label May 13, 2019
@bricss
Copy link

bricss commented May 13, 2019

Looks like, it's an issue with Ajv performance in general right now -> ajv-validator/ajv#995

@bnoordhuis
Copy link
Member

bnoordhuis commented May 13, 2019

Can you run rm *.log && node --prof script.js && node --prof-process *.log with node 8 and 10 and post the outputs? Thanks.

(The reason I ask you to do it is that it might be something local to your machine. That should hopefully show up in the profile log.)

edit: if it freezes, you might want to ^C it after a few seconds. That should still result in a usable log file.

@hellivan
Copy link
Author

hellivan commented May 14, 2019

Can you run rm *.log && node --prof script.js && node --prof-process *.log with node 8 and 10 and post the outputs? Thanks.

(The reason I ask you to do it is that it might be something local to your machine. That should hopefully show up in the profile log.)

edit: if it freezes, you might want to ^C it after a few seconds. That should still result in a usable log file.

NodeJS version 8.16.0:

Processing entities...
Processed entity 0/1000 in 4ms
Processed entity 1/1000 in 0ms
Processed entity 2/1000 in 0ms
Processed entity 3/1000 in 0ms
Processed entity 4/1000 in 0ms
Processed entity 5/1000 in 0ms
Processed entity 6/1000 in 0ms
Processed entity 7/1000 in 0ms
Processed entity 8/1000 in 0ms
Processed entity 9/1000 in 0ms
Processed entity 10/1000 in 0ms
Processed entity 11/1000 in 0ms
Processed entity 12/1000 in 0ms
Processed entity 13/1000 in 0ms
Processed entity 14/1000 in 0ms
Processed entity 15/1000 in 0ms
Processed entity 16/1000 in 0ms
Processed entity 17/1000 in 0ms
Processed entity 18/1000 in 0ms
Processed entity 19/1000 in 0ms
Processed entity 20/1000 in 0ms
Processed entity 21/1000 in 0ms
Processed entity 22/1000 in 0ms
Processed entity 23/1000 in 0ms
Processed entity 24/1000 in 0ms
Processed entity 25/1000 in 0ms
Processed entity 26/1000 in 0ms
Processed entity 27/1000 in 0ms
Processed entity 28/1000 in 0ms
Processed entity 29/1000 in 0ms
Processed entity 30/1000 in 0ms
Processed entity 31/1000 in 0ms
Processed entity 32/1000 in 0ms
Processed entity 33/1000 in 0ms
Processed entity 34/1000 in 0ms
Processed entity 35/1000 in 0ms
Processed entity 36/1000 in 0ms
Processed entity 37/1000 in 0ms
Processed entity 38/1000 in 0ms
Processed entity 39/1000 in 0ms
Processed entity 40/1000 in 0ms
Processed entity 41/1000 in 0ms
Processed entity 42/1000 in 0ms
Processed entity 43/1000 in 0ms
Processed entity 44/1000 in 0ms
Processed entity 45/1000 in 0ms
Processed entity 46/1000 in 0ms
Processed entity 47/1000 in 0ms
Processed entity 48/1000 in 0ms
Processed entity 49/1000 in 0ms
Processed entity 50/1000 in 0ms
Processed entity 51/1000 in 0ms
Processed entity 52/1000 in 0ms
Processed entity 53/1000 in 0ms
Processed entity 54/1000 in 0ms
Processed entity 55/1000 in 0ms
Processed entity 56/1000 in 0ms
Processed entity 57/1000 in 0ms
Processed entity 58/1000 in 0ms
Processed entity 59/1000 in 0ms
Processed entity 60/1000 in 0ms
Processed entity 61/1000 in 0ms
Processed entity 62/1000 in 0ms
Processed entity 63/1000 in 0ms
Processed entity 64/1000 in 0ms
Processed entity 65/1000 in 0ms
Processed entity 66/1000 in 0ms
Processed entity 67/1000 in 0ms
Processed entity 68/1000 in 0ms
Processed entity 69/1000 in 0ms
Processed entity 70/1000 in 0ms
Processed entity 71/1000 in 0ms
Processed entity 72/1000 in 0ms
Processed entity 73/1000 in 0ms
Processed entity 74/1000 in 0ms
Processed entity 75/1000 in 0ms
Processed entity 76/1000 in 0ms
Processed entity 77/1000 in 0ms
Processed entity 78/1000 in 0ms
Processed entity 79/1000 in 0ms
Processed entity 80/1000 in 0ms
Processed entity 81/1000 in 0ms
Processed entity 82/1000 in 0ms
Processed entity 83/1000 in 0ms
Processed entity 84/1000 in 0ms
Processed entity 85/1000 in 0ms
Processed entity 86/1000 in 0ms
Processed entity 87/1000 in 0ms
Processed entity 88/1000 in 0ms
Processed entity 89/1000 in 0ms
Processed entity 90/1000 in 0ms
Processed entity 91/1000 in 0ms
Processed entity 92/1000 in 0ms
Processed entity 93/1000 in 0ms
Processed entity 94/1000 in 0ms
Processed entity 95/1000 in 1ms
Processed entity 96/1000 in 0ms
Processed entity 97/1000 in 0ms
Processed entity 98/1000 in 0ms
Processed entity 99/1000 in 0ms
Processed entity 100/1000 in 0ms
Processed entity 101/1000 in 0ms
Processed entity 102/1000 in 0ms
Processed entity 103/1000 in 0ms
Processed entity 104/1000 in 0ms
Processed entity 105/1000 in 0ms
Processed entity 106/1000 in 0ms
Processed entity 107/1000 in 0ms
Processed entity 108/1000 in 0ms
Processed entity 109/1000 in 0ms
Processed entity 110/1000 in 0ms
Processed entity 111/1000 in 0ms
Processed entity 112/1000 in 0ms
Processed entity 113/1000 in 0ms
Processed entity 114/1000 in 0ms
Processed entity 115/1000 in 0ms
Processed entity 116/1000 in 0ms
Processed entity 117/1000 in 0ms
Processed entity 118/1000 in 0ms
Processed entity 119/1000 in 0ms
Processed entity 120/1000 in 0ms
Processed entity 121/1000 in 0ms
Processed entity 122/1000 in 0ms
Processed entity 123/1000 in 0ms
Processed entity 124/1000 in 0ms
Processed entity 125/1000 in 0ms
Processed entity 126/1000 in 0ms
Processed entity 127/1000 in 0ms
Processed entity 128/1000 in 0ms
Processed entity 129/1000 in 0ms
Processed entity 130/1000 in 0ms
Processed entity 131/1000 in 0ms
Processed entity 132/1000 in 0ms
Processed entity 133/1000 in 0ms
Processed entity 134/1000 in 0ms
Processed entity 135/1000 in 0ms
Processed entity 136/1000 in 0ms
Processed entity 137/1000 in 0ms
Processed entity 138/1000 in 0ms
Processed entity 139/1000 in 0ms
Processed entity 140/1000 in 0ms
Processed entity 141/1000 in 0ms
Processed entity 142/1000 in 0ms
Processed entity 143/1000 in 0ms
Processed entity 144/1000 in 0ms
Processed entity 145/1000 in 0ms
Processed entity 146/1000 in 0ms
Processed entity 147/1000 in 0ms
Processed entity 148/1000 in 0ms
Processed entity 149/1000 in 0ms
Processed entity 150/1000 in 0ms
Processed entity 151/1000 in 0ms
Processed entity 152/1000 in 0ms
Processed entity 153/1000 in 0ms
Processed entity 154/1000 in 0ms
Processed entity 155/1000 in 0ms
Processed entity 156/1000 in 0ms
Processed entity 157/1000 in 0ms
Processed entity 158/1000 in 0ms
Processed entity 159/1000 in 0ms
Processed entity 160/1000 in 0ms
Processed entity 161/1000 in 0ms
Processed entity 162/1000 in 0ms
Processed entity 163/1000 in 0ms
Processed entity 164/1000 in 0ms
Processed entity 165/1000 in 0ms
Processed entity 166/1000 in 0ms
Processed entity 167/1000 in 0ms
Processed entity 168/1000 in 0ms
Processed entity 169/1000 in 0ms
Processed entity 170/1000 in 0ms
Processed entity 171/1000 in 0ms
Processed entity 172/1000 in 0ms
Processed entity 173/1000 in 0ms
Processed entity 174/1000 in 0ms
Processed entity 175/1000 in 0ms
Processed entity 176/1000 in 0ms
Processed entity 177/1000 in 0ms
Processed entity 178/1000 in 0ms
Processed entity 179/1000 in 0ms
Processed entity 180/1000 in 0ms
Processed entity 181/1000 in 0ms
Processed entity 182/1000 in 0ms
Processed entity 183/1000 in 0ms
Processed entity 184/1000 in 0ms
Processed entity 185/1000 in 0ms
Processed entity 186/1000 in 0ms
Processed entity 187/1000 in 0ms
Processed entity 188/1000 in 0ms
Processed entity 189/1000 in 0ms
Processed entity 190/1000 in 0ms
Processed entity 191/1000 in 0ms
Processed entity 192/1000 in 0ms
Processed entity 193/1000 in 0ms
Processed entity 194/1000 in 0ms
Processed entity 195/1000 in 0ms
Processed entity 196/1000 in 0ms
Processed entity 197/1000 in 0ms
Processed entity 198/1000 in 0ms
Processed entity 199/1000 in 0ms
Processed entity 200/1000 in 0ms
Processed entity 201/1000 in 0ms
Processed entity 202/1000 in 0ms
Processed entity 203/1000 in 0ms
Processed entity 204/1000 in 0ms
Processed entity 205/1000 in 0ms
Processed entity 206/1000 in 0ms
Processed entity 207/1000 in 0ms
Processed entity 208/1000 in 0ms
Processed entity 209/1000 in 0ms
Processed entity 210/1000 in 0ms
Processed entity 211/1000 in 0ms
Processed entity 212/1000 in 0ms
Processed entity 213/1000 in 0ms
Processed entity 214/1000 in 0ms
Processed entity 215/1000 in 0ms
Processed entity 216/1000 in 0ms
Processed entity 217/1000 in 0ms
Processed entity 218/1000 in 0ms
Processed entity 219/1000 in 0ms
Processed entity 220/1000 in 0ms
Processed entity 221/1000 in 0ms
Processed entity 222/1000 in 0ms
Processed entity 223/1000 in 0ms
Processed entity 224/1000 in 0ms
Processed entity 225/1000 in 0ms
Processed entity 226/1000 in 0ms
Processed entity 227/1000 in 0ms
Processed entity 228/1000 in 0ms
Processed entity 229/1000 in 1ms
Processed entity 230/1000 in 0ms
Processed entity 231/1000 in 0ms
Processed entity 232/1000 in 0ms
Processed entity 233/1000 in 0ms
Processed entity 234/1000 in 0ms
Processed entity 235/1000 in 0ms
Processed entity 236/1000 in 0ms
Processed entity 237/1000 in 0ms
Processed entity 238/1000 in 0ms
Processed entity 239/1000 in 0ms
Processed entity 240/1000 in 0ms
Processed entity 241/1000 in 0ms
Processed entity 242/1000 in 0ms
Processed entity 243/1000 in 0ms
Processed entity 244/1000 in 0ms
Processed entity 245/1000 in 0ms
Processed entity 246/1000 in 0ms
Processed entity 247/1000 in 0ms
Processed entity 248/1000 in 0ms
Processed entity 249/1000 in 0ms
Processed entity 250/1000 in 0ms
Processed entity 251/1000 in 0ms
Processed entity 252/1000 in 0ms
Processed entity 253/1000 in 0ms
Processed entity 254/1000 in 0ms
Processed entity 255/1000 in 0ms
Processed entity 256/1000 in 0ms
Processed entity 257/1000 in 0ms
Processed entity 258/1000 in 0ms
Processed entity 259/1000 in 0ms
Processed entity 260/1000 in 0ms
Processed entity 261/1000 in 0ms
Processed entity 262/1000 in 0ms
Processed entity 263/1000 in 0ms
Processed entity 264/1000 in 0ms
Processed entity 265/1000 in 0ms
Processed entity 266/1000 in 0ms
Processed entity 267/1000 in 0ms
Processed entity 268/1000 in 0ms
Processed entity 269/1000 in 0ms
Processed entity 270/1000 in 0ms
Processed entity 271/1000 in 0ms
Processed entity 272/1000 in 0ms
Processed entity 273/1000 in 1ms
Processed entity 274/1000 in 0ms
Processed entity 275/1000 in 0ms
Processed entity 276/1000 in 0ms
Processed entity 277/1000 in 0ms
Processed entity 278/1000 in 0ms
Processed entity 279/1000 in 0ms
Processed entity 280/1000 in 0ms
Processed entity 281/1000 in 0ms
Processed entity 282/1000 in 0ms
Processed entity 283/1000 in 0ms
Processed entity 284/1000 in 0ms
Processed entity 285/1000 in 0ms
Processed entity 286/1000 in 0ms
Processed entity 287/1000 in 0ms
Processed entity 288/1000 in 0ms
Processed entity 289/1000 in 0ms
Processed entity 290/1000 in 0ms
Processed entity 291/1000 in 0ms
Processed entity 292/1000 in 0ms
Processed entity 293/1000 in 0ms
Processed entity 294/1000 in 0ms
Processed entity 295/1000 in 0ms
Processed entity 296/1000 in 0ms
Processed entity 297/1000 in 0ms
Processed entity 298/1000 in 0ms
Processed entity 299/1000 in 0ms
Processed entity 300/1000 in 0ms
Processed entity 301/1000 in 0ms
Processed entity 302/1000 in 0ms
Processed entity 303/1000 in 0ms
Processed entity 304/1000 in 0ms
Processed entity 305/1000 in 0ms
Processed entity 306/1000 in 0ms
Processed entity 307/1000 in 0ms
Processed entity 308/1000 in 0ms
Processed entity 309/1000 in 0ms
Processed entity 310/1000 in 0ms
Processed entity 311/1000 in 0ms
Processed entity 312/1000 in 0ms
Processed entity 313/1000 in 0ms
Processed entity 314/1000 in 0ms
Processed entity 315/1000 in 0ms
Processed entity 316/1000 in 0ms
Processed entity 317/1000 in 0ms
Processed entity 318/1000 in 0ms
Processed entity 319/1000 in 0ms
Processed entity 320/1000 in 0ms
Processed entity 321/1000 in 0ms
Processed entity 322/1000 in 0ms
Processed entity 323/1000 in 0ms
Processed entity 324/1000 in 0ms
Processed entity 325/1000 in 0ms
Processed entity 326/1000 in 0ms
Processed entity 327/1000 in 0ms
Processed entity 328/1000 in 0ms
Processed entity 329/1000 in 0ms
Processed entity 330/1000 in 0ms
Processed entity 331/1000 in 0ms
Processed entity 332/1000 in 0ms
Processed entity 333/1000 in 0ms
Processed entity 334/1000 in 0ms
Processed entity 335/1000 in 0ms
Processed entity 336/1000 in 0ms
Processed entity 337/1000 in 0ms
Processed entity 338/1000 in 0ms
Processed entity 339/1000 in 0ms
Processed entity 340/1000 in 0ms
Processed entity 341/1000 in 0ms
Processed entity 342/1000 in 0ms
Processed entity 343/1000 in 0ms
Processed entity 344/1000 in 0ms
Processed entity 345/1000 in 0ms
Processed entity 346/1000 in 0ms
Processed entity 347/1000 in 0ms
Processed entity 348/1000 in 0ms
Processed entity 349/1000 in 0ms
Processed entity 350/1000 in 0ms
Processed entity 351/1000 in 0ms
Processed entity 352/1000 in 0ms
Processed entity 353/1000 in 0ms
Processed entity 354/1000 in 0ms
Processed entity 355/1000 in 0ms
Processed entity 356/1000 in 0ms
Processed entity 357/1000 in 0ms
Processed entity 358/1000 in 0ms
Processed entity 359/1000 in 0ms
Processed entity 360/1000 in 0ms
Processed entity 361/1000 in 0ms
Processed entity 362/1000 in 0ms
Processed entity 363/1000 in 0ms
Processed entity 364/1000 in 0ms
Processed entity 365/1000 in 0ms
Processed entity 366/1000 in 0ms
Processed entity 367/1000 in 0ms
Processed entity 368/1000 in 0ms
Processed entity 369/1000 in 0ms
Processed entity 370/1000 in 0ms
Processed entity 371/1000 in 0ms
Processed entity 372/1000 in 0ms
Processed entity 373/1000 in 0ms
Processed entity 374/1000 in 0ms
Processed entity 375/1000 in 0ms
Processed entity 376/1000 in 0ms
Processed entity 377/1000 in 0ms
Processed entity 378/1000 in 0ms
Processed entity 379/1000 in 0ms
Processed entity 380/1000 in 0ms
Processed entity 381/1000 in 0ms
Processed entity 382/1000 in 0ms
Processed entity 383/1000 in 0ms
Processed entity 384/1000 in 0ms
Processed entity 385/1000 in 0ms
Processed entity 386/1000 in 0ms
Processed entity 387/1000 in 0ms
Processed entity 388/1000 in 0ms
Processed entity 389/1000 in 0ms
Processed entity 390/1000 in 0ms
Processed entity 391/1000 in 0ms
Processed entity 392/1000 in 0ms
Processed entity 393/1000 in 0ms
Processed entity 394/1000 in 1ms
Processed entity 395/1000 in 0ms
Processed entity 396/1000 in 0ms
Processed entity 397/1000 in 0ms
Processed entity 398/1000 in 0ms
Processed entity 399/1000 in 0ms
Processed entity 400/1000 in 0ms
Processed entity 401/1000 in 0ms
Processed entity 402/1000 in 0ms
Processed entity 403/1000 in 0ms
Processed entity 404/1000 in 0ms
Processed entity 405/1000 in 0ms
Processed entity 406/1000 in 0ms
Processed entity 407/1000 in 0ms
Processed entity 408/1000 in 0ms
Processed entity 409/1000 in 0ms
Processed entity 410/1000 in 0ms
Processed entity 411/1000 in 0ms
Processed entity 412/1000 in 0ms
Processed entity 413/1000 in 0ms
Processed entity 414/1000 in 0ms
Processed entity 415/1000 in 0ms
Processed entity 416/1000 in 0ms
Processed entity 417/1000 in 0ms
Processed entity 418/1000 in 0ms
Processed entity 419/1000 in 0ms
Processed entity 420/1000 in 0ms
Processed entity 421/1000 in 0ms
Processed entity 422/1000 in 0ms
Processed entity 423/1000 in 0ms
Processed entity 424/1000 in 0ms
Processed entity 425/1000 in 0ms
Processed entity 426/1000 in 0ms
Processed entity 427/1000 in 1ms
Processed entity 428/1000 in 0ms
Processed entity 429/1000 in 0ms
Processed entity 430/1000 in 0ms
Processed entity 431/1000 in 0ms
Processed entity 432/1000 in 0ms
Processed entity 433/1000 in 0ms
Processed entity 434/1000 in 0ms
Processed entity 435/1000 in 0ms
Processed entity 436/1000 in 0ms
Processed entity 437/1000 in 0ms
Processed entity 438/1000 in 0ms
Processed entity 439/1000 in 0ms
Processed entity 440/1000 in 0ms
Processed entity 441/1000 in 0ms
Processed entity 442/1000 in 0ms
Processed entity 443/1000 in 0ms
Processed entity 444/1000 in 0ms
Processed entity 445/1000 in 0ms
Processed entity 446/1000 in 0ms
Processed entity 447/1000 in 0ms
Processed entity 448/1000 in 0ms
Processed entity 449/1000 in 0ms
Processed entity 450/1000 in 0ms
Processed entity 451/1000 in 0ms
Processed entity 452/1000 in 0ms
Processed entity 453/1000 in 0ms
Processed entity 454/1000 in 0ms
Processed entity 455/1000 in 0ms
Processed entity 456/1000 in 0ms
Processed entity 457/1000 in 0ms
Processed entity 458/1000 in 0ms
Processed entity 459/1000 in 0ms
Processed entity 460/1000 in 0ms
Processed entity 461/1000 in 0ms
Processed entity 462/1000 in 0ms
Processed entity 463/1000 in 0ms
Processed entity 464/1000 in 0ms
Processed entity 465/1000 in 0ms
Processed entity 466/1000 in 1ms
Processed entity 467/1000 in 0ms
Processed entity 468/1000 in 0ms
Processed entity 469/1000 in 0ms
Processed entity 470/1000 in 0ms
Processed entity 471/1000 in 0ms
Processed entity 472/1000 in 0ms
Processed entity 473/1000 in 0ms
Processed entity 474/1000 in 0ms
Processed entity 475/1000 in 0ms
Processed entity 476/1000 in 0ms
Processed entity 477/1000 in 0ms
Processed entity 478/1000 in 0ms
Processed entity 479/1000 in 0ms
Processed entity 480/1000 in 0ms
Processed entity 481/1000 in 0ms
Processed entity 482/1000 in 0ms
Processed entity 483/1000 in 0ms
Processed entity 484/1000 in 0ms
Processed entity 485/1000 in 0ms
Processed entity 486/1000 in 0ms
Processed entity 487/1000 in 0ms
Processed entity 488/1000 in 0ms
Processed entity 489/1000 in 0ms
Processed entity 490/1000 in 0ms
Processed entity 491/1000 in 0ms
Processed entity 492/1000 in 0ms
Processed entity 493/1000 in 0ms
Processed entity 494/1000 in 0ms
Processed entity 495/1000 in 0ms
Processed entity 496/1000 in 0ms
Processed entity 497/1000 in 0ms
Processed entity 498/1000 in 0ms
Processed entity 499/1000 in 0ms
Processed entity 500/1000 in 0ms
Processed entity 501/1000 in 0ms
Processed entity 502/1000 in 0ms
Processed entity 503/1000 in 0ms
Processed entity 504/1000 in 0ms
Processed entity 505/1000 in 0ms
Processed entity 506/1000 in 0ms
Processed entity 507/1000 in 0ms
Processed entity 508/1000 in 0ms
Processed entity 509/1000 in 0ms
Processed entity 510/1000 in 1ms
Processed entity 511/1000 in 0ms
Processed entity 512/1000 in 0ms
Processed entity 513/1000 in 0ms
Processed entity 514/1000 in 0ms
Processed entity 515/1000 in 0ms
Processed entity 516/1000 in 0ms
Processed entity 517/1000 in 0ms
Processed entity 518/1000 in 0ms
Processed entity 519/1000 in 0ms
Processed entity 520/1000 in 0ms
Processed entity 521/1000 in 0ms
Processed entity 522/1000 in 0ms
Processed entity 523/1000 in 0ms
Processed entity 524/1000 in 0ms
Processed entity 525/1000 in 0ms
Processed entity 526/1000 in 0ms
Processed entity 527/1000 in 0ms
Processed entity 528/1000 in 0ms
Processed entity 529/1000 in 0ms
Processed entity 530/1000 in 0ms
Processed entity 531/1000 in 0ms
Processed entity 532/1000 in 0ms
Processed entity 533/1000 in 0ms
Processed entity 534/1000 in 0ms
Processed entity 535/1000 in 0ms
Processed entity 536/1000 in 0ms
Processed entity 537/1000 in 0ms
Processed entity 538/1000 in 0ms
Processed entity 539/1000 in 0ms
Processed entity 540/1000 in 0ms
Processed entity 541/1000 in 0ms
Processed entity 542/1000 in 0ms
Processed entity 543/1000 in 0ms
Processed entity 544/1000 in 0ms
Processed entity 545/1000 in 0ms
Processed entity 546/1000 in 0ms
Processed entity 547/1000 in 0ms
Processed entity 548/1000 in 0ms
Processed entity 549/1000 in 0ms
Processed entity 550/1000 in 0ms
Processed entity 551/1000 in 0ms
Processed entity 552/1000 in 0ms
Processed entity 553/1000 in 0ms
Processed entity 554/1000 in 0ms
Processed entity 555/1000 in 1ms
Processed entity 556/1000 in 0ms
Processed entity 557/1000 in 0ms
Processed entity 558/1000 in 0ms
Processed entity 559/1000 in 0ms
Processed entity 560/1000 in 0ms
Processed entity 561/1000 in 0ms
Processed entity 562/1000 in 0ms
Processed entity 563/1000 in 0ms
Processed entity 564/1000 in 0ms
Processed entity 565/1000 in 0ms
Processed entity 566/1000 in 0ms
Processed entity 567/1000 in 0ms
Processed entity 568/1000 in 0ms
Processed entity 569/1000 in 0ms
Processed entity 570/1000 in 0ms
Processed entity 571/1000 in 0ms
Processed entity 572/1000 in 0ms
Processed entity 573/1000 in 0ms
Processed entity 574/1000 in 0ms
Processed entity 575/1000 in 0ms
Processed entity 576/1000 in 0ms
Processed entity 577/1000 in 0ms
Processed entity 578/1000 in 0ms
Processed entity 579/1000 in 0ms
Processed entity 580/1000 in 0ms
Processed entity 581/1000 in 0ms
Processed entity 582/1000 in 0ms
Processed entity 583/1000 in 0ms
Processed entity 584/1000 in 0ms
Processed entity 585/1000 in 0ms
Processed entity 586/1000 in 0ms
Processed entity 587/1000 in 0ms
Processed entity 588/1000 in 0ms
Processed entity 589/1000 in 0ms
Processed entity 590/1000 in 0ms
Processed entity 591/1000 in 0ms
Processed entity 592/1000 in 0ms
Processed entity 593/1000 in 0ms
Processed entity 594/1000 in 0ms
Processed entity 595/1000 in 0ms
Processed entity 596/1000 in 0ms
Processed entity 597/1000 in 0ms
Processed entity 598/1000 in 0ms
Processed entity 599/1000 in 0ms
Processed entity 600/1000 in 0ms
Processed entity 601/1000 in 0ms
Processed entity 602/1000 in 0ms
Processed entity 603/1000 in 0ms
Processed entity 604/1000 in 0ms
Processed entity 605/1000 in 0ms
Processed entity 606/1000 in 0ms
Processed entity 607/1000 in 0ms
Processed entity 608/1000 in 0ms
Processed entity 609/1000 in 0ms
Processed entity 610/1000 in 0ms
Processed entity 611/1000 in 0ms
Processed entity 612/1000 in 0ms
Processed entity 613/1000 in 0ms
Processed entity 614/1000 in 0ms
Processed entity 615/1000 in 0ms
Processed entity 616/1000 in 0ms
Processed entity 617/1000 in 0ms
Processed entity 618/1000 in 0ms
Processed entity 619/1000 in 0ms
Processed entity 620/1000 in 0ms
Processed entity 621/1000 in 0ms
Processed entity 622/1000 in 0ms
Processed entity 623/1000 in 0ms
Processed entity 624/1000 in 0ms
Processed entity 625/1000 in 0ms
Processed entity 626/1000 in 0ms
Processed entity 627/1000 in 0ms
Processed entity 628/1000 in 0ms
Processed entity 629/1000 in 0ms
Processed entity 630/1000 in 0ms
Processed entity 631/1000 in 0ms
Processed entity 632/1000 in 0ms
Processed entity 633/1000 in 0ms
Processed entity 634/1000 in 0ms
Processed entity 635/1000 in 0ms
Processed entity 636/1000 in 0ms
Processed entity 637/1000 in 0ms
Processed entity 638/1000 in 0ms
Processed entity 639/1000 in 0ms
Processed entity 640/1000 in 0ms
Processed entity 641/1000 in 0ms
Processed entity 642/1000 in 0ms
Processed entity 643/1000 in 0ms
Processed entity 644/1000 in 0ms
Processed entity 645/1000 in 0ms
Processed entity 646/1000 in 0ms
Processed entity 647/1000 in 0ms
Processed entity 648/1000 in 0ms
Processed entity 649/1000 in 0ms
Processed entity 650/1000 in 0ms
Processed entity 651/1000 in 0ms
Processed entity 652/1000 in 0ms
Processed entity 653/1000 in 0ms
Processed entity 654/1000 in 0ms
Processed entity 655/1000 in 0ms
Processed entity 656/1000 in 0ms
Processed entity 657/1000 in 0ms
Processed entity 658/1000 in 0ms
Processed entity 659/1000 in 0ms
Processed entity 660/1000 in 0ms
Processed entity 661/1000 in 0ms
Processed entity 662/1000 in 0ms
Processed entity 663/1000 in 0ms
Processed entity 664/1000 in 0ms
Processed entity 665/1000 in 0ms
Processed entity 666/1000 in 0ms
Processed entity 667/1000 in 0ms
Processed entity 668/1000 in 0ms
Processed entity 669/1000 in 0ms
Processed entity 670/1000 in 0ms
Processed entity 671/1000 in 0ms
Processed entity 672/1000 in 0ms
Processed entity 673/1000 in 0ms
Processed entity 674/1000 in 0ms
Processed entity 675/1000 in 1ms
Processed entity 676/1000 in 0ms
Processed entity 677/1000 in 0ms
Processed entity 678/1000 in 0ms
Processed entity 679/1000 in 0ms
Processed entity 680/1000 in 0ms
Processed entity 681/1000 in 0ms
Processed entity 682/1000 in 0ms
Processed entity 683/1000 in 0ms
Processed entity 684/1000 in 0ms
Processed entity 685/1000 in 0ms
Processed entity 686/1000 in 0ms
Processed entity 687/1000 in 0ms
Processed entity 688/1000 in 0ms
Processed entity 689/1000 in 0ms
Processed entity 690/1000 in 0ms
Processed entity 691/1000 in 0ms
Processed entity 692/1000 in 0ms
Processed entity 693/1000 in 0ms
Processed entity 694/1000 in 0ms
Processed entity 695/1000 in 0ms
Processed entity 696/1000 in 0ms
Processed entity 697/1000 in 0ms
Processed entity 698/1000 in 0ms
Processed entity 699/1000 in 0ms
Processed entity 700/1000 in 0ms
Processed entity 701/1000 in 0ms
Processed entity 702/1000 in 0ms
Processed entity 703/1000 in 0ms
Processed entity 704/1000 in 0ms
Processed entity 705/1000 in 0ms
Processed entity 706/1000 in 1ms
Processed entity 707/1000 in 0ms
Processed entity 708/1000 in 0ms
Processed entity 709/1000 in 0ms
Processed entity 710/1000 in 0ms
Processed entity 711/1000 in 0ms
Processed entity 712/1000 in 0ms
Processed entity 713/1000 in 0ms
Processed entity 714/1000 in 0ms
Processed entity 715/1000 in 0ms
Processed entity 716/1000 in 0ms
Processed entity 717/1000 in 0ms
Processed entity 718/1000 in 0ms
Processed entity 719/1000 in 0ms
Processed entity 720/1000 in 0ms
Processed entity 721/1000 in 0ms
Processed entity 722/1000 in 0ms
Processed entity 723/1000 in 0ms
Processed entity 724/1000 in 0ms
Processed entity 725/1000 in 0ms
Processed entity 726/1000 in 0ms
Processed entity 727/1000 in 0ms
Processed entity 728/1000 in 0ms
Processed entity 729/1000 in 0ms
Processed entity 730/1000 in 0ms
Processed entity 731/1000 in 0ms
Processed entity 732/1000 in 0ms
Processed entity 733/1000 in 0ms
Processed entity 734/1000 in 1ms
Processed entity 735/1000 in 0ms
Processed entity 736/1000 in 0ms
Processed entity 737/1000 in 0ms
Processed entity 738/1000 in 0ms
Processed entity 739/1000 in 0ms
Processed entity 740/1000 in 0ms
Processed entity 741/1000 in 0ms
Processed entity 742/1000 in 0ms
Processed entity 743/1000 in 0ms
Processed entity 744/1000 in 0ms
Processed entity 745/1000 in 0ms
Processed entity 746/1000 in 0ms
Processed entity 747/1000 in 0ms
Processed entity 748/1000 in 0ms
Processed entity 749/1000 in 0ms
Processed entity 750/1000 in 0ms
Processed entity 751/1000 in 0ms
Processed entity 752/1000 in 0ms
Processed entity 753/1000 in 0ms
Processed entity 754/1000 in 0ms
Processed entity 755/1000 in 0ms
Processed entity 756/1000 in 0ms
Processed entity 757/1000 in 0ms
Processed entity 758/1000 in 0ms
Processed entity 759/1000 in 0ms
Processed entity 760/1000 in 0ms
Processed entity 761/1000 in 0ms
Processed entity 762/1000 in 0ms
Processed entity 763/1000 in 0ms
Processed entity 764/1000 in 0ms
Processed entity 765/1000 in 0ms
Processed entity 766/1000 in 0ms
Processed entity 767/1000 in 0ms
Processed entity 768/1000 in 0ms
Processed entity 769/1000 in 1ms
Processed entity 770/1000 in 0ms
Processed entity 771/1000 in 0ms
Processed entity 772/1000 in 0ms
Processed entity 773/1000 in 0ms
Processed entity 774/1000 in 0ms
Processed entity 775/1000 in 0ms
Processed entity 776/1000 in 0ms
Processed entity 777/1000 in 0ms
Processed entity 778/1000 in 0ms
Processed entity 779/1000 in 0ms
Processed entity 780/1000 in 0ms
Processed entity 781/1000 in 0ms
Processed entity 782/1000 in 0ms
Processed entity 783/1000 in 0ms
Processed entity 784/1000 in 0ms
Processed entity 785/1000 in 0ms
Processed entity 786/1000 in 0ms
Processed entity 787/1000 in 0ms
Processed entity 788/1000 in 0ms
Processed entity 789/1000 in 0ms
Processed entity 790/1000 in 0ms
Processed entity 791/1000 in 0ms
Processed entity 792/1000 in 0ms
Processed entity 793/1000 in 0ms
Processed entity 794/1000 in 0ms
Processed entity 795/1000 in 0ms
Processed entity 796/1000 in 0ms
Processed entity 797/1000 in 0ms
Processed entity 798/1000 in 0ms
Processed entity 799/1000 in 0ms
Processed entity 800/1000 in 0ms
Processed entity 801/1000 in 0ms
Processed entity 802/1000 in 0ms
Processed entity 803/1000 in 0ms
Processed entity 804/1000 in 0ms
Processed entity 805/1000 in 0ms
Processed entity 806/1000 in 0ms
Processed entity 807/1000 in 1ms
Processed entity 808/1000 in 0ms
Processed entity 809/1000 in 0ms
Processed entity 810/1000 in 0ms
Processed entity 811/1000 in 0ms
Processed entity 812/1000 in 0ms
Processed entity 813/1000 in 0ms
Processed entity 814/1000 in 0ms
Processed entity 815/1000 in 0ms
Processed entity 816/1000 in 0ms
Processed entity 817/1000 in 0ms
Processed entity 818/1000 in 0ms
Processed entity 819/1000 in 0ms
Processed entity 820/1000 in 0ms
Processed entity 821/1000 in 0ms
Processed entity 822/1000 in 0ms
Processed entity 823/1000 in 0ms
Processed entity 824/1000 in 0ms
Processed entity 825/1000 in 0ms
Processed entity 826/1000 in 0ms
Processed entity 827/1000 in 0ms
Processed entity 828/1000 in 0ms
Processed entity 829/1000 in 0ms
Processed entity 830/1000 in 0ms
Processed entity 831/1000 in 0ms
Processed entity 832/1000 in 0ms
Processed entity 833/1000 in 0ms
Processed entity 834/1000 in 0ms
Processed entity 835/1000 in 0ms
Processed entity 836/1000 in 0ms
Processed entity 837/1000 in 0ms
Processed entity 838/1000 in 0ms
Processed entity 839/1000 in 0ms
Processed entity 840/1000 in 0ms
Processed entity 841/1000 in 0ms
Processed entity 842/1000 in 0ms
Processed entity 843/1000 in 0ms
Processed entity 844/1000 in 0ms
Processed entity 845/1000 in 0ms
Processed entity 846/1000 in 0ms
Processed entity 847/1000 in 0ms
Processed entity 848/1000 in 0ms
Processed entity 849/1000 in 1ms
Processed entity 850/1000 in 0ms
Processed entity 851/1000 in 0ms
Processed entity 852/1000 in 0ms
Processed entity 853/1000 in 0ms
Processed entity 854/1000 in 0ms
Processed entity 855/1000 in 0ms
Processed entity 856/1000 in 0ms
Processed entity 857/1000 in 0ms
Processed entity 858/1000 in 0ms
Processed entity 859/1000 in 0ms
Processed entity 860/1000 in 0ms
Processed entity 861/1000 in 0ms
Processed entity 862/1000 in 0ms
Processed entity 863/1000 in 0ms
Processed entity 864/1000 in 0ms
Processed entity 865/1000 in 0ms
Processed entity 866/1000 in 0ms
Processed entity 867/1000 in 0ms
Processed entity 868/1000 in 0ms
Processed entity 869/1000 in 0ms
Processed entity 870/1000 in 0ms
Processed entity 871/1000 in 0ms
Processed entity 872/1000 in 0ms
Processed entity 873/1000 in 0ms
Processed entity 874/1000 in 0ms
Processed entity 875/1000 in 0ms
Processed entity 876/1000 in 0ms
Processed entity 877/1000 in 0ms
Processed entity 878/1000 in 0ms
Processed entity 879/1000 in 0ms
Processed entity 880/1000 in 0ms
Processed entity 881/1000 in 0ms
Processed entity 882/1000 in 0ms
Processed entity 883/1000 in 0ms
Processed entity 884/1000 in 0ms
Processed entity 885/1000 in 0ms
Processed entity 886/1000 in 0ms
Processed entity 887/1000 in 0ms
Processed entity 888/1000 in 1ms
Processed entity 889/1000 in 0ms
Processed entity 890/1000 in 0ms
Processed entity 891/1000 in 0ms
Processed entity 892/1000 in 0ms
Processed entity 893/1000 in 0ms
Processed entity 894/1000 in 0ms
Processed entity 895/1000 in 0ms
Processed entity 896/1000 in 0ms
Processed entity 897/1000 in 0ms
Processed entity 898/1000 in 0ms
Processed entity 899/1000 in 0ms
Processed entity 900/1000 in 0ms
Processed entity 901/1000 in 0ms
Processed entity 902/1000 in 0ms
Processed entity 903/1000 in 0ms
Processed entity 904/1000 in 0ms
Processed entity 905/1000 in 0ms
Processed entity 906/1000 in 0ms
Processed entity 907/1000 in 0ms
Processed entity 908/1000 in 0ms
Processed entity 909/1000 in 0ms
Processed entity 910/1000 in 0ms
Processed entity 911/1000 in 0ms
Processed entity 912/1000 in 0ms
Processed entity 913/1000 in 0ms
Processed entity 914/1000 in 0ms
Processed entity 915/1000 in 0ms
Processed entity 916/1000 in 0ms
Processed entity 917/1000 in 0ms
Processed entity 918/1000 in 0ms
Processed entity 919/1000 in 0ms
Processed entity 920/1000 in 0ms
Processed entity 921/1000 in 0ms
Processed entity 922/1000 in 0ms
Processed entity 923/1000 in 0ms
Processed entity 924/1000 in 0ms
Processed entity 925/1000 in 0ms
Processed entity 926/1000 in 0ms
Processed entity 927/1000 in 0ms
Processed entity 928/1000 in 1ms
Processed entity 929/1000 in 0ms
Processed entity 930/1000 in 0ms
Processed entity 931/1000 in 0ms
Processed entity 932/1000 in 0ms
Processed entity 933/1000 in 0ms
Processed entity 934/1000 in 0ms
Processed entity 935/1000 in 0ms
Processed entity 936/1000 in 0ms
Processed entity 937/1000 in 0ms
Processed entity 938/1000 in 0ms
Processed entity 939/1000 in 0ms
Processed entity 940/1000 in 0ms
Processed entity 941/1000 in 0ms
Processed entity 942/1000 in 0ms
Processed entity 943/1000 in 0ms
Processed entity 944/1000 in 0ms
Processed entity 945/1000 in 0ms
Processed entity 946/1000 in 0ms
Processed entity 947/1000 in 0ms
Processed entity 948/1000 in 0ms
Processed entity 949/1000 in 0ms
Processed entity 950/1000 in 0ms
Processed entity 951/1000 in 0ms
Processed entity 952/1000 in 0ms
Processed entity 953/1000 in 0ms
Processed entity 954/1000 in 0ms
Processed entity 955/1000 in 0ms
Processed entity 956/1000 in 0ms
Processed entity 957/1000 in 0ms
Processed entity 958/1000 in 0ms
Processed entity 959/1000 in 0ms
Processed entity 960/1000 in 0ms
Processed entity 961/1000 in 0ms
Processed entity 962/1000 in 0ms
Processed entity 963/1000 in 0ms
Processed entity 964/1000 in 0ms
Processed entity 965/1000 in 0ms
Processed entity 966/1000 in 0ms
Processed entity 967/1000 in 0ms
Processed entity 968/1000 in 0ms
Processed entity 969/1000 in 0ms
Processed entity 970/1000 in 0ms
Processed entity 971/1000 in 0ms
Processed entity 972/1000 in 0ms
Processed entity 973/1000 in 0ms
Processed entity 974/1000 in 0ms
Processed entity 975/1000 in 0ms
Processed entity 976/1000 in 0ms
Processed entity 977/1000 in 0ms
Processed entity 978/1000 in 0ms
Processed entity 979/1000 in 0ms
Processed entity 980/1000 in 0ms
Processed entity 981/1000 in 0ms
Processed entity 982/1000 in 0ms
Processed entity 983/1000 in 0ms
Processed entity 984/1000 in 0ms
Processed entity 985/1000 in 0ms
Processed entity 986/1000 in 0ms
Processed entity 987/1000 in 0ms
Processed entity 988/1000 in 0ms
Processed entity 989/1000 in 0ms
Processed entity 990/1000 in 0ms
Processed entity 991/1000 in 0ms
Processed entity 992/1000 in 0ms
Processed entity 993/1000 in 0ms
Processed entity 994/1000 in 0ms
Processed entity 995/1000 in 0ms
Processed entity 996/1000 in 0ms
Processed entity 997/1000 in 0ms
Processed entity 998/1000 in 0ms
Processed entity 999/1000 in 0ms
done
Statistical profiling result from isolate-0x3780740-v8.log, (112 ticks, 5 unaccounted, 0 excluded).

 [Shared libraries]:
   ticks  total  nonlib   name
      2    1.8%          [vdso]
      1    0.9%          /usr/lib/libc-2.29.so

 [JavaScript]:
   ticks  total  nonlib   name
      4    3.6%    3.7%  LazyCompile: ~validateFunction /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:14:26
      2    1.8%    1.8%  Stub: StringAddStub
      1    0.9%    0.9%  Stub: CEntryStub
      1    0.9%    0.9%  LazyCompile: ~validChunk _stream_writable.js:245:20
      1    0.9%    0.9%  LazyCompile: ~nextTick internal/process/next_tick.js:246:20
      1    0.9%    0.9%  LazyCompile: ~_tickCallback internal/process/next_tick.js:152:25
      1    0.9%    0.9%  LazyCompile: ~Writable.write _stream_writable.js:264:36
      1    0.9%    0.9%  LazyCompile: ~TickObject internal/process/next_tick.js:235:16
      1    0.9%    0.9%  LazyCompile: ~<anonymous> native typedarray.js:937:48
      1    0.9%    0.9%  Builtin: TypedArrayPrototypeLength
      1    0.9%    0.9%  Builtin: InterpreterEntryTrampoline
      1    0.9%    0.9%  Builtin: FastNewObject
      1    0.9%    0.9%  Builtin: CallFunction_ReceiverIsAny

 [C++]:
   ticks  total  nonlib   name
     32   28.6%   29.4%  node::(anonymous namespace)::ContextifyScript::New(v8::FunctionCallbackInfo<v8::Value> const&)
      3    2.7%    2.8%  void node::StreamBase::JSMethod<node::LibuvStreamWrap, &(int node::StreamBase::WriteString<(node::encoding)1>(v8::FunctionCallbackInfo<v8::Value> const&))>(v8::FunctionCallbackInfo<v8::Value> const&)
      3    2.7%    2.8%  v8::internal::Scavenger::ScavengeObject(v8::internal::HeapObject**, v8::internal::HeapObject*)
      2    1.8%    1.8%  v8::internal::AstValueFactory::GetOneByteStringInternal(v8::internal::Vector<unsigned char const>)
      2    1.8%    1.8%  v8::internal::AstNumberingVisitor::VisitNoStackOverflowCheck(v8::internal::AstNode*)
      2    1.8%    1.8%  v8::internal::(anonymous namespace)::JSObjectWalkVisitor<v8::internal::AllocationSiteUsageContext>::StructureWalk(v8::internal::Handle<v8::internal::JSObject>)
      2    1.8%    1.8%  prefix_array
      2    1.8%    1.8%  node::Binding(v8::FunctionCallbackInfo<v8::Value> const&)
      1    0.9%    0.9%  v8::internal::interpreter::BytecodeGenerator::VisitPropertyLoad(v8::internal::interpreter::Register, v8::internal::Property*)
      1    0.9%    0.9%  v8::internal::interpreter::BytecodeGenerator::VisitInSameTestExecutionScope(v8::internal::Expression*)
      1    0.9%    0.9%  v8::internal::interpreter::BytecodeArrayWriter::MaybeElideLastBytecode(v8::internal::interpreter::Bytecode, bool) [clone .part.61]
      1    0.9%    0.9%  v8::internal::interpreter::BytecodeArrayBuilder::ToBytecodeArray(v8::internal::Isolate*)
      1    0.9%    0.9%  v8::internal::compiler::InstructionSelector::VisitPhi(v8::internal::compiler::Node*)
      1    0.9%    0.9%  v8::internal::TransitionsAccessor::Insert(v8::internal::Handle<v8::internal::Name>, v8::internal::Handle<v8::internal::Map>, v8::internal::SimpleTransitionFlag)
      1    0.9%    0.9%  v8::internal::StoreBuffer::MoveEntriesToRememberedSet(int)
      1    0.9%    0.9%  v8::internal::Scope::FetchFreeVariables(v8::internal::DeclarationScope*, v8::internal::ParseInfo*, v8::internal::VariableProxy*)
      1    0.9%    0.9%  v8::internal::Scanner::Scan()
      1    0.9%    0.9%  v8::internal::ParserBase<v8::internal::Parser>::ParseFormalParameter(v8::internal::ParserFormalParameters*, bool*)
      1    0.9%    0.9%  v8::internal::Parser::ParseFunctionLiteral(v8::internal::AstRawString const*, v8::internal::Scanner::Location, v8::internal::FunctionNameValidity, v8::internal::FunctionKind, int, v8::internal::FunctionLiteral::FunctionType, v8::internal::LanguageMode, bool*)
      1    0.9%    0.9%  v8::internal::Parser::ParseFunction(v8::internal::AstRawString const*, int, v8::internal::FunctionKind, v8::internal::FunctionLiteral::FunctionType, v8::internal::DeclarationScope*, int*, int*, bool*, int*, bool*)
      1    0.9%    0.9%  v8::internal::PageScavengingItem::Process(v8::internal::Scavenger*)
      1    0.9%    0.9%  v8::internal::Map::UpdateFieldType(int, v8::internal::Handle<v8::internal::Name>, v8::internal::PropertyConstness, v8::internal::Representation, v8::internal::Handle<v8::internal::Object>)
      1    0.9%    0.9%  v8::internal::LoadIC::Load(v8::internal::Handle<v8::internal::Object>, v8::internal::Handle<v8::internal::Name>)
      1    0.9%    0.9%  v8::internal::JSObject::MigrateToMap(v8::internal::Handle<v8::internal::JSObject>, v8::internal::Handle<v8::internal::Map>, int)
      1    0.9%    0.9%  v8::internal::JSObject::FastPropertyAt(v8::internal::Handle<v8::internal::JSObject>, v8::internal::Representation, v8::internal::FieldIndex)
      1    0.9%    0.9%  v8::internal::IC::UpdateState(v8::internal::Handle<v8::internal::Object>, v8::internal::Handle<v8::internal::Object>)
      1    0.9%    0.9%  v8::internal::HeapObjectIterator::Next()
      1    0.9%    0.9%  v8::internal::Heap::AllocateRaw(int, v8::internal::AllocationSpace, v8::internal::AllocationAlignment)
      1    0.9%    0.9%  v8::internal::Heap::AllocateFixedArrayWithFiller(int, v8::internal::PretenureFlag, v8::internal::Object*)
      1    0.9%    0.9%  v8::internal::HashTable<v8::internal::StringTable, v8::internal::StringTableShape>::Rehash()
      1    0.9%    0.9%  v8::internal::GetICCounts(v8::internal::JSFunction*, int*, int*, int*, int*, int*)
      1    0.9%    0.9%  v8::internal::FieldIndex::ForDescriptor(v8::internal::Map const*, int)
      1    0.9%    0.9%  v8::internal::Deserializer::PostProcessNewObject(v8::internal::HeapObject*, int)
      1    0.9%    0.9%  v8::internal::DescriptorArray::GetDetails(int)
      1    0.9%    0.9%  v8::internal::Builtin_HandleApiCall(int, v8::internal::Object**, v8::internal::Isolate*)
      1    0.9%    0.9%  v8::internal::AstValueFactory::Internalize(v8::internal::Isolate*)
      1    0.9%    0.9%  v8::internal::Assembler::jmp(v8::internal::Handle<v8::internal::Code>, v8::internal::RelocInfo::Mode)
      1    0.9%    0.9%  v8::internal::(anonymous namespace)::ElementsAccessorBase<v8::internal::(anonymous namespace)::TypedElementsAccessor<(v8::internal::ElementsKind)15, unsigned int>, v8::internal::(anonymous namespace)::ElementsKindTraits<(v8::internal::ElementsKind)15> >::GetEntryForIndex(v8::internal::Isolate*, v8::internal::JSObject*, v8::internal::FixedArrayBase*, unsigned int)
      1    0.9%    0.9%  start_thread
      1    0.9%    0.9%  node::inspector::(anonymous namespace)::InspectorConsoleCall(v8::FunctionCallbackInfo<v8::Value> const&)
      1    0.9%    0.9%  node::TTYWrap::New(v8::FunctionCallbackInfo<v8::Value> const&)
      1    0.9%    0.9%  node::LibuvStreamWrap::Initialize(v8::Local<v8::Object>, v8::Local<v8::Value>, v8::Local<v8::Context>)::{lambda(v8::FunctionCallbackInfo<v8::Value> const&)#1}::_FUN(v8::FunctionCallbackInfo<v8::Value> const&)
      1    0.9%    0.9%  internal_fnmatch
      1    0.9%    0.9%  int v8::internal::BinarySearch<(v8::internal::SearchMode)1, v8::internal::DescriptorArray>(v8::internal::DescriptorArray*, v8::internal::Name*, int, int*)
      1    0.9%    0.9%  arena_get2.part.5
      1    0.9%    0.9%  __vfwscanf_internal
      1    0.9%    0.9%  __GI___pthread_mutex_trylock

 [Summary]:
   ticks  total  nonlib   name
     17   15.2%   15.6%  JavaScript
     87   77.7%   79.8%  C++
      7    6.3%    6.4%  GC
      3    2.7%          Shared libraries
      5    4.5%          Unaccounted

 [C++ entry points]:
   ticks    cpp   total   name
     41   55.4%   36.6%  v8::internal::Builtin_HandleApiCall(int, v8::internal::Object**, v8::internal::Isolate*)
     15   20.3%   13.4%  v8::internal::Runtime_CompileLazy(int, v8::internal::Object**, v8::internal::Isolate*)
      5    6.8%    4.5%  v8::internal::Runtime_CopyDataProperties(int, v8::internal::Object**, v8::internal::Isolate*)
      3    4.1%    2.7%  v8::internal::Runtime_StoreIC_Miss(int, v8::internal::Object**, v8::internal::Isolate*)
      3    4.1%    2.7%  v8::internal::Runtime_KeyedStoreIC_Miss(int, v8::internal::Object**, v8::internal::Isolate*)
      2    2.7%    1.8%  v8::internal::Runtime_CreateObjectLiteral(int, v8::internal::Object**, v8::internal::Isolate*)
      2    2.7%    1.8%  v8::internal::Builtin_DateNow(int, v8::internal::Object**, v8::internal::Isolate*)
      1    1.4%    0.9%  v8::internal::Runtime_SetProperty(int, v8::internal::Object**, v8::internal::Isolate*)
      1    1.4%    0.9%  v8::internal::Runtime_LoadIC_Miss(int, v8::internal::Object**, v8::internal::Isolate*)
      1    1.4%    0.9%  v8::internal::Runtime_Interrupt(int, v8::internal::Object**, v8::internal::Isolate*)

 [Bottom up (heavy) profile]:
  Note: percentage shows a share of a particular caller in the total
  amount of its parent calls.
  Callers occupying less than 1.0% are not shown.

   ticks parent  name
     32   28.6%  node::(anonymous namespace)::ContextifyScript::New(v8::FunctionCallbackInfo<v8::Value> const&)
     32  100.0%    v8::internal::Builtin_HandleApiCall(int, v8::internal::Object**, v8::internal::Isolate*)
     28   87.5%      LazyCompile: ~runInThisContext bootstrap_node.js:512:28
     28  100.0%        LazyCompile: ~NativeModule.compile bootstrap_node.js:601:44
     28  100.0%          LazyCompile: ~NativeModule.require bootstrap_node.js:533:34
      5   17.9%            Script: ~<anonymous> module.js:1:11
      3   10.7%            Script: ~<anonymous> util.js:1:11
      3   10.7%            Script: ~<anonymous> tty.js:1:11
      3   10.7%            Script: ~<anonymous> stream.js:1:11
      2    7.1%            Script: ~<anonymous> timers.js:1:11
      2    7.1%            LazyCompile: ~setupGlobalConsole bootstrap_node.js:323:30
      1    3.6%            Script: ~<anonymous> readline.js:1:11
      1    3.6%            Script: ~<anonymous> net.js:1:11
      1    3.6%            Script: ~<anonymous> internal/url.js:1:11
      1    3.6%            Script: ~<anonymous> internal/loader/ModuleRequest.js:1:11
      1    3.6%            Script: ~<anonymous> internal/loader/ModuleJob.js:1:11
      1    3.6%            Script: ~<anonymous> fs.js:1:11
      1    3.6%            LazyCompile: ~startup bootstrap_node.js:24:19
      1    3.6%            LazyCompile: ~setup_performance internal/process.js:15:27
      1    3.6%            LazyCompile: ~setupGlobalVariables bootstrap_node.js:268:32
      1    3.6%            LazyCompile: ~setupGlobalTimeouts bootstrap_node.js:313:31
      4   12.5%      LazyCompile: ~createScript vm.js:79:22
      4  100.0%        LazyCompile: ~runInThisContext vm.js:138:26
      4  100.0%          LazyCompile: ~Module._compile module.js:610:37
      4  100.0%            LazyCompile: ~Module._extensions..js module.js:662:37

      5    4.5%  UNKNOWN

      4    3.6%  LazyCompile: ~validateFunction /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:14:26
      4  100.0%    LazyCompile: ~entities.forEach /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:2785:18
      4  100.0%      Builtin: ArrayForEach
      4  100.0%        Script: ~<anonymous> /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:1:11
      4  100.0%          LazyCompile: ~Module._compile module.js:610:37
      4  100.0%            LazyCompile: ~Module._extensions..js module.js:662:37

      3    2.7%  void node::StreamBase::JSMethod<node::LibuvStreamWrap, &(int node::StreamBase::WriteString<(node::encoding)1>(v8::FunctionCallbackInfo<v8::Value> const&))>(v8::FunctionCallbackInfo<v8::Value> const&)
      3  100.0%    v8::internal::Builtin_HandleApiCall(int, v8::internal::Object**, v8::internal::Isolate*)
      3  100.0%      LazyCompile: ~createWriteReq net.js:790:24
      3  100.0%        LazyCompile: ~Socket._writeGeneric net.js:715:42
      3  100.0%          LazyCompile: ~Socket._write net.js:786:35
      3  100.0%            LazyCompile: ~doWrite _stream_writable.js:388:17

      3    2.7%  v8::internal::Scavenger::ScavengeObject(v8::internal::HeapObject**, v8::internal::HeapObject*)

      2    1.8%  v8::internal::AstValueFactory::GetOneByteStringInternal(v8::internal::Vector<unsigned char const>)
      2  100.0%    v8::internal::Runtime_CompileLazy(int, v8::internal::Object**, v8::internal::Isolate*)
      1   50.0%      Script: ~<anonymous> internal/loader/ModuleRequest.js:1:11
      1  100.0%        LazyCompile: ~NativeModule.compile bootstrap_node.js:601:44
      1  100.0%          LazyCompile: ~NativeModule.require bootstrap_node.js:533:34
      1  100.0%            Script: ~<anonymous> internal/loader/Loader.js:1:11
      1   50.0%      LazyCompile: ~entities.forEach /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:2785:18
      1  100.0%        Builtin: ArrayForEach
      1  100.0%          Script: ~<anonymous> /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:1:11
      1  100.0%            LazyCompile: ~Module._compile module.js:610:37

      2    1.8%  v8::internal::AstNumberingVisitor::VisitNoStackOverflowCheck(v8::internal::AstNode*)
      2  100.0%    v8::internal::Runtime_CompileLazy(int, v8::internal::Object**, v8::internal::Isolate*)
      1   50.0%      LazyCompile: ~realpathSync fs.js:1579:40
      1  100.0%        LazyCompile: ~toRealPath module.js:163:20
      1  100.0%          LazyCompile: ~Module._findPath module.js:182:28
      1  100.0%            LazyCompile: ~Module._resolveFilename module.js:515:35
      1   50.0%      LazyCompile: ~_tickCallback internal/process/next_tick.js:152:25
      1  100.0%        LazyCompile: ~Module.runMain module.js:692:26
      1  100.0%          LazyCompile: ~startup bootstrap_node.js:24:19
      1  100.0%            Script: ~<anonymous> bootstrap_node.js:10:10

      2    1.8%  v8::internal::(anonymous namespace)::JSObjectWalkVisitor<v8::internal::AllocationSiteUsageContext>::StructureWalk(v8::internal::Handle<v8::internal::JSObject>)
      2  100.0%    v8::internal::Runtime_CreateObjectLiteral(int, v8::internal::Object**, v8::internal::Isolate*)
      2  100.0%      LazyCompile: ~validateFunction /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:14:26
      2  100.0%        LazyCompile: ~entities.forEach /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:2785:18
      2  100.0%          Builtin: ArrayForEach
      2  100.0%            Script: ~<anonymous> /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:1:11

      2    1.8%  prefix_array
      1   50.0%    v8::internal::Runtime_CompileLazy(int, v8::internal::Object**, v8::internal::Isolate*)
      1  100.0%      Script: ~<anonymous> buffer.js:1:11
      1  100.0%        LazyCompile: ~NativeModule.compile bootstrap_node.js:601:44
      1  100.0%          LazyCompile: ~NativeModule.require bootstrap_node.js:533:34
      1  100.0%            Script: ~<anonymous> util.js:1:11
      1   50.0%    v8::internal::Builtin_DateNow(int, v8::internal::Object**, v8::internal::Isolate*)
      1  100.0%      LazyCompile: ~entities.forEach /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:2785:18
      1  100.0%        Builtin: ArrayForEach
      1  100.0%          Script: ~<anonymous> /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:1:11
      1  100.0%            LazyCompile: ~Module._compile module.js:610:37

      2    1.8%  node::Binding(v8::FunctionCallbackInfo<v8::Value> const&)
      2  100.0%    v8::internal::Builtin_HandleApiCall(int, v8::internal::Object**, v8::internal::Isolate*)
      1   50.0%      Script: ~<anonymous> internal/util.js:1:11
      1  100.0%        LazyCompile: ~NativeModule.compile bootstrap_node.js:601:44
      1  100.0%          LazyCompile: ~NativeModule.require bootstrap_node.js:533:34
      1  100.0%            Script: ~<anonymous> internal/encoding.js:1:11
      1   50.0%      Script: ~<anonymous> bootstrap_node.js:10:10

      2    1.8%  [vdso]
      1   50.0%    v8::internal::Builtin_DateNow(int, v8::internal::Object**, v8::internal::Isolate*)
      1  100.0%      LazyCompile: ~entities.forEach /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:2785:18
      1  100.0%        Builtin: ArrayForEach
      1  100.0%          Script: ~<anonymous> /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:1:11
      1  100.0%            LazyCompile: ~Module._compile module.js:610:37

      2    1.8%  Stub: StringAddStub
      1   50.0%    LazyCompile: ~write console.js:93:15
      1  100.0%      LazyCompile: ~log console.js:127:37
      1  100.0%        node::inspector::(anonymous namespace)::InspectorConsoleCall(v8::FunctionCallbackInfo<v8::Value> const&)
      1  100.0%          LazyCompile: ~entities.forEach /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:2785:18
      1  100.0%            Builtin: ArrayForEach
      1   50.0%    LazyCompile: ~makeGetter bootstrap_node.js:278:24
      1  100.0%      LazyCompile: ~setupGlobalVariables bootstrap_node.js:268:32
      1  100.0%        LazyCompile: ~startup bootstrap_node.js:24:19
      1  100.0%          Script: ~<anonymous> bootstrap_node.js:10:10

NodeJS version 10.0.0:

Processing entities...
Processed entity 0/1000 in 12ms
Processed entity 1/1000 in 0ms
Processed entity 2/1000 in 1ms
Processed entity 3/1000 in 0ms
Processed entity 4/1000 in 0ms
Processed entity 5/1000 in 0ms
Processed entity 6/1000 in 0ms
Processed entity 7/1000 in 0ms
Processed entity 8/1000 in 0ms
Processed entity 9/1000 in 0ms
Processed entity 10/1000 in 0ms
Processed entity 11/1000 in 0ms
Processed entity 12/1000 in 0ms
Processed entity 13/1000 in 0ms
Processed entity 14/1000 in 0ms
Processed entity 15/1000 in 0ms
Processed entity 16/1000 in 0ms
Processed entity 17/1000 in 0ms
Processed entity 18/1000 in 0ms
Processed entity 19/1000 in 0ms
Processed entity 20/1000 in 0ms
Processed entity 21/1000 in 0ms
Processed entity 22/1000 in 0ms
Processed entity 23/1000 in 0ms
Processed entity 24/1000 in 0ms
Processed entity 25/1000 in 0ms
Processed entity 26/1000 in 0ms
Processed entity 27/1000 in 0ms
Processed entity 28/1000 in 0ms
Processed entity 29/1000 in 0ms
Processed entity 30/1000 in 0ms
Processed entity 31/1000 in 0ms
Processed entity 32/1000 in 0ms
Processed entity 33/1000 in 0ms
Processed entity 34/1000 in 0ms
Processed entity 35/1000 in 0ms
Processed entity 36/1000 in 0ms
Processed entity 37/1000 in 0ms
Processed entity 38/1000 in 0ms
Processed entity 39/1000 in 0ms
Processed entity 40/1000 in 0ms
Processed entity 41/1000 in 0ms
Processed entity 42/1000 in 0ms
Processed entity 43/1000 in 0ms
Processed entity 44/1000 in 0ms
Processed entity 45/1000 in 0ms
Processed entity 46/1000 in 0ms
Processed entity 47/1000 in 0ms
Processed entity 48/1000 in 0ms
Processed entity 49/1000 in 1ms
Processed entity 50/1000 in 0ms
Processed entity 51/1000 in 0ms
Processed entity 52/1000 in 0ms
Processed entity 53/1000 in 0ms
Processed entity 54/1000 in 0ms
Processed entity 55/1000 in 0ms
Processed entity 56/1000 in 0ms
Processed entity 57/1000 in 0ms
Processed entity 58/1000 in 0ms
Processed entity 59/1000 in 0ms
Processed entity 60/1000 in 0ms
Processed entity 61/1000 in 0ms
Processed entity 62/1000 in 0ms
Processed entity 63/1000 in 0ms
Processed entity 64/1000 in 0ms
Processed entity 65/1000 in 0ms
Processed entity 66/1000 in 0ms
Processed entity 67/1000 in 0ms
Processed entity 68/1000 in 0ms
Processed entity 69/1000 in 0ms
Processed entity 70/1000 in 0ms
Processed entity 71/1000 in 0ms
Processed entity 72/1000 in 0ms
Processed entity 73/1000 in 0ms
Processed entity 74/1000 in 0ms
Processed entity 75/1000 in 0ms
Processed entity 76/1000 in 0ms
Processed entity 77/1000 in 0ms
Processed entity 78/1000 in 0ms
Processed entity 79/1000 in 0ms
Processed entity 80/1000 in 0ms
Processed entity 81/1000 in 0ms
Processed entity 82/1000 in 0ms
Processed entity 83/1000 in 0ms
Processed entity 84/1000 in 0ms
Processed entity 85/1000 in 0ms
Processed entity 86/1000 in 0ms
Processed entity 87/1000 in 0ms
Processed entity 88/1000 in 0ms
Processed entity 89/1000 in 1ms
Processed entity 90/1000 in 0ms
Processed entity 91/1000 in 0ms
Processed entity 92/1000 in 0ms
Processed entity 93/1000 in 0ms
Processed entity 94/1000 in 0ms
Processed entity 95/1000 in 0ms
Processed entity 96/1000 in 0ms
Processed entity 97/1000 in 0ms
Processed entity 98/1000 in 0ms
Processed entity 99/1000 in 0ms
Processed entity 100/1000 in 0ms
Processed entity 101/1000 in 0ms
Processed entity 102/1000 in 0ms
Processed entity 103/1000 in 0ms
Processed entity 104/1000 in 0ms
Processed entity 105/1000 in 0ms
Processed entity 106/1000 in 0ms
Processed entity 107/1000 in 0ms
Processed entity 108/1000 in 0ms
Processed entity 109/1000 in 0ms
Processed entity 110/1000 in 0ms
Processed entity 111/1000 in 0ms
Processed entity 112/1000 in 0ms
Processed entity 113/1000 in 0ms
Processed entity 114/1000 in 0ms
Processed entity 115/1000 in 0ms
Processed entity 116/1000 in 0ms
Processed entity 117/1000 in 0ms
Processed entity 118/1000 in 0ms
Processed entity 119/1000 in 0ms
Processed entity 120/1000 in 0ms
Processed entity 121/1000 in 0ms
Processed entity 122/1000 in 0ms
Processed entity 123/1000 in 0ms
Processed entity 124/1000 in 0ms
Processed entity 125/1000 in 0ms
Processed entity 126/1000 in 0ms
Processed entity 127/1000 in 0ms
Processed entity 128/1000 in 0ms
Processed entity 129/1000 in 0ms
Processed entity 130/1000 in 0ms
Processed entity 131/1000 in 0ms
Processed entity 132/1000 in 0ms
Processed entity 133/1000 in 0ms
Processed entity 134/1000 in 0ms
Processed entity 135/1000 in 0ms
Processed entity 136/1000 in 0ms
Processed entity 137/1000 in 0ms
Processed entity 138/1000 in 0ms
Processed entity 139/1000 in 0ms
Processed entity 140/1000 in 0ms
Processed entity 141/1000 in 0ms
Processed entity 142/1000 in 0ms
Processed entity 143/1000 in 0ms
Processed entity 144/1000 in 0ms
Processed entity 145/1000 in 0ms
Processed entity 146/1000 in 0ms
Processed entity 147/1000 in 0ms
Processed entity 148/1000 in 0ms
Processed entity 149/1000 in 0ms
Processed entity 150/1000 in 0ms
Processed entity 151/1000 in 0ms
Processed entity 152/1000 in 0ms
Processed entity 153/1000 in 0ms
Processed entity 154/1000 in 0ms
Processed entity 155/1000 in 0ms
Processed entity 156/1000 in 0ms
Processed entity 157/1000 in 0ms
Processed entity 158/1000 in 0ms
Processed entity 159/1000 in 0ms
Processed entity 160/1000 in 0ms
Processed entity 161/1000 in 0ms
Processed entity 162/1000 in 0ms
Processed entity 163/1000 in 0ms
Processed entity 164/1000 in 0ms
Processed entity 165/1000 in 0ms
Processed entity 166/1000 in 0ms
Processed entity 167/1000 in 0ms
Processed entity 168/1000 in 0ms
Processed entity 169/1000 in 0ms
Processed entity 170/1000 in 0ms
Processed entity 171/1000 in 0ms
Processed entity 172/1000 in 0ms
Processed entity 173/1000 in 0ms
Processed entity 174/1000 in 0ms
Processed entity 175/1000 in 0ms
Processed entity 176/1000 in 0ms
Processed entity 177/1000 in 0ms
Processed entity 178/1000 in 0ms
Processed entity 179/1000 in 0ms
Processed entity 180/1000 in 0ms
Processed entity 181/1000 in 0ms
Processed entity 182/1000 in 0ms
Processed entity 183/1000 in 0ms
Processed entity 184/1000 in 0ms
Processed entity 185/1000 in 0ms
Processed entity 186/1000 in 0ms
Processed entity 187/1000 in 0ms
Processed entity 188/1000 in 0ms
Processed entity 189/1000 in 0ms
Processed entity 190/1000 in 0ms
Processed entity 191/1000 in 0ms
Processed entity 192/1000 in 0ms
Processed entity 193/1000 in 0ms
Processed entity 194/1000 in 0ms
Processed entity 195/1000 in 0ms
Processed entity 196/1000 in 0ms
Processed entity 197/1000 in 0ms
Processed entity 198/1000 in 0ms
Processed entity 199/1000 in 0ms
Processed entity 200/1000 in 0ms
Processed entity 201/1000 in 0ms
Processed entity 202/1000 in 0ms
Processed entity 203/1000 in 0ms
Processed entity 204/1000 in 0ms
Processed entity 205/1000 in 0ms
Processed entity 206/1000 in 0ms
Processed entity 207/1000 in 0ms
Processed entity 208/1000 in 0ms
Processed entity 209/1000 in 0ms
Processed entity 210/1000 in 0ms
Processed entity 211/1000 in 0ms
Processed entity 212/1000 in 0ms
Processed entity 213/1000 in 0ms
Processed entity 214/1000 in 0ms
Processed entity 215/1000 in 0ms
Processed entity 216/1000 in 0ms
Processed entity 217/1000 in 0ms
Processed entity 218/1000 in 0ms
Processed entity 219/1000 in 0ms
Processed entity 220/1000 in 0ms
Processed entity 221/1000 in 0ms
Processed entity 222/1000 in 0ms
Processed entity 223/1000 in 0ms
Processed entity 224/1000 in 0ms
Processed entity 225/1000 in 0ms
Processed entity 226/1000 in 0ms
Processed entity 227/1000 in 0ms
Processed entity 228/1000 in 0ms
Processed entity 229/1000 in 0ms
Processed entity 230/1000 in 0ms
Processed entity 231/1000 in 0ms
Processed entity 232/1000 in 0ms
Processed entity 233/1000 in 0ms
Processed entity 234/1000 in 0ms
Processed entity 235/1000 in 0ms
Processed entity 236/1000 in 0ms
Processed entity 237/1000 in 0ms
Processed entity 238/1000 in 0ms
Processed entity 239/1000 in 0ms
Processed entity 240/1000 in 0ms
Processed entity 241/1000 in 0ms
Processed entity 242/1000 in 0ms
Processed entity 243/1000 in 0ms
Processed entity 244/1000 in 0ms
Processed entity 245/1000 in 0ms
Processed entity 246/1000 in 0ms
Processed entity 247/1000 in 0ms
Processed entity 248/1000 in 0ms
Processed entity 249/1000 in 0ms
Processed entity 250/1000 in 0ms
Processed entity 251/1000 in 0ms
Processed entity 252/1000 in 0ms
Processed entity 253/1000 in 0ms
Processed entity 254/1000 in 0ms
Processed entity 255/1000 in 0ms
Processed entity 256/1000 in 0ms
Processed entity 257/1000 in 0ms
Processed entity 258/1000 in 0ms
Processed entity 259/1000 in 0ms
Processed entity 260/1000 in 0ms
Processed entity 261/1000 in 0ms
Processed entity 262/1000 in 0ms
Processed entity 263/1000 in 0ms
Processed entity 264/1000 in 0ms
Processed entity 265/1000 in 0ms
Processed entity 266/1000 in 0ms
Processed entity 267/1000 in 0ms
Processed entity 268/1000 in 0ms
Processed entity 269/1000 in 0ms
Processed entity 270/1000 in 0ms
Processed entity 271/1000 in 0ms
Processed entity 272/1000 in 0ms
Processed entity 273/1000 in 0ms
Processed entity 274/1000 in 0ms
Processed entity 275/1000 in 0ms
Processed entity 276/1000 in 0ms
Processed entity 277/1000 in 0ms
Processed entity 278/1000 in 0ms
Processed entity 279/1000 in 0ms
Processed entity 280/1000 in 0ms
Processed entity 281/1000 in 0ms
Processed entity 282/1000 in 0ms
Processed entity 283/1000 in 0ms
Processed entity 284/1000 in 0ms
Processed entity 285/1000 in 0ms
Processed entity 286/1000 in 0ms
Processed entity 287/1000 in 0ms
Processed entity 288/1000 in 0ms
Processed entity 289/1000 in 0ms
Processed entity 290/1000 in 0ms
Processed entity 291/1000 in 0ms
Processed entity 292/1000 in 0ms
Processed entity 293/1000 in 0ms
Processed entity 294/1000 in 0ms
Processed entity 295/1000 in 1ms
Processed entity 296/1000 in 0ms
Processed entity 297/1000 in 0ms
Processed entity 298/1000 in 0ms
Processed entity 299/1000 in 0ms
Processed entity 300/1000 in 0ms
Processed entity 301/1000 in 0ms
Processed entity 302/1000 in 0ms
Processed entity 303/1000 in 0ms
Processed entity 304/1000 in 0ms
Processed entity 305/1000 in 0ms
Processed entity 306/1000 in 0ms
Processed entity 307/1000 in 0ms
Processed entity 308/1000 in 0ms
Processed entity 309/1000 in 0ms
Processed entity 310/1000 in 0ms
Processed entity 311/1000 in 0ms
Processed entity 312/1000 in 0ms
Processed entity 313/1000 in 0ms
Processed entity 314/1000 in 0ms
Processed entity 315/1000 in 0ms
Processed entity 316/1000 in 0ms
Processed entity 317/1000 in 0ms
Processed entity 318/1000 in 0ms
Processed entity 319/1000 in 0ms
Processed entity 320/1000 in 0ms
Processed entity 321/1000 in 0ms
Processed entity 322/1000 in 0ms
Processed entity 323/1000 in 0ms
Processed entity 324/1000 in 0ms
Processed entity 325/1000 in 0ms
Processed entity 326/1000 in 0ms
Processed entity 327/1000 in 0ms
Processed entity 328/1000 in 0ms
Processed entity 329/1000 in 0ms
Processed entity 330/1000 in 0ms
Processed entity 331/1000 in 0ms
Processed entity 332/1000 in 0ms
Processed entity 333/1000 in 0ms
Processed entity 334/1000 in 0ms
Processed entity 335/1000 in 0ms
Processed entity 336/1000 in 0ms
Processed entity 337/1000 in 0ms
Processed entity 338/1000 in 0ms
Processed entity 339/1000 in 0ms
Processed entity 340/1000 in 0ms
Processed entity 341/1000 in 0ms
Processed entity 342/1000 in 0ms
Processed entity 343/1000 in 0ms
Processed entity 344/1000 in 0ms
Processed entity 345/1000 in 0ms
Processed entity 346/1000 in 1ms
Processed entity 347/1000 in 1ms
Processed entity 348/1000 in 0ms
Processed entity 349/1000 in 1ms
Processed entity 350/1000 in 0ms
Processed entity 351/1000 in 0ms
Processed entity 352/1000 in 0ms
Processed entity 353/1000 in 0ms
Processed entity 354/1000 in 0ms
Processed entity 355/1000 in 0ms
Processed entity 356/1000 in 0ms
Processed entity 357/1000 in 0ms
Processed entity 358/1000 in 0ms
Processed entity 359/1000 in 0ms
Processed entity 360/1000 in 1ms
Processed entity 361/1000 in 0ms
Processed entity 362/1000 in 0ms
Processed entity 363/1000 in 0ms
Processed entity 364/1000 in 0ms
Processed entity 365/1000 in 0ms
Processed entity 366/1000 in 0ms
Processed entity 367/1000 in 0ms
Processed entity 368/1000 in 0ms
Processed entity 369/1000 in 0ms
Processed entity 370/1000 in 0ms
Processed entity 371/1000 in 0ms
Processed entity 372/1000 in 0ms
Processed entity 373/1000 in 0ms
Processed entity 374/1000 in 0ms
Processed entity 375/1000 in 0ms
Processed entity 376/1000 in 0ms
Processed entity 377/1000 in 0ms
Processed entity 378/1000 in 0ms
Processed entity 379/1000 in 0ms
Processed entity 380/1000 in 0ms
Processed entity 381/1000 in 0ms
Processed entity 382/1000 in 0ms
Processed entity 383/1000 in 0ms
Processed entity 384/1000 in 0ms
Processed entity 385/1000 in 0ms
Processed entity 386/1000 in 0ms
Processed entity 387/1000 in 0ms
Processed entity 388/1000 in 0ms
Processed entity 389/1000 in 0ms
Processed entity 390/1000 in 0ms
Processed entity 391/1000 in 0ms
Processed entity 392/1000 in 0ms
Processed entity 393/1000 in 0ms
Processed entity 394/1000 in 0ms
Processed entity 395/1000 in 0ms
Processed entity 396/1000 in 0ms
Processed entity 397/1000 in 0ms
Processed entity 398/1000 in 0ms
Processed entity 399/1000 in 0ms
Processed entity 400/1000 in 0ms
Processed entity 401/1000 in 0ms
Processed entity 402/1000 in 0ms
Processed entity 403/1000 in 0ms
Processed entity 404/1000 in 0ms
Processed entity 405/1000 in 0ms
Processed entity 406/1000 in 0ms
Processed entity 407/1000 in 0ms
Processed entity 408/1000 in 0ms
Processed entity 409/1000 in 0ms
Processed entity 410/1000 in 0ms
Processed entity 411/1000 in 0ms
Processed entity 412/1000 in 0ms
Processed entity 413/1000 in 0ms
Processed entity 414/1000 in 0ms
Processed entity 415/1000 in 0ms
Processed entity 416/1000 in 0ms
Processed entity 417/1000 in 0ms
Processed entity 418/1000 in 0ms
Processed entity 419/1000 in 0ms
Processed entity 420/1000 in 0ms
Processed entity 421/1000 in 0ms
Processed entity 422/1000 in 0ms
Processed entity 423/1000 in 0ms
Processed entity 424/1000 in 0ms
Processed entity 425/1000 in 0ms
Processed entity 426/1000 in 0ms
Processed entity 427/1000 in 0ms
Processed entity 428/1000 in 0ms
Processed entity 429/1000 in 0ms
Processed entity 430/1000 in 0ms
Processed entity 431/1000 in 0ms
Processed entity 432/1000 in 0ms
Processed entity 433/1000 in 0ms
Processed entity 434/1000 in 0ms
Processed entity 435/1000 in 0ms
Processed entity 436/1000 in 0ms
Processed entity 437/1000 in 0ms
Processed entity 438/1000 in 0ms
Processed entity 439/1000 in 0ms
Processed entity 440/1000 in 0ms
Processed entity 441/1000 in 0ms
Processed entity 442/1000 in 0ms
Processed entity 443/1000 in 0ms
Processed entity 444/1000 in 0ms
Processed entity 445/1000 in 0ms
Processed entity 446/1000 in 0ms
Processed entity 447/1000 in 0ms
Processed entity 448/1000 in 0ms
Processed entity 449/1000 in 0ms
Processed entity 450/1000 in 0ms
Processed entity 451/1000 in 0ms
Processed entity 452/1000 in 0ms
Processed entity 453/1000 in 0ms
Processed entity 454/1000 in 0ms
Processed entity 455/1000 in 0ms
Processed entity 456/1000 in 0ms
Processed entity 457/1000 in 0ms
Processed entity 458/1000 in 0ms
Processed entity 459/1000 in 0ms
Processed entity 460/1000 in 0ms
Processed entity 461/1000 in 0ms
Processed entity 462/1000 in 0ms
Processed entity 463/1000 in 0ms
Processed entity 464/1000 in 0ms
Processed entity 465/1000 in 0ms
Processed entity 466/1000 in 0ms
Processed entity 467/1000 in 0ms
Processed entity 468/1000 in 0ms
Processed entity 469/1000 in 0ms
Processed entity 470/1000 in 0ms
Processed entity 471/1000 in 0ms
Processed entity 472/1000 in 0ms
Processed entity 473/1000 in 0ms
Processed entity 474/1000 in 0ms
Processed entity 475/1000 in 0ms
Processed entity 476/1000 in 0ms
Processed entity 477/1000 in 0ms
Processed entity 478/1000 in 0ms
Processed entity 479/1000 in 0ms
Processed entity 480/1000 in 0ms
Processed entity 481/1000 in 0ms
Processed entity 482/1000 in 0ms
Processed entity 483/1000 in 0ms
Processed entity 484/1000 in 0ms
Processed entity 485/1000 in 0ms
Processed entity 486/1000 in 0ms
Processed entity 487/1000 in 0ms
Processed entity 488/1000 in 0ms
Processed entity 489/1000 in 0ms
Processed entity 490/1000 in 0ms
Processed entity 491/1000 in 0ms
Processed entity 492/1000 in 0ms
Processed entity 493/1000 in 0ms
Processed entity 494/1000 in 0ms
Processed entity 495/1000 in 0ms
Processed entity 496/1000 in 0ms
Processed entity 497/1000 in 0ms
Processed entity 498/1000 in 0ms
Processed entity 499/1000 in 0ms
Processed entity 500/1000 in 0ms
Processed entity 501/1000 in 0ms
Processed entity 502/1000 in 0ms
Processed entity 503/1000 in 0ms
Processed entity 504/1000 in 1ms
Processed entity 505/1000 in 0ms
Processed entity 506/1000 in 0ms
Processed entity 507/1000 in 0ms
Processed entity 508/1000 in 0ms
Processed entity 509/1000 in 0ms
Processed entity 510/1000 in 0ms
Processed entity 511/1000 in 0ms
Processed entity 512/1000 in 0ms
Processed entity 513/1000 in 0ms
Processed entity 514/1000 in 0ms
Processed entity 515/1000 in 0ms
Processed entity 516/1000 in 0ms
Processed entity 517/1000 in 0ms
Processed entity 518/1000 in 0ms
Processed entity 519/1000 in 0ms
Processed entity 520/1000 in 0ms
Processed entity 521/1000 in 0ms
Processed entity 522/1000 in 0ms
Processed entity 523/1000 in 0ms
Processed entity 524/1000 in 0ms
Processed entity 525/1000 in 0ms
Processed entity 526/1000 in 0ms
Processed entity 527/1000 in 0ms
Processed entity 528/1000 in 0ms
Processed entity 529/1000 in 0ms
Processed entity 530/1000 in 0ms
Processed entity 531/1000 in 0ms
Processed entity 532/1000 in 0ms
Processed entity 533/1000 in 0ms
Processed entity 534/1000 in 0ms
Processed entity 535/1000 in 0ms
Processed entity 536/1000 in 0ms
Processed entity 537/1000 in 0ms
Processed entity 538/1000 in 0ms
Processed entity 539/1000 in 0ms
Processed entity 540/1000 in 0ms
Processed entity 541/1000 in 0ms
Processed entity 542/1000 in 0ms
Processed entity 543/1000 in 0ms
Processed entity 544/1000 in 0ms
Processed entity 545/1000 in 0ms
Processed entity 546/1000 in 0ms
Processed entity 547/1000 in 0ms
Processed entity 548/1000 in 0ms
Processed entity 549/1000 in 0ms
Processed entity 550/1000 in 0ms
Processed entity 551/1000 in 0ms
Processed entity 552/1000 in 0ms
Processed entity 553/1000 in 0ms
Processed entity 554/1000 in 0ms
Processed entity 555/1000 in 0ms
Processed entity 556/1000 in 0ms
Processed entity 557/1000 in 0ms
Processed entity 558/1000 in 0ms
Processed entity 559/1000 in 0ms
Processed entity 560/1000 in 0ms
Processed entity 561/1000 in 0ms
Processed entity 562/1000 in 0ms
Processed entity 563/1000 in 0ms
Processed entity 564/1000 in 0ms
Processed entity 565/1000 in 0ms
Processed entity 566/1000 in 0ms
Processed entity 567/1000 in 0ms
Processed entity 568/1000 in 0ms
Processed entity 569/1000 in 0ms
Processed entity 570/1000 in 0ms
Processed entity 571/1000 in 0ms
Processed entity 572/1000 in 0ms
Processed entity 573/1000 in 0ms
Processed entity 574/1000 in 0ms
Processed entity 575/1000 in 0ms
Processed entity 576/1000 in 0ms
Processed entity 577/1000 in 0ms
Processed entity 578/1000 in 0ms
Processed entity 579/1000 in 0ms
Processed entity 580/1000 in 0ms
Processed entity 581/1000 in 0ms
Processed entity 582/1000 in 0ms
Processed entity 583/1000 in 0ms
Processed entity 584/1000 in 0ms
Processed entity 585/1000 in 0ms
Processed entity 586/1000 in 0ms
Processed entity 587/1000 in 0ms
Processed entity 588/1000 in 0ms
Processed entity 589/1000 in 0ms
Processed entity 590/1000 in 0ms
Processed entity 591/1000 in 0ms
Processed entity 592/1000 in 0ms
Processed entity 593/1000 in 0ms
Processed entity 594/1000 in 0ms
Processed entity 595/1000 in 211710ms
Processed entity 596/1000 in 1ms
Processed entity 597/1000 in 0ms
Processed entity 598/1000 in 0ms
Processed entity 599/1000 in 1ms
Processed entity 600/1000 in 0ms
Processed entity 601/1000 in 0ms
Processed entity 602/1000 in 0ms
Processed entity 603/1000 in 0ms
Processed entity 604/1000 in 0ms
Processed entity 605/1000 in 0ms
Processed entity 606/1000 in 1ms
Processed entity 607/1000 in 0ms
Processed entity 608/1000 in 1ms
Processed entity 609/1000 in 1ms
Processed entity 610/1000 in 0ms
Processed entity 611/1000 in 0ms
Processed entity 612/1000 in 1ms
Processed entity 613/1000 in 0ms
Processed entity 614/1000 in 0ms
Processed entity 615/1000 in 1ms
Processed entity 616/1000 in 0ms
Processed entity 617/1000 in 0ms
Processed entity 618/1000 in 0ms
Processed entity 619/1000 in 1ms
Processed entity 620/1000 in 0ms
Processed entity 621/1000 in 0ms
Processed entity 622/1000 in 0ms
Processed entity 623/1000 in 0ms
Processed entity 624/1000 in 0ms
Processed entity 625/1000 in 1ms
Processed entity 626/1000 in 0ms
Processed entity 627/1000 in 0ms
Processed entity 628/1000 in 0ms
Processed entity 629/1000 in 0ms
Processed entity 630/1000 in 0ms
Processed entity 631/1000 in 0ms
Processed entity 632/1000 in 0ms
Processed entity 633/1000 in 0ms
Processed entity 634/1000 in 0ms
Processed entity 635/1000 in 0ms
Processed entity 636/1000 in 0ms
Processed entity 637/1000 in 0ms
Processed entity 638/1000 in 0ms
Processed entity 639/1000 in 0ms
Processed entity 640/1000 in 1ms
Processed entity 641/1000 in 0ms
Processed entity 642/1000 in 0ms
Processed entity 643/1000 in 0ms
Processed entity 644/1000 in 0ms
Processed entity 645/1000 in 0ms
Processed entity 646/1000 in 0ms
Processed entity 647/1000 in 0ms
Processed entity 648/1000 in 0ms
Processed entity 649/1000 in 0ms
Processed entity 650/1000 in 0ms
Processed entity 651/1000 in 0ms
Processed entity 652/1000 in 0ms
Processed entity 653/1000 in 0ms
Processed entity 654/1000 in 0ms
Processed entity 655/1000 in 0ms
Processed entity 656/1000 in 1ms
Processed entity 657/1000 in 0ms
Processed entity 658/1000 in 0ms
Processed entity 659/1000 in 0ms
Processed entity 660/1000 in 0ms
Processed entity 661/1000 in 0ms
Processed entity 662/1000 in 0ms
Processed entity 663/1000 in 1ms
Processed entity 664/1000 in 0ms
Processed entity 665/1000 in 0ms
Processed entity 666/1000 in 0ms
Processed entity 667/1000 in 0ms
Processed entity 668/1000 in 0ms
Processed entity 669/1000 in 0ms
Processed entity 670/1000 in 0ms
Processed entity 671/1000 in 0ms
Processed entity 672/1000 in 0ms
Processed entity 673/1000 in 0ms
Processed entity 674/1000 in 0ms
Processed entity 675/1000 in 0ms
Processed entity 676/1000 in 1ms
Processed entity 677/1000 in 0ms
Processed entity 678/1000 in 0ms
Processed entity 679/1000 in 0ms
Processed entity 680/1000 in 0ms
Processed entity 681/1000 in 0ms
Processed entity 682/1000 in 0ms
Processed entity 683/1000 in 0ms
Processed entity 684/1000 in 0ms
Processed entity 685/1000 in 0ms
Processed entity 686/1000 in 1ms
Processed entity 687/1000 in 0ms
Processed entity 688/1000 in 0ms
Processed entity 689/1000 in 0ms
Processed entity 690/1000 in 0ms
Processed entity 691/1000 in 0ms
Processed entity 692/1000 in 0ms
Processed entity 693/1000 in 0ms
Processed entity 694/1000 in 0ms
Processed entity 695/1000 in 0ms
Processed entity 696/1000 in 0ms
Processed entity 697/1000 in 0ms
Processed entity 698/1000 in 0ms
Processed entity 699/1000 in 0ms
Processed entity 700/1000 in 0ms
Processed entity 701/1000 in 0ms
Processed entity 702/1000 in 0ms
Processed entity 703/1000 in 0ms
Processed entity 704/1000 in 0ms
Processed entity 705/1000 in 1ms
Processed entity 706/1000 in 0ms
Processed entity 707/1000 in 0ms
Processed entity 708/1000 in 0ms
Processed entity 709/1000 in 0ms
Processed entity 710/1000 in 0ms
Processed entity 711/1000 in 0ms
Processed entity 712/1000 in 0ms
Processed entity 713/1000 in 0ms
Processed entity 714/1000 in 0ms
Processed entity 715/1000 in 0ms
Processed entity 716/1000 in 0ms
Processed entity 717/1000 in 0ms
Processed entity 718/1000 in 0ms
Processed entity 719/1000 in 0ms
Processed entity 720/1000 in 0ms
Processed entity 721/1000 in 0ms
Processed entity 722/1000 in 1ms
Processed entity 723/1000 in 0ms
Processed entity 724/1000 in 0ms
Processed entity 725/1000 in 0ms
Processed entity 726/1000 in 0ms
Processed entity 727/1000 in 0ms
Processed entity 728/1000 in 0ms
Processed entity 729/1000 in 0ms
Processed entity 730/1000 in 0ms
Processed entity 731/1000 in 0ms
Processed entity 732/1000 in 0ms
Processed entity 733/1000 in 0ms
Processed entity 734/1000 in 0ms
Processed entity 735/1000 in 0ms
Processed entity 736/1000 in 0ms
Processed entity 737/1000 in 0ms
Processed entity 738/1000 in 0ms
Processed entity 739/1000 in 0ms
Processed entity 740/1000 in 0ms
Processed entity 741/1000 in 0ms
Processed entity 742/1000 in 0ms
Processed entity 743/1000 in 0ms
Processed entity 744/1000 in 0ms
Processed entity 745/1000 in 0ms
Processed entity 746/1000 in 0ms
Processed entity 747/1000 in 0ms
Processed entity 748/1000 in 0ms
Processed entity 749/1000 in 0ms
Processed entity 750/1000 in 0ms
Processed entity 751/1000 in 0ms
Processed entity 752/1000 in 0ms
Processed entity 753/1000 in 0ms
Processed entity 754/1000 in 0ms
Processed entity 755/1000 in 0ms
Processed entity 756/1000 in 0ms
Processed entity 757/1000 in 0ms
Processed entity 758/1000 in 0ms
Processed entity 759/1000 in 0ms
Processed entity 760/1000 in 0ms
Processed entity 761/1000 in 0ms
Processed entity 762/1000 in 0ms
Processed entity 763/1000 in 0ms
Processed entity 764/1000 in 0ms
Processed entity 765/1000 in 0ms
Processed entity 766/1000 in 0ms
Processed entity 767/1000 in 0ms
Processed entity 768/1000 in 0ms
Processed entity 769/1000 in 0ms
Processed entity 770/1000 in 0ms
Processed entity 771/1000 in 0ms
Processed entity 772/1000 in 0ms
Processed entity 773/1000 in 0ms
Processed entity 774/1000 in 0ms
Processed entity 775/1000 in 0ms
Processed entity 776/1000 in 0ms
Processed entity 777/1000 in 0ms
Processed entity 778/1000 in 0ms
Processed entity 779/1000 in 0ms
Processed entity 780/1000 in 0ms
Processed entity 781/1000 in 0ms
Processed entity 782/1000 in 1ms
Processed entity 783/1000 in 0ms
Processed entity 784/1000 in 0ms
Processed entity 785/1000 in 0ms
Processed entity 786/1000 in 0ms
Processed entity 787/1000 in 0ms
Processed entity 788/1000 in 0ms
Processed entity 789/1000 in 0ms
Processed entity 790/1000 in 0ms
Processed entity 791/1000 in 0ms
Processed entity 792/1000 in 0ms
Processed entity 793/1000 in 0ms
Processed entity 794/1000 in 0ms
Processed entity 795/1000 in 0ms
Processed entity 796/1000 in 0ms
Processed entity 797/1000 in 0ms
Processed entity 798/1000 in 0ms
Processed entity 799/1000 in 0ms
Processed entity 800/1000 in 0ms
Processed entity 801/1000 in 0ms
Processed entity 802/1000 in 0ms
Processed entity 803/1000 in 0ms
Processed entity 804/1000 in 0ms
Processed entity 805/1000 in 0ms
Processed entity 806/1000 in 0ms
Processed entity 807/1000 in 0ms
Processed entity 808/1000 in 0ms
Processed entity 809/1000 in 0ms
Processed entity 810/1000 in 0ms
Processed entity 811/1000 in 0ms
Processed entity 812/1000 in 1ms
Processed entity 813/1000 in 0ms
Processed entity 814/1000 in 0ms
Processed entity 815/1000 in 0ms
Processed entity 816/1000 in 0ms
Processed entity 817/1000 in 0ms
Processed entity 818/1000 in 0ms
Processed entity 819/1000 in 0ms
Processed entity 820/1000 in 0ms
Processed entity 821/1000 in 0ms
Processed entity 822/1000 in 0ms
Processed entity 823/1000 in 0ms
Processed entity 824/1000 in 1ms
Processed entity 825/1000 in 0ms
Processed entity 826/1000 in 0ms
Processed entity 827/1000 in 0ms
Processed entity 828/1000 in 0ms
Processed entity 829/1000 in 0ms
Processed entity 830/1000 in 0ms
Processed entity 831/1000 in 0ms
Processed entity 832/1000 in 0ms
Processed entity 833/1000 in 0ms
Processed entity 834/1000 in 0ms
Processed entity 835/1000 in 0ms
Processed entity 836/1000 in 0ms
Processed entity 837/1000 in 0ms
Processed entity 838/1000 in 0ms
Processed entity 839/1000 in 0ms
Processed entity 840/1000 in 0ms
Processed entity 841/1000 in 0ms
Processed entity 842/1000 in 0ms
Processed entity 843/1000 in 0ms
Processed entity 844/1000 in 0ms
Processed entity 845/1000 in 0ms
Processed entity 846/1000 in 0ms
Processed entity 847/1000 in 0ms
Processed entity 848/1000 in 0ms
Processed entity 849/1000 in 0ms
Processed entity 850/1000 in 0ms
Processed entity 851/1000 in 0ms
Processed entity 852/1000 in 0ms
Processed entity 853/1000 in 0ms
Processed entity 854/1000 in 0ms
Processed entity 855/1000 in 0ms
Processed entity 856/1000 in 0ms
Processed entity 857/1000 in 0ms
Processed entity 858/1000 in 0ms
Processed entity 859/1000 in 0ms
Processed entity 860/1000 in 0ms
Processed entity 861/1000 in 0ms
Processed entity 862/1000 in 0ms
Processed entity 863/1000 in 0ms
Processed entity 864/1000 in 0ms
Processed entity 865/1000 in 0ms
Processed entity 866/1000 in 1ms
Processed entity 867/1000 in 0ms
Processed entity 868/1000 in 0ms
Processed entity 869/1000 in 0ms
Processed entity 870/1000 in 0ms
Processed entity 871/1000 in 0ms
Processed entity 872/1000 in 0ms
Processed entity 873/1000 in 0ms
Processed entity 874/1000 in 0ms
Processed entity 875/1000 in 0ms
Processed entity 876/1000 in 0ms
Processed entity 877/1000 in 0ms
Processed entity 878/1000 in 0ms
Processed entity 879/1000 in 0ms
Processed entity 880/1000 in 0ms
Processed entity 881/1000 in 0ms
Processed entity 882/1000 in 0ms
Processed entity 883/1000 in 0ms
Processed entity 884/1000 in 0ms
Processed entity 885/1000 in 0ms
Processed entity 886/1000 in 0ms
Processed entity 887/1000 in 0ms
Processed entity 888/1000 in 0ms
Processed entity 889/1000 in 0ms
Processed entity 890/1000 in 0ms
Processed entity 891/1000 in 0ms
Processed entity 892/1000 in 0ms
Processed entity 893/1000 in 0ms
Processed entity 894/1000 in 0ms
Processed entity 895/1000 in 0ms
Processed entity 896/1000 in 0ms
Processed entity 897/1000 in 0ms
Processed entity 898/1000 in 0ms
Processed entity 899/1000 in 0ms
Processed entity 900/1000 in 0ms
Processed entity 901/1000 in 0ms
Processed entity 902/1000 in 0ms
Processed entity 903/1000 in 1ms
Processed entity 904/1000 in 0ms
Processed entity 905/1000 in 0ms
Processed entity 906/1000 in 0ms
Processed entity 907/1000 in 0ms
Processed entity 908/1000 in 1ms
Processed entity 909/1000 in 1ms
Processed entity 910/1000 in 0ms
Processed entity 911/1000 in 0ms
Processed entity 912/1000 in 0ms
Processed entity 913/1000 in 0ms
Processed entity 914/1000 in 0ms
Processed entity 915/1000 in 0ms
Processed entity 916/1000 in 0ms
Processed entity 917/1000 in 0ms
Processed entity 918/1000 in 0ms
Processed entity 919/1000 in 1ms
Processed entity 920/1000 in 0ms
Processed entity 921/1000 in 0ms
Processed entity 922/1000 in 0ms
Processed entity 923/1000 in 0ms
Processed entity 924/1000 in 0ms
Processed entity 925/1000 in 0ms
Processed entity 926/1000 in 1ms
Processed entity 927/1000 in 0ms
Processed entity 928/1000 in 1ms
Processed entity 929/1000 in 0ms
Processed entity 930/1000 in 1ms
Processed entity 931/1000 in 0ms
Processed entity 932/1000 in 0ms
Processed entity 933/1000 in 0ms
Processed entity 934/1000 in 0ms
Processed entity 935/1000 in 0ms
Processed entity 936/1000 in 0ms
Processed entity 937/1000 in 0ms
Processed entity 938/1000 in 0ms
Processed entity 939/1000 in 0ms
Processed entity 940/1000 in 0ms
Processed entity 941/1000 in 0ms
Processed entity 942/1000 in 0ms
Processed entity 943/1000 in 1ms
Processed entity 944/1000 in 0ms
Processed entity 945/1000 in 0ms
Processed entity 946/1000 in 0ms
Processed entity 947/1000 in 0ms
Processed entity 948/1000 in 0ms
Processed entity 949/1000 in 0ms
Processed entity 950/1000 in 0ms
Processed entity 951/1000 in 0ms
Processed entity 952/1000 in 0ms
Processed entity 953/1000 in 0ms
Processed entity 954/1000 in 0ms
Processed entity 955/1000 in 0ms
Processed entity 956/1000 in 0ms
Processed entity 957/1000 in 0ms
Processed entity 958/1000 in 0ms
Processed entity 959/1000 in 0ms
Processed entity 960/1000 in 0ms
Processed entity 961/1000 in 0ms
Processed entity 962/1000 in 0ms
Processed entity 963/1000 in 0ms
Processed entity 964/1000 in 0ms
Processed entity 965/1000 in 0ms
Processed entity 966/1000 in 0ms
Processed entity 967/1000 in 0ms
Processed entity 968/1000 in 0ms
Processed entity 969/1000 in 0ms
Processed entity 970/1000 in 0ms
Processed entity 971/1000 in 0ms
Processed entity 972/1000 in 0ms
Processed entity 973/1000 in 0ms
Processed entity 974/1000 in 1ms
Processed entity 975/1000 in 0ms
Processed entity 976/1000 in 0ms
Processed entity 977/1000 in 0ms
Processed entity 978/1000 in 0ms
Processed entity 979/1000 in 0ms
Processed entity 980/1000 in 0ms
Processed entity 981/1000 in 0ms
Processed entity 982/1000 in 0ms
Processed entity 983/1000 in 0ms
Processed entity 984/1000 in 0ms
Processed entity 985/1000 in 0ms
Processed entity 986/1000 in 0ms
Processed entity 987/1000 in 0ms
Processed entity 988/1000 in 0ms
Processed entity 989/1000 in 0ms
Processed entity 990/1000 in 0ms
Processed entity 991/1000 in 0ms
Processed entity 992/1000 in 0ms
Processed entity 993/1000 in 0ms
Processed entity 994/1000 in 0ms
Processed entity 995/1000 in 0ms
Processed entity 996/1000 in 0ms
Processed entity 997/1000 in 0ms
Processed entity 998/1000 in 0ms
Processed entity 999/1000 in 0ms
done
Statistical profiling result from isolate-0x2dde7b0-v8.log, (192657 ticks, 1 unaccounted, 0 excluded).

 [Shared libraries]:
   ticks  total  nonlib   name

 [JavaScript]:
   ticks  total  nonlib   name
     21    0.0%    0.0%  LazyCompile: ~validateFunction /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:14:26
      5    0.0%    0.0%  LazyCompile: ~nextTick internal/process/next_tick.js:206:20
      5    0.0%    0.0%  Builtin: CallFunction_ReceiverIsAny
      4    0.0%    0.0%  Builtin: InterpreterEntryTrampoline
      3    0.0%    0.0%  LazyCompile: ~entities.forEach /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:2785:18
      2    0.0%    0.0%  Stub: StringAddStub
      2    0.0%    0.0%  LazyCompile: ~validateAsyncId internal/async_hooks.js:117:25
      2    0.0%    0.0%  LazyCompile: ~onwrite _stream_writable.js:458:17
      2    0.0%    0.0%  LazyCompile: ~handleWriteReq internal/stream_base_commons.js:9:24
      2    0.0%    0.0%  LazyCompile: ~Writable.write _stream_writable.js:269:36
      2    0.0%    0.0%  LazyCompile: ~Readable.removeListener _stream_readable.js:832:45
      2    0.0%    0.0%  Builtin: Call_ReceiverIsAny
      1    0.0%    0.0%  Stub: ArraySingleArgumentConstructorStub
      1    0.0%    0.0%  Stub: ArrayConstructorStub
      1    0.0%    0.0%  LazyCompile: ~writeGeneric internal/stream_base_commons.js:67:22
      1    0.0%    0.0%  LazyCompile: ~write console.js:141:15
      1    0.0%    0.0%  LazyCompile: ~setup_performance internal/process.js:27:27
      1    0.0%    0.0%  LazyCompile: ~popAsyncIds internal/async_hooks.js:402:21
      1    0.0%    0.0%  LazyCompile: ~getColorDepth internal/tty.js:72:23
      1    0.0%    0.0%  LazyCompile: ~doWrite _stream_writable.js:403:17
      1    0.0%    0.0%  LazyCompile: ~addListener events.js:260:58
      1    0.0%    0.0%  LazyCompile: ~_tickCallback internal/process/next_tick.js:152:25
      1    0.0%    0.0%  LazyCompile: ~_addListener events.js:193:22
      1    0.0%    0.0%  LazyCompile: ~Socket._writeGeneric net.js:734:42
      1    0.0%    0.0%  LazyCompile: ~Socket net.js:227:16
      1    0.0%    0.0%  LazyCompile: *getColorDepth internal/tty.js:72:23
      1    0.0%    0.0%  Builtin: StoreIC
      1    0.0%    0.0%  Builtin: RegExpPrototypeTest
      1    0.0%    0.0%  Builtin: KeyedStoreIC
      1    0.0%    0.0%  Builtin: FastNewObject
      1    0.0%    0.0%  Builtin: FastFunctionPrototypeBind
      1    0.0%    0.0%  Builtin: Call_ReceiverIsNullOrUndefined
      1    0.0%    0.0%  Builtin: CallFunction_ReceiverIsNullOrUndefined
      1    0.0%    0.0%  Builtin: CallBoundFunction

 [C++]:
   ticks  total  nonlib   name
  120013   62.3%   62.3%  v8::internal::compiler::(anonymous namespace)::NeedsCheckHeapObject(v8::internal::compiler::Node*)
  46487   24.1%   24.1%  v8::internal::compiler::NodeProperties::GetControlInput(v8::internal::compiler::Node*, int)
  13024    6.8%    6.8%  v8::internal::compiler::OperatorProperties::HasContextInput(v8::internal::compiler::Operator const*)
  12811    6.6%    6.6%  v8::internal::compiler::OperatorProperties::HasFrameStateInput(v8::internal::compiler::Operator const*)
     71    0.0%    0.0%  __pthread_cond_wait
     40    0.0%    0.0%  node::contextify::ContextifyScript::New(v8::FunctionCallbackInfo<v8::Value> const&)
     14    0.0%    0.0%  void node::StreamBase::JSMethod<node::LibuvStreamWrap, &(int node::StreamBase::WriteString<(node::encoding)1>(v8::FunctionCallbackInfo<v8::Value> const&))>(v8::FunctionCallbackInfo<v8::Value> const&)
      6    0.0%    0.0%  v8::internal::compiler::GraphReducer::ReduceTop()
      6    0.0%    0.0%  node::GetBinding(v8::FunctionCallbackInfo<v8::Value> const&)
      5    0.0%    0.0%  v8::internal::compiler::GraphTrimmer::TrimGraph()
      5    0.0%    0.0%  __GI___libc_write
      4    0.0%    0.0%  v8::internal::(anonymous namespace)::JSObjectWalkVisitor<v8::internal::AllocationSiteUsageContext>::StructureWalk(v8::internal::Handle<v8::internal::JSObject>)
      4    0.0%    0.0%  node::EnvGetter(v8::Local<v8::Name>, v8::PropertyCallbackInfo<v8::Value> const&)
      3    0.0%    0.0%  v8::internal::compiler::Node::New(v8::internal::Zone*, unsigned int, v8::internal::compiler::Operator const*, int, v8::internal::compiler::Node* const*, bool)
      2    0.0%    0.0%  void v8::internal::LookupIterator::Start<false>()
      2    0.0%    0.0%  v8::internal::StackGuard::HandleInterrupts()
      2    0.0%    0.0%  v8::internal::Scope::FinalizeBlockScope()
      2    0.0%    0.0%  v8::internal::Heap::CopyJSObject(v8::internal::JSObject*, v8::internal::AllocationSite*)
      2    0.0%    0.0%  v8::internal::AstValueFactory::GetString(unsigned int, bool, v8::internal::Vector<unsigned char const>)
      1    0.0%    0.0%  void v8::internal::LookupIterator::NextInternal<false>(v8::internal::Map*, v8::internal::JSReceiver*)
      1    0.0%    0.0%  void v8::internal::CopyWords<v8::internal::Object*>(v8::internal::Object**, v8::internal::Object* const*, unsigned long)
      1    0.0%    0.0%  void node::Buffer::(anonymous namespace)::StringSlice<(node::encoding)1>(v8::FunctionCallbackInfo<v8::Value> const&)
      1    0.0%    0.0%  v8::internal::interpreter::BytecodeLabels::New()
      1    0.0%    0.0%  v8::internal::compiler::Type::SlowIs(v8::internal::compiler::Type*) [clone .part.28]
      1    0.0%    0.0%  v8::internal::compiler::SourcePositionTable::Decorator::Decorate(v8::internal::compiler::Node*)
      1    0.0%    0.0%  v8::internal::compiler::PropertyAccessBuilder::BuildCheckMaps(v8::internal::compiler::Node*, v8::internal::compiler::Node**, v8::internal::compiler::Node*, std::vector<v8::internal::Handle<v8::internal::Map>, std::allocator<v8::internal::Handle<v8::internal::Map> > > const&)
      1    0.0%    0.0%  v8::internal::compiler::Pipeline::GenerateCodeForCodeStub(v8::internal::Isolate*, v8::internal::compiler::CallDescriptor*, v8::internal::compiler::Graph*, v8::internal::compiler::Schedule*, v8::internal::Code::Kind, char const*, unsigned int, int, v8::internal::JumpOptimizationInfo*)
      1    0.0%    0.0%  v8::internal::compiler::MapsParameterInfo::MapsParameterInfo(v8::internal::ZoneHandleSet<v8::internal::Map> const&)
      1    0.0%    0.0%  v8::internal::compiler::LoopVariableOptimizer::Run()
      1    0.0%    0.0%  v8::internal::compiler::GraphReducer::ReplaceWithValue(v8::internal::compiler::Node*, v8::internal::compiler::Node*, v8::internal::compiler::Node*, v8::internal::compiler::Node*)
      1    0.0%    0.0%  v8::internal::compiler::GraphReducer::Reduce(v8::internal::compiler::Node*)
      1    0.0%    0.0%  v8::internal::compiler::DeadCodeElimination::ReduceNode(v8::internal::compiler::Node*)
      1    0.0%    0.0%  v8::internal::compiler::CommonOperatorReducer::ReduceMerge(v8::internal::compiler::Node*)
      1    0.0%    0.0%  v8::internal::compiler::CodeGenerator::ComputeBranchInfo(v8::internal::compiler::BranchInfo*, v8::internal::compiler::Instruction*)
      1    0.0%    0.0%  v8::internal::compiler::BytecodeLivenessMap::GetLiveness(int) const
      1    0.0%    0.0%  v8::internal::compiler::BytecodeGraphBuilder::MakeNode(v8::internal::compiler::Operator const*, int, v8::internal::compiler::Node* const*, bool)
      1    0.0%    0.0%  v8::internal::compiler::(anonymous namespace)::UpdateOutLiveness(v8::internal::interpreter::Bytecode, v8::internal::compiler::BytecodeLivenessState&, v8::internal::compiler::BytecodeLivenessState*, v8::internal::interpreter::BytecodeArrayAccessor const&, v8::internal::compiler::BytecodeLivenessMap const&)
      1    0.0%    0.0%  v8::internal::compiler::(anonymous namespace)::UpdateInLiveness(v8::internal::interpreter::Bytecode, v8::internal::compiler::BytecodeLivenessState&, v8::internal::interpreter::BytecodeArrayAccessor const&)
      1    0.0%    0.0%  v8::internal::Zone::New(unsigned long)
      1    0.0%    0.0%  v8::internal::StringTable::LookupKey(v8::internal::Isolate*, v8::internal::StringTableKey*)
      1    0.0%    0.0%  v8::internal::ScopeInfo::Create(v8::internal::Isolate*, v8::internal::Zone*, v8::internal::Scope*, v8::internal::MaybeHandle<v8::internal::ScopeInfo>)
      1    0.0%    0.0%  v8::internal::Scavenger::ScavengeObject(v8::internal::HeapObject**, v8::internal::HeapObject*)
      1    0.0%    0.0%  v8::internal::Scavenger::Process(v8::internal::OneshotBarrier*)
      1    0.0%    0.0%  v8::internal::Scanner::ScanIdentifierOrKeywordInner(v8::internal::Scanner::LiteralScope*)
      1    0.0%    0.0%  v8::internal::Scanner::Next()
      1    0.0%    0.0%  v8::internal::SaveContext::SaveContext(v8::internal::Isolate*)
      1    0.0%    0.0%  v8::internal::Runtime_CreateObjectLiteral(int, v8::internal::Object**, v8::internal::Isolate*)
      1    0.0%    0.0%  v8::internal::ParserBase<v8::internal::Parser>::ParsePropertyName(v8::internal::AstRawString const**, v8::internal::ParserBase<v8::internal::Parser>::PropertyKind*, bool*, bool*, bool*, bool*, bool*, bool*)
      1    0.0%    0.0%  v8::internal::ParserBase<v8::internal::Parser>::ParseBinaryExpression(int, bool, bool*)
      1    0.0%    0.0%  v8::internal::ParserBase<v8::internal::Parser>::ParseAssignmentExpression(bool, bool*)
      1    0.0%    0.0%  v8::internal::ParserBase<v8::internal::Parser>::BindingPatternUnexpectedToken()
      1    0.0%    0.0%  v8::internal::ParserBase<v8::internal::Parser>::ArrowFormalParametersUnexpectedToken()
      1    0.0%    0.0%  v8::internal::Object::ToNumber(v8::internal::Handle<v8::internal::Object>)
      1    0.0%    0.0%  v8::internal::MaybeHandle<v8::internal::Object> v8::internal::(anonymous namespace)::HandleApiCallHelper<false>(v8::internal::Isolate*, v8::internal::Handle<v8::internal::HeapObject>, v8::internal::Handle<v8::internal::HeapObject>, v8::internal::Handle<v8::internal::FunctionTemplateInfo>, v8::internal::Handle<v8::internal::Object>, v8::internal::BuiltinArguments)
      1    0.0%    0.0%  v8::internal::MaybeHandle<v8::internal::JSObject> v8::internal::(anonymous namespace)::CreateLiteral<v8::internal::(anonymous namespace)::ObjectBoilerplate>(v8::internal::Isolate*, v8::internal::Handle<v8::internal::FeedbackVector>, int, v8::internal::Handle<v8::internal::HeapObject>, int)
      1    0.0%    0.0%  v8::internal::Map::RawCopy(v8::internal::Handle<v8::internal::Map>, int, int)
      1    0.0%    0.0%  v8::internal::LookupIterator::WriteDataValue(v8::internal::Handle<v8::internal::Object>, bool)
      1    0.0%    0.0%  v8::internal::LookupIterator::UpdateProtector() [clone .part.352]
      1    0.0%    0.0%  v8::internal::LookupIterator::State v8::internal::LookupIterator::LookupInRegularHolder<false>(v8::internal::Map*, v8::internal::JSReceiver*)
      1    0.0%    0.0%  v8::internal::LookupIterator::PrepareTransitionToDataProperty(v8::internal::Handle<v8::internal::JSReceiver>, v8::internal::Handle<v8::internal::Object>, v8::internal::PropertyAttributes, v8::internal::Object::StoreFromKeyed)
      1    0.0%    0.0%  v8::internal::LookupIterator::ApplyTransitionToDataProperty(v8::internal::Handle<v8::internal::JSReceiver>)
      1    0.0%    0.0%  v8::internal::KeyedStoreIC::Store(v8::internal::Handle<v8::internal::Object>, v8::internal::Handle<v8::internal::Object>, v8::internal::Handle<v8::internal::Object>) [clone .constprop.199]
      1    0.0%    0.0%  v8::internal::JSReceiver::SetOrCopyDataProperties(v8::internal::Isolate*, v8::internal::Handle<v8::internal::JSReceiver>, v8::internal::Handle<v8::internal::Object>, v8::internal::ScopedVector<v8::internal::Handle<v8::internal::Object> > const*, bool)
      1    0.0%    0.0%  v8::internal::JSObject::SetPrototype(v8::internal::Handle<v8::internal::JSObject>, v8::internal::Handle<v8::internal::Object>, bool, v8::internal::ShouldThrow)
      1    0.0%    0.0%  v8::internal::JSObject::DefineOwnPropertyIgnoreAttributes(v8::internal::LookupIterator*, v8::internal::Handle<v8::internal::Object>, v8::internal::PropertyAttributes, v8::internal::ShouldThrow, v8::internal::JSObject::AccessorInfoHandling)
      1    0.0%    0.0%  v8::internal::IterateAndScavengePromotedObjectsVisitor::VisitPointers(v8::internal::HeapObject*, v8::internal::Object**, v8::internal::Object**)
      1    0.0%    0.0%  v8::internal::HeapObjectIterator::Next()
      1    0.0%    0.0%  v8::internal::HeapObject::synchronized_set_map(v8::internal::Map*)
      1    0.0%    0.0%  v8::internal::Heap::Scavenge()
      1    0.0%    0.0%  v8::internal::Heap::AllocateRaw(int, v8::internal::AllocationSpace, v8::internal::AllocationAlignment)
      1    0.0%    0.0%  v8::internal::Heap::AllocateFeedbackVector(v8::internal::SharedFunctionInfo*, v8::internal::PretenureFlag)
      1    0.0%    0.0%  v8::internal::FunctionCallbackArguments::Call(v8::internal::CallHandlerInfo*)
      1    0.0%    0.0%  v8::internal::FixStaleLeftTrimmedHandlesVisitor::VisitRootPointers(v8::internal::Root, char const*, v8::internal::Object**, v8::internal::Object**)
      1    0.0%    0.0%  v8::internal::FieldType::cast(v8::internal::Object*)
      1    0.0%    0.0%  v8::internal::FieldType::Any()
      1    0.0%    0.0%  v8::internal::Factory::NewFixedArrayWithMap(v8::internal::Heap::RootListIndex, int, v8::internal::PretenureFlag)
      1    0.0%    0.0%  v8::internal::ExpressionClassifier<v8::internal::ParserTypes<v8::internal::Parser> >::Accumulate(v8::internal::ExpressionClassifier<v8::internal::ParserTypes<v8::internal::Parser> >*, unsigned int)
      1    0.0%    0.0%  v8::internal::Deserializer<v8::internal::DefaultDeserializerAllocator>::ReadData(v8::internal::Object**, v8::internal::Object**, int, unsigned char*)
      1    0.0%    0.0%  v8::internal::AstValueFactory::GetOneByteStringInternal(v8::internal::Vector<unsigned char const>)
      1    0.0%    0.0%  v8::internal::Assembler::emit_operand(int, v8::internal::Operand)
      1    0.0%    0.0%  v8::internal::Assembler::bind_to(v8::internal::Label*, int)
      1    0.0%    0.0%  v8::internal::ApiNatives::InstantiateFunction(v8::internal::Handle<v8::internal::FunctionTemplateInfo>, v8::internal::MaybeHandle<v8::internal::Name>)
      1    0.0%    0.0%  v8::internal::AllocationResult v8::internal::Heap::CopyArrayAndGrow<v8::internal::PropertyArray>(v8::internal::PropertyArray*, int, v8::internal::PretenureFlag)
      1    0.0%    0.0%  v8::internal::AccountingAllocator::ReturnSegment(v8::internal::Segment*)
      1    0.0%    0.0%  v8::internal::(anonymous namespace)::UpdateNeedsHoleCheck(v8::internal::Variable*, v8::internal::VariableProxy*, v8::internal::Scope*)
      1    0.0%    0.0%  v8::internal::(anonymous namespace)::InvalidateOnePrototypeValidityCellInternal(v8::internal::Map*)
      1    0.0%    0.0%  v8::internal::(anonymous namespace)::InitializeIntrinsicFunctionNames()
      1    0.0%    0.0%  std::vector<v8::internal::compiler::SpecialRPONumberer::LoopInfo, v8::internal::ZoneAllocator<v8::internal::compiler::SpecialRPONumberer::LoopInfo> >::_M_fill_insert(__gnu_cxx::__normal_iterator<v8::internal::compiler::SpecialRPONumberer::LoopInfo*, std::vector<v8::internal::compiler::SpecialRPONumberer::LoopInfo, v8::internal::ZoneAllocator<v8::internal::compiler::SpecialRPONumberer::LoopInfo> > >, unsigned long, v8::internal::compiler::SpecialRPONumberer::LoopInfo const&)
      1    0.0%    0.0%  std::ios_base::ios_base()
      1    0.0%    0.0%  node::inspector::(anonymous namespace)::InspectorConsoleCall(v8::FunctionCallbackInfo<v8::Value> const&)
      1    0.0%    0.0%  node::LibuvStreamWrap::Initialize(v8::Local<v8::Object>, v8::Local<v8::Value>, v8::Local<v8::Context>)::{lambda(v8::FunctionCallbackInfo<v8::Value> const&)#1}::_FUN(v8::FunctionCallbackInfo<v8::Value> const&)
      1    0.0%    0.0%  getrn
      1    0.0%    0.0%  fwrite
      1    0.0%    0.0%  fflush
      1    0.0%    0.0%  do_futex_wait.constprop.1
      1    0.0%    0.0%  _dl_addr
      1    0.0%    0.0%  __pthread_mutex_unlock_usercnt
      1    0.0%    0.0%  __lll_unlock_wake

 [Summary]:
   ticks  total  nonlib   name
     74    0.0%    0.0%  JavaScript
  192582  100.0%  100.0%  C++
      8    0.0%    0.0%  GC
      0    0.0%          Shared libraries
      1    0.0%          Unaccounted

 [C++ entry points]:
   ticks    cpp   total   name
  192363   99.9%   99.8%  v8::internal::Runtime_CompileOptimized_Concurrent(int, v8::internal::Object**, v8::internal::Isolate*)
     66    0.0%    0.0%  v8::internal::Builtin_HandleApiCall(int, v8::internal::Object**, v8::internal::Isolate*)
     23    0.0%    0.0%  v8::internal::Runtime_CompileLazy(int, v8::internal::Object**, v8::internal::Isolate*)
     11    0.0%    0.0%  v8::internal::Runtime_CreateObjectLiteral(int, v8::internal::Object**, v8::internal::Isolate*)
     10    0.0%    0.0%  v8::internal::Runtime_CopyDataProperties(int, v8::internal::Object**, v8::internal::Isolate*)
      7    0.0%    0.0%  v8::internal::Runtime_LoadPropertyWithInterceptor(int, v8::internal::Object**, v8::internal::Isolate*)
      7    0.0%    0.0%  v8::internal::Runtime_KeyedStoreIC_Miss(int, v8::internal::Object**, v8::internal::Isolate*)
      2    0.0%    0.0%  v8::internal::Runtime_Interrupt(int, v8::internal::Object**, v8::internal::Isolate*)
      1    0.0%    0.0%  v8::internal::Runtime_RegExpExec(int, v8::internal::Object**, v8::internal::Isolate*)
      1    0.0%    0.0%  v8::internal::Runtime_InterpreterDeserializeLazy(int, v8::internal::Object**, v8::internal::Isolate*)
      1    0.0%    0.0%  v8::internal::Runtime_AddDictionaryProperty(int, v8::internal::Object**, v8::internal::Isolate*)
      1    0.0%    0.0%  v8::internal::Builtin_StringPrototypeLastIndexOf(int, v8::internal::Object**, v8::internal::Isolate*)
      1    0.0%    0.0%  v8::internal::Builtin_ObjectSetPrototypeOf(int, v8::internal::Object**, v8::internal::Isolate*)

 [Bottom up (heavy) profile]:
  Note: percentage shows a share of a particular caller in the total
  amount of its parent calls.
  Callers occupying less than 1.0% are not shown.

   ticks parent  name
  120013   62.3%  v8::internal::compiler::(anonymous namespace)::NeedsCheckHeapObject(v8::internal::compiler::Node*)
  120013  100.0%    v8::internal::Runtime_CompileOptimized_Concurrent(int, v8::internal::Object**, v8::internal::Isolate*)
  120013  100.0%      LazyCompile: ~entities.forEach /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:2785:18
  120013  100.0%        Builtin: ArrayForEach
  120013  100.0%          Script: ~<anonymous> /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:1:11
  120013  100.0%            LazyCompile: ~Module._compile internal/modules/cjs/loader.js:639:37

  46487   24.1%  v8::internal::compiler::NodeProperties::GetControlInput(v8::internal::compiler::Node*, int)
  46487  100.0%    v8::internal::Runtime_CompileOptimized_Concurrent(int, v8::internal::Object**, v8::internal::Isolate*)
  46487  100.0%      LazyCompile: ~entities.forEach /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:2785:18
  46487  100.0%        Builtin: ArrayForEach
  46487  100.0%          Script: ~<anonymous> /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:1:11
  46487  100.0%            LazyCompile: ~Module._compile internal/modules/cjs/loader.js:639:37

  13024    6.8%  v8::internal::compiler::OperatorProperties::HasContextInput(v8::internal::compiler::Operator const*)
  13024  100.0%    v8::internal::Runtime_CompileOptimized_Concurrent(int, v8::internal::Object**, v8::internal::Isolate*)
  13024  100.0%      LazyCompile: ~entities.forEach /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:2785:18
  13024  100.0%        Builtin: ArrayForEach
  13024  100.0%          Script: ~<anonymous> /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:1:11
  13024  100.0%            LazyCompile: ~Module._compile internal/modules/cjs/loader.js:639:37

  12811    6.6%  v8::internal::compiler::OperatorProperties::HasFrameStateInput(v8::internal::compiler::Operator const*)
  12811  100.0%    v8::internal::Runtime_CompileOptimized_Concurrent(int, v8::internal::Object**, v8::internal::Isolate*)
  12811  100.0%      LazyCompile: ~entities.forEach /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:2785:18
  12811  100.0%        Builtin: ArrayForEach
  12811  100.0%          Script: ~<anonymous> /home/ivan/Downloads/inode27667/nodeJsPerformanceIssue.js:1:11
  12811  100.0%            LazyCompile: ~Module._compile internal/modules/cjs/loader.js:639:37

@hellivan
Copy link
Author

Looks like, it's an issue with Ajv performance in general right now -> epoberezkin/ajv#995

I don't think that it has to do something with AJV library since the extracted validation function in the example script is pure and simple JavaScript code, which even does not have any loops in it. Further also the testing data, an array of 1000 elements that however in fact only consists of two different elements (one failing and one succeeding) cloned multiple times, should be no real problem.
Nevertheless, execution freezes in NodeJS >= 10 (cpu usage of node process at 100% for an indefinite amount of time) even though we are only calling the same function with the same data multiple times.

@bnoordhuis bnoordhuis added the confirmed-bug Issues with confirmed bugs. label May 14, 2019
@bnoordhuis
Copy link
Member

bnoordhuis commented May 14, 2019

Thanks, I see roughly the same thing: exponential behavior in the (edit: tier 2) JS compiler.

NeedsCheckHeapObject() calls itself recursively and it seems to be visiting the same 50 or so phi nodes over and over again. If I hack that function to return true instead of recursing, the script finishes in .1 second.

I'm reasonably sure it was introduced by v8/v8@947101c8734 so cc @bmeurer. That also explains why Node.js v8.x is unaffected because that change was introduced in V8 6.3 and Node.js v8.x ships 6.2.

edit: forgot to mention, V8 ToT seems to be affected as well.

@bmeurer
Copy link
Member

bmeurer commented May 15, 2019

Good catch. I filed v8:9250 upstream.

@bmeurer
Copy link
Member

bmeurer commented May 15, 2019

Consider floating this patch in Node 10 / 12.

bnoordhuis pushed a commit to bnoordhuis/io.js that referenced this issue May 16, 2019
Original commit message:

    Remove recursion from NeedsCheckHeapObject.

    We use the predicate NeedsCheckHeapObject in the compiler frontend to
    determine whether we can skip introducing CheckHeapObject nodes. But
    this predicate would also walk up the graph in case of Phis, which can
    result in really long compilation times (on the main thread). In the
    report in nodejs#27667, the compiler
    frontend alone took around 4-5mins of main thread time for a single
    function. With this patch the time goes down to 4-5ms.

    Bug: v8:9250
    Refs: nodejs#27667
    Change-Id: I231eb780ff04f949fa1669714f9af6ebfbcade05
    Reviewed-on: https://chromium-review.googlesource.com/c/v8/v8/+/1612897
    Reviewed-by: Jaroslav Sevcik <jarin@chromium.org>
    Commit-Queue: Benedikt Meurer <bmeurer@chromium.org>
    Cr-Commit-Position: refs/heads/master@{#61503}

Fixes: nodejs#27667
targos pushed a commit that referenced this issue May 20, 2019
Original commit message:

    Remove recursion from NeedsCheckHeapObject.

    We use the predicate NeedsCheckHeapObject in the compiler frontend to
    determine whether we can skip introducing CheckHeapObject nodes. But
    this predicate would also walk up the graph in case of Phis, which can
    result in really long compilation times (on the main thread). In the
    report in #27667, the compiler
    frontend alone took around 4-5mins of main thread time for a single
    function. With this patch the time goes down to 4-5ms.

    Bug: v8:9250
    Refs: #27667
    Change-Id: I231eb780ff04f949fa1669714f9af6ebfbcade05
    Reviewed-on: https://chromium-review.googlesource.com/c/v8/v8/+/1612897
    Reviewed-by: Jaroslav Sevcik <jarin@chromium.org>
    Commit-Queue: Benedikt Meurer <bmeurer@chromium.org>
    Cr-Commit-Position: refs/heads/master@{#61503}

Fixes: #27667

PR-URL: #27729
Reviewed-By: Michaël Zasso <targos@protonmail.com>
Reviewed-By: Colin Ihrig <cjihrig@gmail.com>
Reviewed-By: Ruben Bridgewater <ruben@bridgewater.de>
Reviewed-By: Rich Trott <rtrott@gmail.com>
Reviewed-By: Michael Dawson <michael_dawson@ca.ibm.com>
Reviewed-By: James M Snell <jasnell@gmail.com>
refack pushed a commit to targos/node that referenced this issue May 22, 2019
Original commit message:

    Remove recursion from NeedsCheckHeapObject.

    We use the predicate NeedsCheckHeapObject in the compiler frontend to
    determine whether we can skip introducing CheckHeapObject nodes. But
    this predicate would also walk up the graph in case of Phis, which can
    result in really long compilation times (on the main thread). In the
    report in nodejs#27667, the compiler
    frontend alone took around 4-5mins of main thread time for a single
    function. With this patch the time goes down to 4-5ms.

    Bug: v8:9250
    Refs: nodejs#27667
    Change-Id: I231eb780ff04f949fa1669714f9af6ebfbcade05
    Reviewed-on: https://chromium-review.googlesource.com/c/v8/v8/+/1612897
    Reviewed-by: Jaroslav Sevcik <jarin@chromium.org>
    Commit-Queue: Benedikt Meurer <bmeurer@chromium.org>
    Cr-Commit-Position: refs/heads/master@{#61503}

Fixes: nodejs#27667

PR-URL: nodejs#27729
Reviewed-By: Michaël Zasso <targos@protonmail.com>
Reviewed-By: Colin Ihrig <cjihrig@gmail.com>
Reviewed-By: Ruben Bridgewater <ruben@bridgewater.de>
Reviewed-By: Rich Trott <rtrott@gmail.com>
Reviewed-By: Michael Dawson <michael_dawson@ca.ibm.com>
Reviewed-By: James M Snell <jasnell@gmail.com>
cjihrig pushed a commit to cjihrig/node that referenced this issue May 23, 2019
Original commit message:

    Remove recursion from NeedsCheckHeapObject.

    We use the predicate NeedsCheckHeapObject in the compiler frontend to
    determine whether we can skip introducing CheckHeapObject nodes. But
    this predicate would also walk up the graph in case of Phis, which can
    result in really long compilation times (on the main thread). In the
    report in nodejs#27667, the compiler
    frontend alone took around 4-5mins of main thread time for a single
    function. With this patch the time goes down to 4-5ms.

    Bug: v8:9250
    Refs: nodejs#27667
    Change-Id: I231eb780ff04f949fa1669714f9af6ebfbcade05
    Reviewed-on: https://chromium-review.googlesource.com/c/v8/v8/+/1612897
    Reviewed-by: Jaroslav Sevcik <jarin@chromium.org>
    Commit-Queue: Benedikt Meurer <bmeurer@chromium.org>
    Cr-Commit-Position: refs/heads/master@{#61503}

Fixes: nodejs#27667

PR-URL: nodejs#27729
Reviewed-By: Michaël Zasso <targos@protonmail.com>
Reviewed-By: Colin Ihrig <cjihrig@gmail.com>
Reviewed-By: Ruben Bridgewater <ruben@bridgewater.de>
Reviewed-By: Rich Trott <rtrott@gmail.com>
Reviewed-By: Michael Dawson <michael_dawson@ca.ibm.com>
Reviewed-By: James M Snell <jasnell@gmail.com>
targos pushed a commit to targos/node that referenced this issue May 26, 2019
Original commit message:

    Remove recursion from NeedsCheckHeapObject.

    We use the predicate NeedsCheckHeapObject in the compiler frontend to
    determine whether we can skip introducing CheckHeapObject nodes. But
    this predicate would also walk up the graph in case of Phis, which can
    result in really long compilation times (on the main thread). In the
    report in nodejs#27667, the compiler
    frontend alone took around 4-5mins of main thread time for a single
    function. With this patch the time goes down to 4-5ms.

    Bug: v8:9250
    Refs: nodejs#27667
    Change-Id: I231eb780ff04f949fa1669714f9af6ebfbcade05
    Reviewed-on: https://chromium-review.googlesource.com/c/v8/v8/+/1612897
    Reviewed-by: Jaroslav Sevcik <jarin@chromium.org>
    Commit-Queue: Benedikt Meurer <bmeurer@chromium.org>
    Cr-Commit-Position: refs/heads/master@{#61503}

Fixes: nodejs#27667

PR-URL: nodejs#27729
Reviewed-By: Michaël Zasso <targos@protonmail.com>
Reviewed-By: Colin Ihrig <cjihrig@gmail.com>
Reviewed-By: Ruben Bridgewater <ruben@bridgewater.de>
Reviewed-By: Rich Trott <rtrott@gmail.com>
Reviewed-By: Michael Dawson <michael_dawson@ca.ibm.com>
Reviewed-By: James M Snell <jasnell@gmail.com>
refack pushed a commit to targos/node that referenced this issue Jun 1, 2019
Original commit message:

    Remove recursion from NeedsCheckHeapObject.

    We use the predicate NeedsCheckHeapObject in the compiler frontend to
    determine whether we can skip introducing CheckHeapObject nodes. But
    this predicate would also walk up the graph in case of Phis, which can
    result in really long compilation times (on the main thread). In the
    report in nodejs#27667, the compiler
    frontend alone took around 4-5mins of main thread time for a single
    function. With this patch the time goes down to 4-5ms.

    Bug: v8:9250
    Refs: nodejs#27667
    Change-Id: I231eb780ff04f949fa1669714f9af6ebfbcade05
    Reviewed-on: https://chromium-review.googlesource.com/c/v8/v8/+/1612897
    Reviewed-by: Jaroslav Sevcik <jarin@chromium.org>
    Commit-Queue: Benedikt Meurer <bmeurer@chromium.org>
    Cr-Commit-Position: refs/heads/master@{#61503}

Fixes: nodejs#27667

PR-URL: nodejs#27729
Reviewed-By: Michaël Zasso <targos@protonmail.com>
Reviewed-By: Colin Ihrig <cjihrig@gmail.com>
Reviewed-By: Ruben Bridgewater <ruben@bridgewater.de>
Reviewed-By: Rich Trott <rtrott@gmail.com>
Reviewed-By: Michael Dawson <michael_dawson@ca.ibm.com>
Reviewed-By: James M Snell <jasnell@gmail.com>
targos pushed a commit to targos/node that referenced this issue Jun 4, 2019
Original commit message:

    Remove recursion from NeedsCheckHeapObject.

    We use the predicate NeedsCheckHeapObject in the compiler frontend to
    determine whether we can skip introducing CheckHeapObject nodes. But
    this predicate would also walk up the graph in case of Phis, which can
    result in really long compilation times (on the main thread). In the
    report in nodejs#27667, the compiler
    frontend alone took around 4-5mins of main thread time for a single
    function. With this patch the time goes down to 4-5ms.

    Bug: v8:9250
    Refs: nodejs#27667
    Change-Id: I231eb780ff04f949fa1669714f9af6ebfbcade05
    Reviewed-on: https://chromium-review.googlesource.com/c/v8/v8/+/1612897
    Reviewed-by: Jaroslav Sevcik <jarin@chromium.org>
    Commit-Queue: Benedikt Meurer <bmeurer@chromium.org>
    Cr-Commit-Position: refs/heads/master@{#61503}

Fixes: nodejs#27667

Backport-PR-URL: nodejs#28005
PR-URL: nodejs#27729
Reviewed-By: Michaël Zasso <targos@protonmail.com>
Reviewed-By: Colin Ihrig <cjihrig@gmail.com>
Reviewed-By: Ruben Bridgewater <ruben@bridgewater.de>
Reviewed-By: Rich Trott <rtrott@gmail.com>
Reviewed-By: Michael Dawson <michael_dawson@ca.ibm.com>
Reviewed-By: James M Snell <jasnell@gmail.com>
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
confirmed-bug Issues with confirmed bugs. performance Issues and PRs related to the performance of Node.js. v8 engine Issues and PRs related to the V8 dependency.
Projects
None yet
Development

Successfully merging a pull request may close this issue.

6 participants