formal-model/urcu-controldataflow-alpha-ipi-progress-minimal/urcu_progress_writer_error.log

   1 make[1]: Entering directory `/home/compudj/doc/userspace-rcu/formal-model/urcu-controldataflow-min-progress'
   2 rm -f pan* trail.out .input.spin* *.spin.trail .input.define
   3 touch .input.define
   4 cat .input.define > pan.ltl
   5 cat DEFINES >> pan.ltl
   6 spin -f "!(`cat urcu_progress.ltl | grep -v ^//`)" >> pan.ltl
   7 cp urcu_progress_writer_error.define .input.define
   8 cat .input.define > .input.spin
   9 cat DEFINES >> .input.spin
  10 cat urcu.spin >> .input.spin
  11 rm -f .input.spin.trail
  12 spin -a -X -N pan.ltl .input.spin
  13 Exit-Status 0
  14 gcc -O2 -w -DHASH64 -DCOLLAPSE -o pan pan.c
  15 ./pan -a -f -v -c1 -X -m10000000 -w20
  16 warning: for p.o. reduction to be valid the never claim must be stutter-invariant
  17 (never claims generated from LTL formulae are stutter-invariant)
  18 depth 0: Claim reached state 5 (line 1180)
  19 depth 7: Claim reached state 9 (line 1185)
  20 depth 46: Claim reached state 9 (line 1184)
  21 pan: acceptance cycle (at depth 3798)
  22 pan: wrote .input.spin.trail
  23
  24 (Spin Version 5.1.7 -- 23 December 2008)
  25 Warning: Search not completed
  26         + Partial Order Reduction
  27         + Compression
  28
  29 Full statespace search for:
  30         never claim             +
  31         assertion violations    + (if within scope of claim)
  32         acceptance   cycles     + (fairness enabled)
  33         invalid end states      - (disabled by never claim)
  34
  35 State-vector 80 byte, depth reached 3956, errors: 1
  36    139740 states, stored (233412 visited)
  37  43234612 states, matched
  38  43468024 transitions (= visited+matched)
  39 2.3201732e+08 atomic steps
  40 hash conflicts:    937081 (resolved)
  41
  42 Stats on memory usage (in Megabytes):
  43    15.459       equivalent memory usage for states (stored*(State-vector + overhead))
  44     7.007       actual memory usage for states (compression: 45.33%)
  45                 state-vector as stored = 17 byte + 36 byte overhead
  46     8.000       memory used for hash table (-w20)
  47   457.764       memory used for DFS stack (-m10000000)
  48   472.697       total actual memory usage
  49
  50 nr of templates: [ globals chans procs ]
  51 collapse counts: [ 1001 532 228 2 2 ]
  52 unreached in proctype urcu_reader
  53         line 269, "pan.___", state 55, "cache_dirty_urcu_gp_ctr = 0"
  54         line 277, "pan.___", state 77, "cache_dirty_rcu_ptr = 0"
  55         line 281, "pan.___", state 86, "cache_dirty_rcu_data[i] = 0"
  56         line 246, "pan.___", state 102, "(1)"
  57         line 250, "pan.___", state 110, "(1)"
  58         line 254, "pan.___", state 122, "(1)"
  59         line 258, "pan.___", state 130, "(1)"
  60         line 405, "pan.___", state 156, "cache_dirty_urcu_gp_ctr = 0"
  61         line 414, "pan.___", state 188, "cache_dirty_rcu_ptr = 0"
  62         line 418, "pan.___", state 202, "cache_dirty_rcu_data[i] = 0"
  63         line 423, "pan.___", state 221, "(1)"
  64         line 432, "pan.___", state 251, "(1)"
  65         line 436, "pan.___", state 264, "(1)"
  66         line 615, "pan.___", state 285, "_proc_urcu_reader = (_proc_urcu_reader|((1<<2)<<1))"
  67         line 405, "pan.___", state 292, "cache_dirty_urcu_gp_ctr = 0"
  68         line 414, "pan.___", state 324, "cache_dirty_rcu_ptr = 0"
  69         line 418, "pan.___", state 338, "cache_dirty_rcu_data[i] = 0"
  70         line 423, "pan.___", state 357, "(1)"
  71         line 432, "pan.___", state 387, "(1)"
  72         line 436, "pan.___", state 400, "(1)"
  73         line 405, "pan.___", state 421, "cache_dirty_urcu_gp_ctr = 0"
  74         line 414, "pan.___", state 453, "cache_dirty_rcu_ptr = 0"
  75         line 418, "pan.___", state 467, "cache_dirty_rcu_data[i] = 0"
  76         line 423, "pan.___", state 486, "(1)"
  77         line 432, "pan.___", state 516, "(1)"
  78         line 436, "pan.___", state 529, "(1)"
  79         line 405, "pan.___", state 552, "cache_dirty_urcu_gp_ctr = 0"
  80         line 405, "pan.___", state 554, "(1)"
  81         line 405, "pan.___", state 555, "(cache_dirty_urcu_gp_ctr)"
  82         line 405, "pan.___", state 555, "else"
  83         line 405, "pan.___", state 558, "(1)"
  84         line 409, "pan.___", state 566, "cache_dirty_urcu_active_readers = 0"
  85         line 409, "pan.___", state 568, "(1)"
  86         line 409, "pan.___", state 569, "(cache_dirty_urcu_active_readers)"
  87         line 409, "pan.___", state 569, "else"
  88         line 409, "pan.___", state 572, "(1)"
  89         line 409, "pan.___", state 573, "(1)"
  90         line 409, "pan.___", state 573, "(1)"
  91         line 407, "pan.___", state 578, "((i<1))"
  92         line 407, "pan.___", state 578, "((i>=1))"
  93         line 414, "pan.___", state 584, "cache_dirty_rcu_ptr = 0"
  94         line 414, "pan.___", state 586, "(1)"
  95         line 414, "pan.___", state 587, "(cache_dirty_rcu_ptr)"
  96         line 414, "pan.___", state 587, "else"
  97         line 414, "pan.___", state 590, "(1)"
  98         line 414, "pan.___", state 591, "(1)"
  99         line 414, "pan.___", state 591, "(1)"
 100         line 418, "pan.___", state 598, "cache_dirty_rcu_data[i] = 0"
 101         line 418, "pan.___", state 600, "(1)"
 102         line 418, "pan.___", state 601, "(cache_dirty_rcu_data[i])"
 103         line 418, "pan.___", state 601, "else"
 104         line 418, "pan.___", state 604, "(1)"
 105         line 418, "pan.___", state 605, "(1)"
 106         line 418, "pan.___", state 605, "(1)"
 107         line 416, "pan.___", state 610, "((i<2))"
 108         line 416, "pan.___", state 610, "((i>=2))"
 109         line 423, "pan.___", state 617, "(1)"
 110         line 423, "pan.___", state 618, "(!(cache_dirty_urcu_gp_ctr))"
 111         line 423, "pan.___", state 618, "else"
 112         line 423, "pan.___", state 621, "(1)"
 113         line 423, "pan.___", state 622, "(1)"
 114         line 423, "pan.___", state 622, "(1)"
 115         line 427, "pan.___", state 630, "(1)"
 116         line 427, "pan.___", state 631, "(!(cache_dirty_urcu_active_readers))"
 117         line 427, "pan.___", state 631, "else"
 118         line 427, "pan.___", state 634, "(1)"
 119         line 427, "pan.___", state 635, "(1)"
 120         line 427, "pan.___", state 635, "(1)"
 121         line 425, "pan.___", state 640, "((i<1))"
 122         line 425, "pan.___", state 640, "((i>=1))"
 123         line 432, "pan.___", state 647, "(1)"
 124         line 432, "pan.___", state 648, "(!(cache_dirty_rcu_ptr))"
 125         line 432, "pan.___", state 648, "else"
 126         line 432, "pan.___", state 651, "(1)"
 127         line 432, "pan.___", state 652, "(1)"
 128         line 432, "pan.___", state 652, "(1)"
 129         line 436, "pan.___", state 660, "(1)"
 130         line 436, "pan.___", state 661, "(!(cache_dirty_rcu_data[i]))"
 131         line 436, "pan.___", state 661, "else"
 132         line 436, "pan.___", state 664, "(1)"
 133         line 436, "pan.___", state 665, "(1)"
 134         line 436, "pan.___", state 665, "(1)"
 135         line 434, "pan.___", state 670, "((i<2))"
 136         line 434, "pan.___", state 670, "((i>=2))"
 137         line 444, "pan.___", state 674, "(1)"
 138         line 444, "pan.___", state 674, "(1)"
 139         line 615, "pan.___", state 677, "cached_urcu_active_readers = (tmp+1)"
 140         line 615, "pan.___", state 678, "_proc_urcu_reader = (_proc_urcu_reader|(1<<5))"
 141         line 615, "pan.___", state 679, "(1)"
 142         line 405, "pan.___", state 686, "cache_dirty_urcu_gp_ctr = 0"
 143         line 414, "pan.___", state 718, "cache_dirty_rcu_ptr = 0"
 144         line 418, "pan.___", state 732, "cache_dirty_rcu_data[i] = 0"
 145         line 423, "pan.___", state 751, "(1)"
 146         line 432, "pan.___", state 781, "(1)"
 147         line 436, "pan.___", state 794, "(1)"
 148         line 405, "pan.___", state 821, "cache_dirty_urcu_gp_ctr = 0"
 149         line 414, "pan.___", state 853, "cache_dirty_rcu_ptr = 0"
 150         line 418, "pan.___", state 867, "cache_dirty_rcu_data[i] = 0"
 151         line 423, "pan.___", state 886, "(1)"
 152         line 432, "pan.___", state 916, "(1)"
 153         line 436, "pan.___", state 929, "(1)"
 154         line 405, "pan.___", state 950, "cache_dirty_urcu_gp_ctr = 0"
 155         line 414, "pan.___", state 982, "cache_dirty_rcu_ptr = 0"
 156         line 418, "pan.___", state 996, "cache_dirty_rcu_data[i] = 0"
 157         line 423, "pan.___", state 1015, "(1)"
 158         line 432, "pan.___", state 1045, "(1)"
 159         line 436, "pan.___", state 1058, "(1)"
 160         line 246, "pan.___", state 1091, "(1)"
 161         line 254, "pan.___", state 1111, "(1)"
 162         line 258, "pan.___", state 1119, "(1)"
 163         line 749, "pan.___", state 1136, "-end-"
 164         (91 of 1136 states)
 165 unreached in proctype urcu_writer
 166         line 405, "pan.___", state 45, "cache_dirty_urcu_gp_ctr = 0"
 167         line 409, "pan.___", state 59, "cache_dirty_urcu_active_readers = 0"
 168         line 414, "pan.___", state 77, "cache_dirty_rcu_ptr = 0"
 169         line 423, "pan.___", state 110, "(1)"
 170         line 427, "pan.___", state 123, "(1)"
 171         line 432, "pan.___", state 140, "(1)"
 172         line 269, "pan.___", state 176, "cache_dirty_urcu_gp_ctr = 0"
 173         line 273, "pan.___", state 185, "cache_dirty_urcu_active_readers = 0"
 174         line 277, "pan.___", state 198, "cache_dirty_rcu_ptr = 0"
 175         line 405, "pan.___", state 238, "cache_dirty_urcu_gp_ctr = 0"
 176         line 409, "pan.___", state 252, "cache_dirty_urcu_active_readers = 0"
 177         line 414, "pan.___", state 270, "cache_dirty_rcu_ptr = 0"
 178         line 418, "pan.___", state 284, "cache_dirty_rcu_data[i] = 0"
 179         line 423, "pan.___", state 303, "(1)"
 180         line 427, "pan.___", state 316, "(1)"
 181         line 432, "pan.___", state 333, "(1)"
 182         line 436, "pan.___", state 346, "(1)"
 183         line 409, "pan.___", state 383, "cache_dirty_urcu_active_readers = 0"
 184         line 414, "pan.___", state 401, "cache_dirty_rcu_ptr = 0"
 185         line 418, "pan.___", state 415, "cache_dirty_rcu_data[i] = 0"
 186         line 427, "pan.___", state 447, "(1)"
 187         line 432, "pan.___", state 464, "(1)"
 188         line 436, "pan.___", state 477, "(1)"
 189         line 405, "pan.___", state 504, "cache_dirty_urcu_gp_ctr = 0"
 190         line 405, "pan.___", state 506, "(1)"
 191         line 405, "pan.___", state 507, "(cache_dirty_urcu_gp_ctr)"
 192         line 405, "pan.___", state 507, "else"
 193         line 405, "pan.___", state 510, "(1)"
 194         line 409, "pan.___", state 518, "cache_dirty_urcu_active_readers = 0"
 195         line 409, "pan.___", state 520, "(1)"
 196         line 409, "pan.___", state 521, "(cache_dirty_urcu_active_readers)"
 197         line 409, "pan.___", state 521, "else"
 198         line 409, "pan.___", state 524, "(1)"
 199         line 409, "pan.___", state 525, "(1)"
 200         line 409, "pan.___", state 525, "(1)"
 201         line 407, "pan.___", state 530, "((i<1))"
 202         line 407, "pan.___", state 530, "((i>=1))"
 203         line 414, "pan.___", state 536, "cache_dirty_rcu_ptr = 0"
 204         line 414, "pan.___", state 538, "(1)"
 205         line 414, "pan.___", state 539, "(cache_dirty_rcu_ptr)"
 206         line 414, "pan.___", state 539, "else"
 207         line 414, "pan.___", state 542, "(1)"
 208         line 414, "pan.___", state 543, "(1)"
 209         line 414, "pan.___", state 543, "(1)"
 210         line 418, "pan.___", state 550, "cache_dirty_rcu_data[i] = 0"
 211         line 418, "pan.___", state 552, "(1)"
 212         line 418, "pan.___", state 553, "(cache_dirty_rcu_data[i])"
 213         line 418, "pan.___", state 553, "else"
 214         line 418, "pan.___", state 556, "(1)"
 215         line 418, "pan.___", state 557, "(1)"
 216         line 418, "pan.___", state 557, "(1)"
 217         line 416, "pan.___", state 562, "((i<2))"
 218         line 416, "pan.___", state 562, "((i>=2))"
 219         line 423, "pan.___", state 569, "(1)"
 220         line 423, "pan.___", state 570, "(!(cache_dirty_urcu_gp_ctr))"
 221         line 423, "pan.___", state 570, "else"
 222         line 423, "pan.___", state 573, "(1)"
 223         line 423, "pan.___", state 574, "(1)"
 224         line 423, "pan.___", state 574, "(1)"
 225         line 427, "pan.___", state 582, "(1)"
 226         line 427, "pan.___", state 583, "(!(cache_dirty_urcu_active_readers))"
 227         line 427, "pan.___", state 583, "else"
 228         line 427, "pan.___", state 586, "(1)"
 229         line 427, "pan.___", state 587, "(1)"
 230         line 427, "pan.___", state 587, "(1)"
 231         line 425, "pan.___", state 592, "((i<1))"
 232         line 425, "pan.___", state 592, "((i>=1))"
 233         line 432, "pan.___", state 599, "(1)"
 234         line 432, "pan.___", state 600, "(!(cache_dirty_rcu_ptr))"
 235         line 432, "pan.___", state 600, "else"
 236         line 432, "pan.___", state 603, "(1)"
 237         line 432, "pan.___", state 604, "(1)"
 238         line 432, "pan.___", state 604, "(1)"
 239         line 436, "pan.___", state 612, "(1)"
 240         line 436, "pan.___", state 613, "(!(cache_dirty_rcu_data[i]))"
 241         line 436, "pan.___", state 613, "else"
 242         line 436, "pan.___", state 616, "(1)"
 243         line 436, "pan.___", state 617, "(1)"
 244         line 436, "pan.___", state 617, "(1)"
 245         line 444, "pan.___", state 626, "(1)"
 246         line 444, "pan.___", state 626, "(1)"
 247         line 409, "pan.___", state 646, "cache_dirty_urcu_active_readers = 0"
 248         line 414, "pan.___", state 664, "cache_dirty_rcu_ptr = 0"
 249         line 418, "pan.___", state 678, "cache_dirty_rcu_data[i] = 0"
 250         line 427, "pan.___", state 710, "(1)"
 251         line 432, "pan.___", state 727, "(1)"
 252         line 436, "pan.___", state 740, "(1)"
 253         line 409, "pan.___", state 775, "cache_dirty_urcu_active_readers = 0"
 254         line 414, "pan.___", state 793, "cache_dirty_rcu_ptr = 0"
 255         line 418, "pan.___", state 807, "cache_dirty_rcu_data[i] = 0"
 256         line 427, "pan.___", state 839, "(1)"
 257         line 432, "pan.___", state 856, "(1)"
 258         line 436, "pan.___", state 869, "(1)"
 259         line 409, "pan.___", state 906, "cache_dirty_urcu_active_readers = 0"
 260         line 414, "pan.___", state 924, "cache_dirty_rcu_ptr = 0"
 261         line 418, "pan.___", state 938, "cache_dirty_rcu_data[i] = 0"
 262         line 427, "pan.___", state 970, "(1)"
 263         line 432, "pan.___", state 987, "(1)"
 264         line 436, "pan.___", state 1000, "(1)"
 265         line 409, "pan.___", state 1040, "cache_dirty_urcu_active_readers = 0"
 266         line 414, "pan.___", state 1058, "cache_dirty_rcu_ptr = 0"
 267         line 418, "pan.___", state 1072, "cache_dirty_rcu_data[i] = 0"
 268         line 427, "pan.___", state 1104, "(1)"
 269         line 432, "pan.___", state 1121, "(1)"
 270         line 436, "pan.___", state 1134, "(1)"
 271         line 269, "pan.___", state 1180, "cache_dirty_urcu_gp_ctr = 0"
 272         line 273, "pan.___", state 1189, "cache_dirty_urcu_active_readers = 0"
 273         line 277, "pan.___", state 1204, "(1)"
 274         line 281, "pan.___", state 1211, "cache_dirty_rcu_data[i] = 0"
 275         line 246, "pan.___", state 1227, "(1)"
 276         line 250, "pan.___", state 1235, "(1)"
 277         line 254, "pan.___", state 1247, "(1)"
 278         line 258, "pan.___", state 1255, "(1)"
 279         line 269, "pan.___", state 1286, "cache_dirty_urcu_gp_ctr = 0"
 280         line 273, "pan.___", state 1295, "cache_dirty_urcu_active_readers = 0"
 281         line 277, "pan.___", state 1308, "cache_dirty_rcu_ptr = 0"
 282         line 281, "pan.___", state 1317, "cache_dirty_rcu_data[i] = 0"
 283         line 246, "pan.___", state 1333, "(1)"
 284         line 250, "pan.___", state 1341, "(1)"
 285         line 254, "pan.___", state 1353, "(1)"
 286         line 258, "pan.___", state 1361, "(1)"
 287         line 273, "pan.___", state 1387, "cache_dirty_urcu_active_readers = 0"
 288         line 277, "pan.___", state 1400, "cache_dirty_rcu_ptr = 0"
 289         line 281, "pan.___", state 1409, "cache_dirty_rcu_data[i] = 0"
 290         line 246, "pan.___", state 1425, "(1)"
 291         line 250, "pan.___", state 1433, "(1)"
 292         line 254, "pan.___", state 1445, "(1)"
 293         line 258, "pan.___", state 1453, "(1)"
 294         line 269, "pan.___", state 1484, "cache_dirty_urcu_gp_ctr = 0"
 295         line 273, "pan.___", state 1493, "cache_dirty_urcu_active_readers = 0"
 296         line 277, "pan.___", state 1506, "cache_dirty_rcu_ptr = 0"
 297         line 281, "pan.___", state 1515, "cache_dirty_rcu_data[i] = 0"
 298         line 246, "pan.___", state 1531, "(1)"
 299         line 250, "pan.___", state 1539, "(1)"
 300         line 254, "pan.___", state 1551, "(1)"
 301         line 258, "pan.___", state 1559, "(1)"
 302         line 1124, "pan.___", state 1575, "-end-"
 303         (118 of 1575 states)
 304 unreached in proctype :init:
 305         (0 of 26 states)
 306 unreached in proctype :never:
 307         line 1187, "pan.___", state 11, "-end-"
 308         (1 of 11 states)
 309
 310 pan: elapsed time 67.7 seconds
 311 pan: rate 3447.2308 states/second
 312 pan: avg transition delay 1.5577e-06 usec
 313 cp .input.spin urcu_progress_writer_error.spin.input
 314 cp .input.spin.trail urcu_progress_writer_error.spin.input.trail
 315 make[1]: Leaving directory `/home/compudj/doc/userspace-rcu/formal-model/urcu-controldataflow-min-progress'