examples/msg/tracing/tasks.tesh

   1 #! ./tesh
   2
   3 p Tracing tasks
   4
   5 $ $SG_TEST_EXENV ${bindir:=.}/tracing/tasks$EXEEXT --cfg=tracing:1 --cfg=tracing/msg/task:1 --cfg=tracing/filename:tracing/tasks.trace ${srcdir:=.}/tracing/platform.xml ${srcdir:=.}/tracing/deployment.xml
   6 > [0.000000] [xbt_cfg/INFO] Configuration change: Set 'tracing' to '1'
   7 > [0.000000] [xbt_cfg/INFO] Configuration change: Set 'tracing/msg/task' to '1'
   8 > [0.000000] [xbt_cfg/INFO] Configuration change: Set 'tracing/filename' to 'tracing/tasks.trace'
   9 > [Tremblay:master:(1) 0.000000] [msg_test/INFO] master 20 50000000.000000 1000000.000000 5
  10 > [Tremblay:slave:(2) 0.002339] [msg_test/INFO] Executing task 50000000.000000
  11 > [Jupiter:slave:(3) 0.168300] [msg_test/INFO] Executing task 50000000.000000
  12 > [Fafard:slave:(4) 0.386454] [msg_test/INFO] Executing task 50000000.000000
  13 > [Tremblay:slave:(2) 0.512049] [msg_test/INFO] End of execution
  14 > [Ginette:slave:(5) 0.526913] [msg_test/INFO] Executing task 50000000.000000
  15 > [Bourassa:slave:(6) 0.742786] [msg_test/INFO] Executing task 50000000.000000
  16 > [Tremblay:slave:(2) 0.745124] [msg_test/INFO] Executing task 50000000.000000
  17 > [Jupiter:slave:(3) 0.823643] [msg_test/INFO] End of execution
  18 > [Jupiter:slave:(3) 0.989605] [msg_test/INFO] Executing task 50000000.000000
  19 > [Fafard:slave:(4) 1.041796] [msg_test/INFO] End of execution
  20 > [Tremblay:slave:(2) 1.254834] [msg_test/INFO] End of execution
  21 > [Fafard:slave:(4) 1.259949] [msg_test/INFO] Executing task 50000000.000000
  22 > [Tremblay:slave:(2) 1.262288] [msg_test/INFO] Executing task 50000000.000000
  23 > [Ginette:slave:(5) 1.558011] [msg_test/INFO] End of execution
  24 > [Jupiter:slave:(3) 1.644947] [msg_test/INFO] End of execution
  25 > [Ginette:slave:(5) 1.698471] [msg_test/INFO] Executing task 50000000.000000
  26 > [Tremblay:slave:(2) 1.771998] [msg_test/INFO] End of execution
  27 > [Bourassa:slave:(6) 1.773883] [msg_test/INFO] End of execution
  28 > [Jupiter:slave:(3) 1.864433] [msg_test/INFO] Executing task 50000000.000000
  29 > [Tremblay:slave:(2) 1.866771] [msg_test/INFO] Executing task 50000000.000000
  30 > [Fafard:slave:(4) 1.915291] [msg_test/INFO] End of execution
  31 > [Bourassa:slave:(6) 2.082643] [msg_test/INFO] Executing task 50000000.000000
  32 > [Fafard:slave:(4) 2.300797] [msg_test/INFO] Executing task 50000000.000000
  33 > [Tremblay:slave:(2) 2.376481] [msg_test/INFO] End of execution
  34 > [Tremblay:slave:(2) 2.378820] [msg_test/INFO] Executing task 50000000.000000
  35 > [Jupiter:slave:(3) 2.519775] [msg_test/INFO] End of execution
  36 > [Jupiter:slave:(3) 2.685737] [msg_test/INFO] Executing task 50000000.000000
  37 > [Ginette:slave:(5) 2.729569] [msg_test/INFO] End of execution
  38 > [Ginette:slave:(5) 2.870028] [msg_test/INFO] Executing task 50000000.000000
  39 > [Tremblay:slave:(2) 2.888530] [msg_test/INFO] End of execution
  40 > [Tremblay:slave:(2) 2.890868] [msg_test/INFO] Executing task 50000000.000000
  41 > [Fafard:slave:(4) 2.956139] [msg_test/INFO] End of execution
  42 > [Bourassa:slave:(6) 3.113741] [msg_test/INFO] End of execution
  43 > [Fafard:slave:(4) 3.174292] [msg_test/INFO] Executing task 50000000.000000
  44 > [Jupiter:slave:(3) 3.341079] [msg_test/INFO] End of execution
  45 > [Bourassa:slave:(6) 3.390164] [msg_test/INFO] Executing task 50000000.000000
  46 > [Tremblay:slave:(2) 3.400578] [msg_test/INFO] End of execution
  47 > [Fafard:slave:(4) 3.829634] [msg_test/INFO] End of execution
  48 > [Ginette:slave:(5) 3.901126] [msg_test/INFO] End of execution
  49 > [Bourassa:slave:(6) 4.421262] [msg_test/INFO] End of execution
  50 > [4.441598] [msg_test/INFO] Simulation time 4.4416
  51
  52 $ cat tracing/tasks.trace
  53 > %EventDef PajeDefineContainerType 0
  54 > %       Alias string
  55 > %       ContainerType string
  56 > %       Name string
  57 > %EndEventDef
  58 > %EventDef PajeDefineVariableType 1
  59 > %       Alias string
  60 > %       ContainerType string
  61 > %       Name string
  62 > %       Color color
  63 > %EndEventDef
  64 > %EventDef PajeDefineStateType 2
  65 > %       Alias string
  66 > %       ContainerType string
  67 > %       Name string
  68 > %EndEventDef
  69 > %EventDef PajeDefineEventType 3
  70 > %       Alias string
  71 > %       EntityType string
  72 > %       Name string
  73 > %       Color color
  74 > %EndEventDef
  75 > %EventDef PajeDefineLinkType 4
  76 > %       Alias string
  77 > %       ContainerType string
  78 > %       SourceContainerType string
  79 > %       DestContainerType string
  80 > %       Name string
  81 > %EndEventDef
  82 > %EventDef PajeCreateContainer 5
  83 > %       Time date
  84 > %       Alias string
  85 > %       Type string
  86 > %       Container string
  87 > %       Name string
  88 > %EndEventDef
  89 > %EventDef PajeDestroyContainer 6
  90 > %       Time date
  91 > %       Type string
  92 > %       Container string
  93 > %EndEventDef
  94 > %EventDef PajeSetVariable 7
  95 > %       Time date
  96 > %       EntityType string
  97 > %       Container string
  98 > %       Value string
  99 > %EndEventDef
 100 > %EventDef PajeAddVariable 8
 101 > %       Time date
 102 > %       EntityType string
 103 > %       Container string
 104 > %       Value string
 105 > %EndEventDef
 106 > %EventDef PajeSubVariable 9
 107 > %       Time date
 108 > %       EntityType string
 109 > %       Container string
 110 > %       Value string
 111 > %EndEventDef
 112 > %EventDef PajeSetState 10
 113 > %       Time date
 114 > %       EntityType string
 115 > %       Container string
 116 > %       Value string
 117 > %EndEventDef
 118 > %EventDef PajePushState 11
 119 > %       Time date
 120 > %       EntityType string
 121 > %       Container string
 122 > %       Value string
 123 > %EndEventDef
 124 > %EventDef PajePopState 12
 125 > %       Time date
 126 > %       EntityType string
 127 > %       Container string
 128 > %EndEventDef
 129 > %EventDef PajeStartLink 13
 130 > %       Time date
 131 > %       EntityType string
 132 > %       Container string
 133 > %       Value string
 134 > %       SourceContainer string
 135 > %       Key string
 136 > %EndEventDef
 137 > %EventDef PajeEndLink 14
 138 > %       Time date
 139 > %       EntityType string
 140 > %       Container string
 141 > %       Value string
 142 > %       DestContainer string
 143 > %       Key string
 144 > %EndEventDef
 145 > %EventDef PajeNewEvent 15
 146 > %       Time date
 147 > %       EntityType string
 148 > %       Container string
 149 > %       Value string
 150 > %EndEventDef
 151 > 0 1 0 L1
 152 > 5 0 1 1 0 AS0
 153 > 0 2 1 HOST
 154 > 5 0 2 2 1 Tremblay
 155 > 1 3 2 power "1 1 1"
 156 > 0 4 2 MSG_TASK
 157 > 2 5 4 MSG_TASK_STATE
 158 > 4 6 0 4 4 MSG_TASK_LINK
 159 > 5 0 3 2 1 Jupiter
 160 > 5 0 4 2 1 Fafard
 161 > 5 0 5 2 1 Ginette
 162 > 5 0 6 2 1 Bourassa
 163 > 0 7 1 ROUTER
 164 > 5 0 7 7 1 R.4-3
 165 > 5 0 8 7 1 R.3-2-5
 166 > 5 0 9 7 1 R.2-0
 167 > 5 0 10 7 1 R.1-0
 168 > 5 0 11 7 1 R.1-8-6
 169 > 5 0 12 7 1 R.6-7
 170 > 0 8 1 LINK
 171 > 5 0 13 8 1 6
 172 > 1 9 8 bandwidth "1 1 1"
 173 > 1 10 8 latency "1 1 1"
 174 > 5 0 14 8 1 11
 175 > 5 0 15 8 1 3
 176 > 5 0 16 8 1 7
 177 > 5 0 17 8 1 9
 178 > 5 0 18 8 1 12
 179 > 5 0 19 8 1 2
 180 > 5 0 20 8 1 8
 181 > 5 0 21 8 1 1
 182 > 5 0 22 8 1 4
 183 > 5 0 23 8 1 0
 184 > 5 0 24 8 1 10
 185 > 5 0 25 8 1 5
 186 > 5 0 26 8 1 loopback
 187 > 4 11 1 2 8 HOST-LINK
 188 > 4 12 1 8 2 LINK-HOST
 189 > 4 13 1 8 7 LINK-ROUTER
 190 > 4 14 1 8 8 LINK-LINK
 191 > 4 15 1 7 8 ROUTER-LINK
 192 > 7 0 3 2 98095000.000000
 193 > 7 0 3 3 76296000.000000
 194 > 7 0 3 4 76296000.000000
 195 > 7 0 3 5 48492000.000000
 196 > 7 0 3 6 48492000.000000
 197 > 7 0 9 13 41279125.000000
 198 > 7 0 10 13 0.000060
 199 > 7 0 9 14 252750.000000
 200 > 7 0 10 14 0.005705
 201 > 7 0 9 15 34285622.000000
 202 > 7 0 10 15 0.000514
 203 > 7 0 9 16 11618875.000000
 204 > 7 0 10 16 0.000190
 205 > 7 0 9 17 7209750.000000
 206 > 7 0 10 17 0.001462
 207 > 7 0 9 18 1792625.000000
 208 > 7 0 10 18 0.007878
 209 > 7 0 9 19 22222222.000000
 210 > 7 0 10 19 0.000137
 211 > 7 0 9 20 8158000.000000
 212 > 7 0 10 20 0.000271
 213 > 7 0 9 21 34285625.000000
 214 > 7 0 10 21 0.000514
 215 > 7 0 9 22 10099625.000000
 216 > 7 0 10 22 0.000480
 217 > 7 0 9 23 41279125.000000
 218 > 7 0 10 23 0.000060
 219 > 7 0 9 24 4679750.000000
 220 > 7 0 10 24 0.000849
 221 > 7 0 9 25 27946250.000000
 222 > 7 0 10 25 0.000278
 223 > 7 0 9 26 498000000.000000
 224 > 7 0 10 26 0.000015
 225 > 13 0 11 1 G 4 0
 226 > 14 0 11 1 G 26 0
 227 > 13 0 12 1 G 26 1
 228 > 14 0 12 1 G 4 1
 229 > 13 0 11 1 G 4 2
 230 > 14 0 11 1 G 20 2
 231 > 13 0 13 1 G 20 3
 232 > 14 0 13 1 G 11 3
 233 > 13 0 11 1 G 4 4
 234 > 14 0 11 1 G 20 4
 235 > 13 0 14 1 G 20 5
 236 > 14 0 14 1 G 21 5
 237 > 13 0 14 1 G 21 6
 238 > 14 0 14 1 G 23 6
 239 > 13 0 14 1 G 23 7
 240 > 14 0 14 1 G 19 7
 241 > 13 0 14 1 G 19 8
 242 > 14 0 14 1 G 15 8
 243 > 13 0 14 1 G 15 9
 244 > 14 0 14 1 G 22 9
 245 > 13 0 12 1 G 22 10
 246 > 14 0 12 1 G 2 10
 247 > 13 0 11 1 G 4 11
 248 > 14 0 11 1 G 20 11
 249 > 13 0 14 1 G 20 12
 250 > 14 0 14 1 G 13 12
 251 > 13 0 14 1 G 13 13
 252 > 14 0 14 1 G 16 13
 253 > 13 0 12 1 G 16 14
 254 > 14 0 12 1 G 6 14
 255 > 13 0 11 1 G 4 15
 256 > 14 0 11 1 G 20 15
 257 > 13 0 14 1 G 20 16
 258 > 14 0 14 1 G 21 16
 259 > 13 0 14 1 G 21 17
 260 > 14 0 14 1 G 23 17
 261 > 13 0 14 1 G 23 18
 262 > 14 0 14 1 G 19 18
 263 > 13 0 14 1 G 19 19
 264 > 14 0 14 1 G 15 19
 265 > 13 0 14 1 G 15 20
 266 > 14 0 14 1 G 22 20
 267 > 13 0 14 1 G 22 21
 268 > 14 0 14 1 G 17 21
 269 > 13 0 12 1 G 17 22
 270 > 14 0 12 1 G 3 22
 271 > 13 0 11 1 G 4 23
 272 > 14 0 11 1 G 20 23
 273 > 13 0 14 1 G 20 24
 274 > 14 0 14 1 G 21 24
 275 > 13 0 14 1 G 21 25
 276 > 14 0 14 1 G 23 25
 277 > 13 0 14 1 G 23 26
 278 > 14 0 14 1 G 19 26
 279 > 13 0 14 1 G 19 27
 280 > 14 0 14 1 G 25 27
 281 > 13 0 12 1 G 25 28
 282 > 14 0 12 1 G 5 28
 283 > 13 0 15 1 G 9 29
 284 > 14 0 15 1 G 19 29
 285 > 13 0 13 1 G 19 30
 286 > 14 0 13 1 G 8 30
 287 > 13 0 15 1 G 9 31
 288 > 14 0 15 1 G 23 31
 289 > 13 0 13 1 G 23 32
 290 > 14 0 13 1 G 10 32
 291 > 13 0 15 1 G 7 33
 292 > 14 0 15 1 G 15 33
 293 > 13 0 13 1 G 15 34
 294 > 14 0 13 1 G 8 34
 295 > 13 0 15 1 G 7 35
 296 > 14 0 15 1 G 22 35
 297 > 13 0 12 1 G 22 36
 298 > 14 0 12 1 G 2 36
 299 > 13 0 15 1 G 8 37
 300 > 14 0 15 1 G 25 37
 301 > 13 0 12 1 G 25 38
 302 > 14 0 12 1 G 5 38
 303 > 13 0 15 1 G 11 39
 304 > 14 0 15 1 G 13 39
 305 > 13 0 13 1 G 13 40
 306 > 14 0 13 1 G 12 40
 307 > 13 0 15 1 G 11 41
 308 > 14 0 15 1 G 21 41
 309 > 13 0 13 1 G 21 42
 310 > 14 0 13 1 G 10 42
 311 > 13 0 15 1 G 12 43
 312 > 14 0 15 1 G 16 43
 313 > 13 0 12 1 G 16 44
 314 > 14 0 12 1 G 6 44
 315 > 13 0 11 1 G 2 45
 316 > 14 0 11 1 G 26 45
 317 > 13 0 12 1 G 26 46
 318 > 14 0 12 1 G 2 46
 319 > 13 0 11 1 G 2 47
 320 > 14 0 11 1 G 22 47
 321 > 13 0 14 1 G 22 48
 322 > 14 0 14 1 G 15 48
 323 > 13 0 14 1 G 15 49
 324 > 14 0 14 1 G 19 49
 325 > 13 0 14 1 G 19 50
 326 > 14 0 14 1 G 23 50
 327 > 13 0 14 1 G 23 51
 328 > 14 0 14 1 G 21 51
 329 > 13 0 14 1 G 21 52
 330 > 14 0 14 1 G 13 52
 331 > 13 0 14 1 G 13 53
 332 > 14 0 14 1 G 16 53
 333 > 13 0 12 1 G 16 54
 334 > 14 0 12 1 G 6 54
 335 > 13 0 11 1 G 2 55
 336 > 14 0 11 1 G 17 55
 337 > 13 0 12 1 G 17 56
 338 > 14 0 12 1 G 3 56
 339 > 13 0 11 1 G 2 57
 340 > 14 0 11 1 G 22 57
 341 > 13 0 14 1 G 22 58
 342 > 14 0 14 1 G 15 58
 343 > 13 0 14 1 G 15 59
 344 > 14 0 14 1 G 25 59
 345 > 13 0 12 1 G 25 60
 346 > 14 0 12 1 G 5 60
 347 > 13 0 11 1 G 6 61
 348 > 14 0 11 1 G 26 61
 349 > 13 0 12 1 G 26 62
 350 > 14 0 12 1 G 6 62
 351 > 13 0 11 1 G 6 63
 352 > 14 0 11 1 G 16 63
 353 > 13 0 14 1 G 16 64
 354 > 14 0 14 1 G 13 64
 355 > 13 0 14 1 G 13 65
 356 > 14 0 14 1 G 21 65
 357 > 13 0 14 1 G 21 66
 358 > 14 0 14 1 G 23 66
 359 > 13 0 14 1 G 23 67
 360 > 14 0 14 1 G 19 67
 361 > 13 0 14 1 G 19 68
 362 > 14 0 14 1 G 15 68
 363 > 13 0 14 1 G 15 69
 364 > 14 0 14 1 G 22 69
 365 > 13 0 14 1 G 22 70
 366 > 14 0 14 1 G 17 70
 367 > 13 0 12 1 G 17 71
 368 > 14 0 12 1 G 3 71
 369 > 13 0 11 1 G 6 72
 370 > 14 0 11 1 G 16 72
 371 > 13 0 14 1 G 16 73
 372 > 14 0 14 1 G 13 73
 373 > 13 0 14 1 G 13 74
 374 > 14 0 14 1 G 21 74
 375 > 13 0 14 1 G 21 75
 376 > 14 0 14 1 G 23 75
 377 > 13 0 14 1 G 23 76
 378 > 14 0 14 1 G 19 76
 379 > 13 0 14 1 G 19 77
 380 > 14 0 14 1 G 25 77
 381 > 13 0 12 1 G 25 78
 382 > 14 0 12 1 G 5 78
 383 > 13 0 11 1 G 3 79
 384 > 14 0 11 1 G 26 79
 385 > 13 0 12 1 G 26 80
 386 > 14 0 12 1 G 3 80
 387 > 13 0 11 1 G 3 81
 388 > 14 0 11 1 G 17 81
 389 > 13 0 14 1 G 17 82
 390 > 14 0 14 1 G 22 82
 391 > 13 0 14 1 G 22 83
 392 > 14 0 14 1 G 15 83
 393 > 13 0 14 1 G 15 84
 394 > 14 0 14 1 G 25 84
 395 > 13 0 12 1 G 25 85
 396 > 14 0 12 1 G 5 85
 397 > 13 0 11 1 G 5 86
 398 > 14 0 11 1 G 26 86
 399 > 13 0 12 1 G 26 87
 400 > 14 0 12 1 G 5 87
 401 > 5 0 27 4 2 task-0
 402 > 1 16 4 compute "1 1 1"
 403 > 7 0 16 27 1.000000
 404 > 11 0 5 27 created
 405 > 12 0 5 27
 406 > 13 0 6 0 SR 27 0
 407 > 6 0 4 27
 408 > 5 0.002339 28 4 2 task-1
 409 > 5 0.002339 29 4 2 task-0
 410 > 7 0.002339 16 28 1.000000
 411 > 11 0.002339 5 28 created
 412 > 7 0.002339 16 29 1.000000
 413 > 11 0.002339 5 29 created
 414 > 14 0.002339 6 0 SR 29 0
 415 > 11 0.002339 5 29 MSG_task_execute
 416 > 12 0.002339 5 28
 417 > 13 0.002339 6 0 SR 28 1
 418 > 6 0.002339 4 28
 419 > 5 0.168300 30 4 2 task-2
 420 > 5 0.168300 31 4 3 task-1
 421 > 7 0.168300 16 30 1.000000
 422 > 11 0.168300 5 30 created
 423 > 7 0.168300 16 31 1.000000
 424 > 11 0.168300 5 31 created
 425 > 14 0.168300 6 0 SR 31 1
 426 > 11 0.168300 5 31 MSG_task_execute
 427 > 12 0.168300 5 30
 428 > 13 0.168300 6 0 SR 30 2
 429 > 6 0.168300 4 30
 430 > 5 0.386454 32 4 2 task-3
 431 > 5 0.386454 33 4 4 task-2
 432 > 7 0.386454 16 32 1.000000
 433 > 11 0.386454 5 32 created
 434 > 7 0.386454 16 33 1.000000
 435 > 11 0.386454 5 33 created
 436 > 14 0.386454 6 0 SR 33 2
 437 > 11 0.386454 5 33 MSG_task_execute
 438 > 12 0.386454 5 32
 439 > 13 0.386454 6 0 SR 32 3
 440 > 6 0.386454 4 32
 441 > 12 0.512049 5 29
 442 > 6 0.512049 4 29
 443 > 5 0.526913 34 4 2 task-4
 444 > 5 0.526913 35 4 5 task-3
 445 > 7 0.526913 16 34 1.000000
 446 > 11 0.526913 5 34 created
 447 > 7 0.526913 16 35 1.000000
 448 > 11 0.526913 5 35 created
 449 > 14 0.526913 6 0 SR 35 3
 450 > 11 0.526913 5 35 MSG_task_execute
 451 > 12 0.526913 5 34
 452 > 13 0.526913 6 0 SR 34 4
 453 > 6 0.526913 4 34
 454 > 5 0.742786 36 4 2 task-5
 455 > 5 0.742786 37 4 6 task-4
 456 > 7 0.742786 16 36 1.000000
 457 > 11 0.742786 5 36 created
 458 > 7 0.742786 16 37 1.000000
 459 > 11 0.742786 5 37 created
 460 > 14 0.742786 6 0 SR 37 4
 461 > 11 0.742786 5 37 MSG_task_execute
 462 > 12 0.742786 5 36
 463 > 13 0.742786 6 0 SR 36 5
 464 > 6 0.742786 4 36
 465 > 5 0.745124 38 4 2 task-6
 466 > 5 0.745124 39 4 2 task-5
 467 > 7 0.745124 16 38 1.000000
 468 > 11 0.745124 5 38 created
 469 > 7 0.745124 16 39 1.000000
 470 > 11 0.745124 5 39 created
 471 > 14 0.745124 6 0 SR 39 5
 472 > 11 0.745124 5 39 MSG_task_execute
 473 > 12 0.745124 5 38
 474 > 13 0.745124 6 0 SR 38 6
 475 > 6 0.745124 4 38
 476 > 12 0.823643 5 31
 477 > 6 0.823643 4 31
 478 > 5 0.989605 40 4 2 task-7
 479 > 5 0.989605 41 4 3 task-6
 480 > 7 0.989605 16 40 1.000000
 481 > 11 0.989605 5 40 created
 482 > 7 0.989605 16 41 1.000000
 483 > 11 0.989605 5 41 created
 484 > 14 0.989605 6 0 SR 41 6
 485 > 11 0.989605 5 41 MSG_task_execute
 486 > 12 0.989605 5 40
 487 > 13 0.989605 6 0 SR 40 7
 488 > 6 0.989605 4 40
 489 > 12 1.041796 5 33
 490 > 6 1.041796 4 33
 491 > 12 1.254834 5 39
 492 > 6 1.254834 4 39
 493 > 5 1.259949 42 4 2 task-8
 494 > 5 1.259949 43 4 4 task-7
 495 > 7 1.259949 16 42 1.000000
 496 > 11 1.259949 5 42 created
 497 > 7 1.259949 16 43 1.000000
 498 > 11 1.259949 5 43 created
 499 > 14 1.259949 6 0 SR 43 7
 500 > 11 1.259949 5 43 MSG_task_execute
 501 > 12 1.259949 5 42
 502 > 13 1.259949 6 0 SR 42 8
 503 > 6 1.259949 4 42
 504 > 5 1.262288 44 4 2 task-9
 505 > 5 1.262288 45 4 2 task-8
 506 > 7 1.262288 16 44 1.000000
 507 > 11 1.262288 5 44 created
 508 > 7 1.262288 16 45 1.000000
 509 > 11 1.262288 5 45 created
 510 > 14 1.262288 6 0 SR 45 8
 511 > 11 1.262288 5 45 MSG_task_execute
 512 > 12 1.262288 5 44
 513 > 13 1.262288 6 0 SR 44 9
 514 > 6 1.262288 4 44
 515 > 12 1.558011 5 35
 516 > 6 1.558011 4 35
 517 > 12 1.644947 5 41
 518 > 6 1.644947 4 41
 519 > 5 1.698471 46 4 2 task-10
 520 > 5 1.698471 47 4 5 task-9
 521 > 7 1.698471 16 46 1.000000
 522 > 11 1.698471 5 46 created
 523 > 7 1.698471 16 47 1.000000
 524 > 11 1.698471 5 47 created
 525 > 14 1.698471 6 0 SR 47 9
 526 > 11 1.698471 5 47 MSG_task_execute
 527 > 12 1.698471 5 46
 528 > 13 1.698471 6 0 SR 46 10
 529 > 6 1.698471 4 46
 530 > 12 1.771998 5 45
 531 > 6 1.771998 4 45
 532 > 12 1.773883 5 37
 533 > 6 1.773883 4 37
 534 > 5 1.864433 48 4 2 task-11
 535 > 5 1.864433 49 4 3 task-10
 536 > 7 1.864433 16 48 1.000000
 537 > 11 1.864433 5 48 created
 538 > 7 1.864433 16 49 1.000000
 539 > 11 1.864433 5 49 created
 540 > 14 1.864433 6 0 SR 49 10
 541 > 11 1.864433 5 49 MSG_task_execute
 542 > 12 1.864433 5 48
 543 > 13 1.864433 6 0 SR 48 11
 544 > 6 1.864433 4 48
 545 > 5 1.866771 50 4 2 task-12
 546 > 5 1.866771 51 4 2 task-11
 547 > 7 1.866771 16 50 1.000000
 548 > 11 1.866771 5 50 created
 549 > 7 1.866771 16 51 1.000000
 550 > 11 1.866771 5 51 created
 551 > 14 1.866771 6 0 SR 51 11
 552 > 11 1.866771 5 51 MSG_task_execute
 553 > 12 1.866771 5 50
 554 > 13 1.866771 6 0 SR 50 12
 555 > 6 1.866771 4 50
 556 > 12 1.915291 5 43
 557 > 6 1.915291 4 43
 558 > 5 2.082643 52 4 2 task-13
 559 > 5 2.082643 53 4 6 task-12
 560 > 7 2.082643 16 52 1.000000
 561 > 11 2.082643 5 52 created
 562 > 7 2.082643 16 53 1.000000
 563 > 11 2.082643 5 53 created
 564 > 14 2.082643 6 0 SR 53 12
 565 > 11 2.082643 5 53 MSG_task_execute
 566 > 12 2.082643 5 52
 567 > 13 2.082643 6 0 SR 52 13
 568 > 6 2.082643 4 52
 569 > 5 2.300797 54 4 2 task-14
 570 > 5 2.300797 55 4 4 task-13
 571 > 7 2.300797 16 54 1.000000
 572 > 11 2.300797 5 54 created
 573 > 7 2.300797 16 55 1.000000
 574 > 11 2.300797 5 55 created
 575 > 14 2.300797 6 0 SR 55 13
 576 > 11 2.300797 5 55 MSG_task_execute
 577 > 12 2.300797 5 54
 578 > 13 2.300797 6 0 SR 54 14
 579 > 6 2.300797 4 54
 580 > 12 2.376481 5 51
 581 > 6 2.376481 4 51
 582 > 5 2.378820 56 4 2 task-15
 583 > 5 2.378820 57 4 2 task-14
 584 > 7 2.378820 16 56 1.000000
 585 > 11 2.378820 5 56 created
 586 > 7 2.378820 16 57 1.000000
 587 > 11 2.378820 5 57 created
 588 > 14 2.378820 6 0 SR 57 14
 589 > 11 2.378820 5 57 MSG_task_execute
 590 > 12 2.378820 5 56
 591 > 13 2.378820 6 0 SR 56 15
 592 > 6 2.378820 4 56
 593 > 12 2.519775 5 49
 594 > 6 2.519775 4 49
 595 > 5 2.685737 58 4 2 task-16
 596 > 5 2.685737 59 4 3 task-15
 597 > 7 2.685737 16 58 1.000000
 598 > 11 2.685737 5 58 created
 599 > 7 2.685737 16 59 1.000000
 600 > 11 2.685737 5 59 created
 601 > 14 2.685737 6 0 SR 59 15
 602 > 11 2.685737 5 59 MSG_task_execute
 603 > 12 2.685737 5 58
 604 > 13 2.685737 6 0 SR 58 16
 605 > 6 2.685737 4 58
 606 > 12 2.729569 5 47
 607 > 6 2.729569 4 47
 608 > 5 2.870028 60 4 2 task-17
 609 > 5 2.870028 61 4 5 task-16
 610 > 7 2.870028 16 60 1.000000
 611 > 11 2.870028 5 60 created
 612 > 7 2.870028 16 61 1.000000
 613 > 11 2.870028 5 61 created
 614 > 14 2.870028 6 0 SR 61 16
 615 > 11 2.870028 5 61 MSG_task_execute
 616 > 12 2.870028 5 60
 617 > 13 2.870028 6 0 SR 60 17
 618 > 6 2.870028 4 60
 619 > 12 2.888530 5 57
 620 > 6 2.888530 4 57
 621 > 5 2.890868 62 4 2 task-18
 622 > 5 2.890868 63 4 2 task-17
 623 > 7 2.890868 16 62 1.000000
 624 > 11 2.890868 5 62 created
 625 > 7 2.890868 16 63 1.000000
 626 > 11 2.890868 5 63 created
 627 > 14 2.890868 6 0 SR 63 17
 628 > 11 2.890868 5 63 MSG_task_execute
 629 > 12 2.890868 5 62
 630 > 13 2.890868 6 0 SR 62 18
 631 > 6 2.890868 4 62
 632 > 12 2.956139 5 55
 633 > 6 2.956139 4 55
 634 > 12 3.113741 5 53
 635 > 6 3.113741 4 53
 636 > 5 3.174292 64 4 2 task-19
 637 > 5 3.174292 65 4 4 task-18
 638 > 7 3.174292 16 64 1.000000
 639 > 11 3.174292 5 64 created
 640 > 7 3.174292 16 65 1.000000
 641 > 11 3.174292 5 65 created
 642 > 14 3.174292 6 0 SR 65 18
 643 > 11 3.174292 5 65 MSG_task_execute
 644 > 12 3.174292 5 64
 645 > 13 3.174292 6 0 SR 64 19
 646 > 6 3.174292 4 64
 647 > 12 3.341079 5 59
 648 > 6 3.341079 4 59
 649 > 5 3.390164 66 4 2 task-0
 650 > 1 17 4 finalize "1 1 1"
 651 > 5 3.390164 67 4 6 task-19
 652 > 7 3.390164 17 66 1.000000
 653 > 11 3.390164 5 66 created
 654 > 7 3.390164 16 67 1.000000
 655 > 11 3.390164 5 67 created
 656 > 14 3.390164 6 0 SR 67 19
 657 > 11 3.390164 5 67 MSG_task_execute
 658 > 12 3.390164 5 66
 659 > 13 3.390164 6 0 SR 66 20
 660 > 6 3.390164 4 66
 661 > 12 3.400578 5 63
 662 > 6 3.400578 4 63
 663 > 5 3.405364 68 4 2 task-1
 664 > 5 3.405364 69 4 3 task-0
 665 > 7 3.405364 17 68 1.000000
 666 > 11 3.405364 5 68 created
 667 > 7 3.405364 17 69 1.000000
 668 > 11 3.405364 5 69 created
 669 > 14 3.405364 6 0 SR 69 20
 670 > 6 3.405364 4 69
 671 > 12 3.405364 5 68
 672 > 13 3.405364 6 0 SR 68 21
 673 > 6 3.405364 4 68
 674 > 5 3.405520 70 4 2 task-2
 675 > 5 3.405520 71 4 2 task-1
 676 > 7 3.405520 17 70 1.000000
 677 > 11 3.405520 5 70 created
 678 > 7 3.405520 17 71 1.000000
 679 > 11 3.405520 5 71 created
 680 > 14 3.405520 6 0 SR 71 21
 681 > 6 3.405520 4 71
 682 > 12 3.405520 5 70
 683 > 13 3.405520 6 0 SR 70 22
 684 > 6 3.405520 4 70
 685 > 12 3.829634 5 65
 686 > 6 3.829634 4 65
 687 > 5 3.850185 72 4 2 task-3
 688 > 5 3.850185 73 4 4 task-2
 689 > 7 3.850185 17 72 1.000000
 690 > 11 3.850185 5 72 created
 691 > 7 3.850185 17 73 1.000000
 692 > 11 3.850185 5 73 created
 693 > 14 3.850185 6 0 SR 73 22
 694 > 6 3.850185 4 73
 695 > 12 3.850185 5 72
 696 > 13 3.850185 6 0 SR 72 23
 697 > 6 3.850185 4 72
 698 > 12 3.901126 5 61
 699 > 6 3.901126 4 61
 700 > 5 3.914358 74 4 2 task-4
 701 > 5 3.914358 75 4 5 task-3
 702 > 7 3.914358 17 74 1.000000
 703 > 11 3.914358 5 74 created
 704 > 7 3.914358 17 75 1.000000
 705 > 11 3.914358 5 75 created
 706 > 14 3.914358 6 0 SR 75 23
 707 > 6 3.914358 4 75
 708 > 12 3.914358 5 74
 709 > 13 3.914358 6 0 SR 74 24
 710 > 6 3.914358 4 74
 711 > 12 4.421262 5 67
 712 > 6 4.421262 4 67
 713 > 5 4.441598 76 4 6 task-4
 714 > 7 4.441598 17 76 1.000000
 715 > 11 4.441598 5 76 created
 716 > 14 4.441598 6 0 SR 76 24
 717 > 6 4.441598 4 76
 718 > 6 4.441598 2 4
 719 > 6 4.441598 7 9
 720 > 6 4.441598 7 7
 721 > 6 4.441598 7 8
 722 > 6 4.441598 7 11
 723 > 6 4.441598 7 12
 724 > 6 4.441598 2 2
 725 > 6 4.441598 8 24
 726 > 6 4.441598 8 14
 727 > 6 4.441598 8 18
 728 > 6 4.441598 8 26
 729 > 6 4.441598 7 10
 730 > 6 4.441598 8 23
 731 > 6 4.441598 8 21
 732 > 6 4.441598 8 19
 733 > 6 4.441598 8 15
 734 > 6 4.441598 8 22
 735 > 6 4.441598 8 25
 736 > 6 4.441598 8 13
 737 > 6 4.441598 8 16
 738 > 6 4.441598 8 20
 739 > 6 4.441598 8 17
 740 > 6 4.441598 2 6
 741 > 6 4.441598 2 3
 742 > 6 4.441598 2 5
 743 > 6 4.441598 1 1
 744 > 6 4.441598 0 0
 745
 746 $ rm -rf tracing/tasks.trace