formal-model/urcu/result-standard-execution-nest/urcu_progress_reader.log

   1 make[1]: Entering directory `/home/compudj/doc/userspace-rcu/formal-model/urcu'
   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_reader.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 -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 744)
  19 depth 15: Claim reached state 9 (line 749)
  20 depth 1767: Claim reached state 9 (line 748)
  21 Depth=    3880 States=    1e+06 Transitions= 1.71e+07 Memory=   499.651 t=   14.5 R=   7e+04
  22 Depth=    3880 States=    2e+06 Transitions= 3.79e+07 Memory=   532.365 t=   32.3 R=   6e+04
  23 Depth=    3880 States=    3e+06 Transitions= 5.83e+07 Memory=   564.983 t=   50.2 R=   6e+04
  24 pan: resizing hashtable to -w22..  done
  25 Depth=    3880 States=    4e+06 Transitions= 7.76e+07 Memory=   629.307 t=     67 R=   6e+04
  26 Depth=    3880 States=    5e+06 Transitions= 9.78e+07 Memory=   662.315 t=   84.2 R=   6e+04
  27 Depth=    3880 States=    6e+06 Transitions= 1.19e+08 Memory=   694.736 t=    102 R=   6e+04
  28 Depth=    3880 States=    7e+06 Transitions= 1.38e+08 Memory=   727.842 t=    119 R=   6e+04
  29 Depth=    3880 States=    8e+06 Transitions= 1.59e+08 Memory=   760.752 t=    137 R=   6e+04
  30 Depth=    3880 States=    9e+06 Transitions= 1.79e+08 Memory=   793.174 t=    155 R=   6e+04
  31 pan: resizing hashtable to -w24..  done
  32 Depth=    3880 States=    1e+07 Transitions= 1.98e+08 Memory=   950.568 t=    172 R=   6e+04
  33 Depth=    3880 States=  1.1e+07 Transitions= 2.19e+08 Memory=   983.479 t=    189 R=   6e+04
  34 Depth=    3880 States=  1.2e+07 Transitions=  2.4e+08 Memory=  1015.901 t=    207 R=   6e+04
  35 Depth=    3880 States=  1.3e+07 Transitions= 2.58e+08 Memory=  1049.397 t=    223 R=   6e+04
  36
  37 (Spin Version 5.1.7 -- 23 December 2008)
  38         + Partial Order Reduction
  39
  40 Full statespace search for:
  41         never claim             +
  42         assertion violations    + (if within scope of claim)
  43         acceptance   cycles     + (fairness enabled)
  44         invalid end states      - (disabled by never claim)
  45
  46 State-vector 56 byte, depth reached 3880, errors: 0
  47   7197860 states, stored (1.33457e+07 visited)
  48 2.5266654e+08 states, matched
  49 2.6601223e+08 transitions (= visited+matched)
  50 9.9067284e+08 atomic steps
  51 hash conflicts:  87783920 (resolved)
  52
  53 Stats on memory usage (in Megabytes):
  54   576.611       equivalent memory usage for states (stored*(State-vector + overhead))
  55   474.839       actual memory usage for states (compression: 82.35%)
  56                 state-vector as stored = 41 byte + 28 byte overhead
  57   128.000       memory used for hash table (-w24)
  58   457.764       memory used for DFS stack (-m10000000)
  59  1060.529       total actual memory usage
  60
  61 unreached in proctype urcu_reader
  62         line 399, "pan.___", state 16, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
  63         line 408, "pan.___", state 48, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
  64         line 409, "pan.___", state 61, "(1)"
  65         line 418, "pan.___", state 91, "(1)"
  66         line 399, "pan.___", state 106, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
  67         line 408, "pan.___", state 138, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
  68         line 409, "pan.___", state 151, "(1)"
  69         line 418, "pan.___", state 181, "(1)"
  70         line 399, "pan.___", state 197, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
  71         line 408, "pan.___", state 229, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
  72         line 409, "pan.___", state 242, "(1)"
  73         line 418, "pan.___", state 272, "(1)"
  74         line 399, "pan.___", state 315, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
  75         line 408, "pan.___", state 347, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
  76         line 409, "pan.___", state 360, "(1)"
  77         line 418, "pan.___", state 390, "(1)"
  78         line 540, "pan.___", state 414, "-end-"
  79         (17 of 414 states)
  80 unreached in proctype urcu_writer
  81         line 399, "pan.___", state 14, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
  82         line 403, "pan.___", state 28, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
  83         line 408, "pan.___", state 46, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
  84         line 409, "pan.___", state 59, "(1)"
  85         line 413, "pan.___", state 72, "(1)"
  86         line 418, "pan.___", state 89, "(1)"
  87         line 399, "pan.___", state 108, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
  88         line 403, "pan.___", state 122, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
  89         line 409, "pan.___", state 153, "(1)"
  90         line 413, "pan.___", state 166, "(1)"
  91         line 652, "pan.___", state 199, "(1)"
  92         line 175, "pan.___", state 208, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
  93         line 179, "pan.___", state 217, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
  94         line 160, "pan.___", state 240, "(1)"
  95         line 164, "pan.___", state 248, "(1)"
  96         line 168, "pan.___", state 260, "(1)"
  97         line 175, "pan.___", state 271, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<i)))"
  98         line 183, "pan.___", state 293, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<i)))"
  99         line 160, "pan.___", state 303, "(1)"
 100         line 164, "pan.___", state 311, "(1)"
 101         line 168, "pan.___", state 323, "(1)"
 102         line 175, "pan.___", state 338, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
 103         line 179, "pan.___", state 347, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 104         line 183, "pan.___", state 360, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 105         line 160, "pan.___", state 370, "(1)"
 106         line 164, "pan.___", state 378, "(1)"
 107         line 168, "pan.___", state 390, "(1)"
 108         line 399, "pan.___", state 404, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
 109         line 403, "pan.___", state 418, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 110         line 408, "pan.___", state 436, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 111         line 409, "pan.___", state 449, "(1)"
 112         line 413, "pan.___", state 462, "(1)"
 113         line 418, "pan.___", state 479, "(1)"
 114         line 399, "pan.___", state 498, "(1)"
 115         line 403, "pan.___", state 510, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 116         line 408, "pan.___", state 528, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 117         line 413, "pan.___", state 554, "(1)"
 118         line 418, "pan.___", state 571, "(1)"
 119         line 403, "pan.___", state 603, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 120         line 408, "pan.___", state 621, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 121         line 413, "pan.___", state 647, "(1)"
 122         line 418, "pan.___", state 664, "(1)"
 123         line 179, "pan.___", state 687, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 124         line 183, "pan.___", state 700, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 125         line 160, "pan.___", state 710, "(1)"
 126         line 164, "pan.___", state 718, "(1)"
 127         line 168, "pan.___", state 730, "(1)"
 128         line 175, "pan.___", state 741, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<i)))"
 129         line 183, "pan.___", state 763, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<i)))"
 130         line 160, "pan.___", state 773, "(1)"
 131         line 164, "pan.___", state 781, "(1)"
 132         line 168, "pan.___", state 793, "(1)"
 133         line 175, "pan.___", state 808, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
 134         line 179, "pan.___", state 817, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 135         line 183, "pan.___", state 830, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 136         line 160, "pan.___", state 840, "(1)"
 137         line 164, "pan.___", state 848, "(1)"
 138         line 168, "pan.___", state 860, "(1)"
 139         line 399, "pan.___", state 882, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
 140         line 399, "pan.___", state 884, "(1)"
 141         line 399, "pan.___", state 885, "((cache_dirty_urcu_gp_ctr.bitfield&(1<<_pid)))"
 142         line 399, "pan.___", state 885, "else"
 143         line 399, "pan.___", state 888, "(1)"
 144         line 403, "pan.___", state 896, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 145         line 403, "pan.___", state 898, "(1)"
 146         line 403, "pan.___", state 899, "((cache_dirty_urcu_active_readers.bitfield&(1<<_pid)))"
 147         line 403, "pan.___", state 899, "else"
 148         line 403, "pan.___", state 902, "(1)"
 149         line 403, "pan.___", state 903, "(1)"
 150         line 403, "pan.___", state 903, "(1)"
 151         line 401, "pan.___", state 908, "((i<1))"
 152         line 401, "pan.___", state 908, "((i>=1))"
 153         line 408, "pan.___", state 914, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 154         line 409, "pan.___", state 927, "(1)"
 155         line 409, "pan.___", state 928, "(!((cache_dirty_urcu_gp_ctr.bitfield&(1<<_pid))))"
 156         line 409, "pan.___", state 928, "else"
 157         line 409, "pan.___", state 931, "(1)"
 158         line 409, "pan.___", state 932, "(1)"
 159         line 409, "pan.___", state 932, "(1)"
 160         line 413, "pan.___", state 940, "(1)"
 161         line 413, "pan.___", state 941, "(!((cache_dirty_urcu_active_readers.bitfield&(1<<_pid))))"
 162         line 413, "pan.___", state 941, "else"
 163         line 413, "pan.___", state 944, "(1)"
 164         line 413, "pan.___", state 945, "(1)"
 165         line 413, "pan.___", state 945, "(1)"
 166         line 411, "pan.___", state 950, "((i<1))"
 167         line 411, "pan.___", state 950, "((i>=1))"
 168         line 418, "pan.___", state 957, "(1)"
 169         line 418, "pan.___", state 958, "(!((cache_dirty_generation_ptr.bitfield&(1<<_pid))))"
 170         line 418, "pan.___", state 958, "else"
 171         line 418, "pan.___", state 961, "(1)"
 172         line 418, "pan.___", state 962, "(1)"
 173         line 418, "pan.___", state 962, "(1)"
 174         line 420, "pan.___", state 965, "(1)"
 175         line 420, "pan.___", state 965, "(1)"
 176         line 403, "pan.___", state 996, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 177         line 408, "pan.___", state 1014, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 178         line 413, "pan.___", state 1040, "(1)"
 179         line 418, "pan.___", state 1057, "(1)"
 180         line 403, "pan.___", state 1086, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 181         line 408, "pan.___", state 1104, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 182         line 413, "pan.___", state 1130, "(1)"
 183         line 418, "pan.___", state 1147, "(1)"
 184         line 399, "pan.___", state 1166, "(1)"
 185         line 403, "pan.___", state 1178, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 186         line 408, "pan.___", state 1196, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 187         line 413, "pan.___", state 1222, "(1)"
 188         line 418, "pan.___", state 1239, "(1)"
 189         line 403, "pan.___", state 1271, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 190         line 408, "pan.___", state 1289, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 191         line 413, "pan.___", state 1315, "(1)"
 192         line 418, "pan.___", state 1332, "(1)"
 193         line 179, "pan.___", state 1355, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 194         line 183, "pan.___", state 1368, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 195         line 160, "pan.___", state 1378, "(1)"
 196         line 164, "pan.___", state 1386, "(1)"
 197         line 168, "pan.___", state 1398, "(1)"
 198         line 175, "pan.___", state 1409, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<i)))"
 199         line 183, "pan.___", state 1431, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<i)))"
 200         line 160, "pan.___", state 1441, "(1)"
 201         line 164, "pan.___", state 1449, "(1)"
 202         line 168, "pan.___", state 1461, "(1)"
 203         line 175, "pan.___", state 1476, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
 204         line 179, "pan.___", state 1485, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 205         line 183, "pan.___", state 1498, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 206         line 160, "pan.___", state 1508, "(1)"
 207         line 164, "pan.___", state 1516, "(1)"
 208         line 168, "pan.___", state 1528, "(1)"
 209         line 399, "pan.___", state 1550, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
 210         line 399, "pan.___", state 1552, "(1)"
 211         line 399, "pan.___", state 1553, "((cache_dirty_urcu_gp_ctr.bitfield&(1<<_pid)))"
 212         line 399, "pan.___", state 1553, "else"
 213         line 399, "pan.___", state 1556, "(1)"
 214         line 403, "pan.___", state 1564, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 215         line 403, "pan.___", state 1566, "(1)"
 216         line 403, "pan.___", state 1567, "((cache_dirty_urcu_active_readers.bitfield&(1<<_pid)))"
 217         line 403, "pan.___", state 1567, "else"
 218         line 403, "pan.___", state 1570, "(1)"
 219         line 403, "pan.___", state 1571, "(1)"
 220         line 403, "pan.___", state 1571, "(1)"
 221         line 401, "pan.___", state 1576, "((i<1))"
 222         line 401, "pan.___", state 1576, "((i>=1))"
 223         line 408, "pan.___", state 1582, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 224         line 409, "pan.___", state 1595, "(1)"
 225         line 409, "pan.___", state 1596, "(!((cache_dirty_urcu_gp_ctr.bitfield&(1<<_pid))))"
 226         line 409, "pan.___", state 1596, "else"
 227         line 409, "pan.___", state 1599, "(1)"
 228         line 409, "pan.___", state 1600, "(1)"
 229         line 409, "pan.___", state 1600, "(1)"
 230         line 413, "pan.___", state 1608, "(1)"
 231         line 413, "pan.___", state 1609, "(!((cache_dirty_urcu_active_readers.bitfield&(1<<_pid))))"
 232         line 413, "pan.___", state 1609, "else"
 233         line 413, "pan.___", state 1612, "(1)"
 234         line 413, "pan.___", state 1613, "(1)"
 235         line 413, "pan.___", state 1613, "(1)"
 236         line 411, "pan.___", state 1618, "((i<1))"
 237         line 411, "pan.___", state 1618, "((i>=1))"
 238         line 418, "pan.___", state 1625, "(1)"
 239         line 418, "pan.___", state 1626, "(!((cache_dirty_generation_ptr.bitfield&(1<<_pid))))"
 240         line 418, "pan.___", state 1626, "else"
 241         line 418, "pan.___", state 1629, "(1)"
 242         line 418, "pan.___", state 1630, "(1)"
 243         line 418, "pan.___", state 1630, "(1)"
 244         line 420, "pan.___", state 1633, "(1)"
 245         line 420, "pan.___", state 1633, "(1)"
 246         line 179, "pan.___", state 1658, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 247         line 183, "pan.___", state 1671, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 248         line 160, "pan.___", state 1681, "(1)"
 249         line 164, "pan.___", state 1689, "(1)"
 250         line 168, "pan.___", state 1701, "(1)"
 251         line 175, "pan.___", state 1712, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<i)))"
 252         line 183, "pan.___", state 1734, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<i)))"
 253         line 160, "pan.___", state 1744, "(1)"
 254         line 164, "pan.___", state 1752, "(1)"
 255         line 168, "pan.___", state 1764, "(1)"
 256         line 175, "pan.___", state 1779, "cache_dirty_urcu_gp_ctr.bitfield = (cache_dirty_urcu_gp_ctr.bitfield&~((1<<_pid)))"
 257         line 179, "pan.___", state 1788, "cache_dirty_urcu_active_readers.bitfield = (cache_dirty_urcu_active_readers.bitfield&~((1<<_pid)))"
 258         line 183, "pan.___", state 1801, "cache_dirty_generation_ptr.bitfield = (cache_dirty_generation_ptr.bitfield&~((1<<_pid)))"
 259         line 160, "pan.___", state 1811, "(1)"
 260         line 164, "pan.___", state 1819, "(1)"
 261         line 168, "pan.___", state 1831, "(1)"
 262         line 702, "pan.___", state 1856, "-end-"
 263         (158 of 1856 states)
 264 unreached in proctype :init:
 265         (0 of 46 states)
 266 unreached in proctype :never:
 267         line 751, "pan.___", state 11, "-end-"
 268         (1 of 11 states)
 269
 270 pan: elapsed time 230 seconds
 271 pan: rate  58151.15 states/second
 272 pan: avg transition delay 8.6274e-07 usec
 273 cp .input.spin urcu_progress_reader.spin.input
 274 cp .input.spin.trail urcu_progress_reader.spin.input.trail
 275 make[1]: Leaving directory `/home/compudj/doc/userspace-rcu/formal-model/urcu'