| 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_free.ltl | grep -v ^//`)" >> pan.ltl |
| 7 | cp urcu_free_no_rmb.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 -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 1179) |
| 19 | pan: claim violated! (at depth 1476) |
| 20 | pan: wrote .input.spin.trail |
| 21 | |
| 22 | (Spin Version 5.1.7 -- 23 December 2008) |
| 23 | Warning: Search not completed |
| 24 | + Partial Order Reduction |
| 25 | + Compression |
| 26 | |
| 27 | Full statespace search for: |
| 28 | never claim + |
| 29 | assertion violations + (if within scope of claim) |
| 30 | acceptance cycles + (fairness disabled) |
| 31 | invalid end states - (disabled by never claim) |
| 32 | |
| 33 | State-vector 80 byte, depth reached 3979, errors: 1 |
| 34 | 430584 states, stored |
| 35 | 99483765 states, matched |
| 36 | 99914349 transitions (= stored+matched) |
| 37 | 5.7035561e+08 atomic steps |
| 38 | hash conflicts: 13587432 (resolved) |
| 39 | |
| 40 | Stats on memory usage (in Megabytes): |
| 41 | 47.634 equivalent memory usage for states (stored*(State-vector + overhead)) |
| 42 | 20.652 actual memory usage for states (compression: 43.36%) |
| 43 | state-vector as stored = 14 byte + 36 byte overhead |
| 44 | 8.000 memory used for hash table (-w20) |
| 45 | 457.764 memory used for DFS stack (-m10000000) |
| 46 | 486.369 total actual memory usage |
| 47 | |
| 48 | nr of templates: [ globals chans procs ] |
| 49 | collapse counts: [ 7914 682 569 2 2 ] |
| 50 | unreached in proctype urcu_reader |
| 51 | line 713, "pan.___", state 12, "((i<1))" |
| 52 | line 713, "pan.___", state 12, "((i>=1))" |
| 53 | line 268, "pan.___", state 55, "cache_dirty_urcu_gp_ctr = 0" |
| 54 | line 276, "pan.___", state 77, "cache_dirty_rcu_ptr = 0" |
| 55 | line 280, "pan.___", state 86, "cache_dirty_rcu_data[i] = 0" |
| 56 | line 245, "pan.___", state 102, "(1)" |
| 57 | line 249, "pan.___", state 110, "(1)" |
| 58 | line 253, "pan.___", state 122, "(1)" |
| 59 | line 257, "pan.___", state 130, "(1)" |
| 60 | line 404, "pan.___", state 156, "cache_dirty_urcu_gp_ctr = 0" |
| 61 | line 413, "pan.___", state 188, "cache_dirty_rcu_ptr = 0" |
| 62 | line 417, "pan.___", state 202, "cache_dirty_rcu_data[i] = 0" |
| 63 | line 422, "pan.___", state 221, "(1)" |
| 64 | line 431, "pan.___", state 251, "(1)" |
| 65 | line 435, "pan.___", state 264, "(1)" |
| 66 | line 614, "pan.___", state 285, "_proc_urcu_reader = (_proc_urcu_reader|((1<<2)<<1))" |
| 67 | line 404, "pan.___", state 292, "cache_dirty_urcu_gp_ctr = 0" |
| 68 | line 413, "pan.___", state 324, "cache_dirty_rcu_ptr = 0" |
| 69 | line 417, "pan.___", state 338, "cache_dirty_rcu_data[i] = 0" |
| 70 | line 422, "pan.___", state 357, "(1)" |
| 71 | line 431, "pan.___", state 387, "(1)" |
| 72 | line 435, "pan.___", state 400, "(1)" |
| 73 | line 404, "pan.___", state 421, "cache_dirty_urcu_gp_ctr = 0" |
| 74 | line 413, "pan.___", state 453, "cache_dirty_rcu_ptr = 0" |
| 75 | line 417, "pan.___", state 467, "cache_dirty_rcu_data[i] = 0" |
| 76 | line 422, "pan.___", state 486, "(1)" |
| 77 | line 431, "pan.___", state 516, "(1)" |
| 78 | line 435, "pan.___", state 529, "(1)" |
| 79 | line 404, "pan.___", state 552, "cache_dirty_urcu_gp_ctr = 0" |
| 80 | line 404, "pan.___", state 554, "(1)" |
| 81 | line 404, "pan.___", state 555, "(cache_dirty_urcu_gp_ctr)" |
| 82 | line 404, "pan.___", state 555, "else" |
| 83 | line 404, "pan.___", state 558, "(1)" |
| 84 | line 408, "pan.___", state 566, "cache_dirty_urcu_active_readers = 0" |
| 85 | line 408, "pan.___", state 568, "(1)" |
| 86 | line 408, "pan.___", state 569, "(cache_dirty_urcu_active_readers)" |
| 87 | line 408, "pan.___", state 569, "else" |
| 88 | line 408, "pan.___", state 572, "(1)" |
| 89 | line 408, "pan.___", state 573, "(1)" |
| 90 | line 408, "pan.___", state 573, "(1)" |
| 91 | line 406, "pan.___", state 578, "((i<1))" |
| 92 | line 406, "pan.___", state 578, "((i>=1))" |
| 93 | line 413, "pan.___", state 584, "cache_dirty_rcu_ptr = 0" |
| 94 | line 413, "pan.___", state 586, "(1)" |
| 95 | line 413, "pan.___", state 587, "(cache_dirty_rcu_ptr)" |
| 96 | line 413, "pan.___", state 587, "else" |
| 97 | line 413, "pan.___", state 590, "(1)" |
| 98 | line 413, "pan.___", state 591, "(1)" |
| 99 | line 413, "pan.___", state 591, "(1)" |
| 100 | line 417, "pan.___", state 598, "cache_dirty_rcu_data[i] = 0" |
| 101 | line 417, "pan.___", state 600, "(1)" |
| 102 | line 417, "pan.___", state 601, "(cache_dirty_rcu_data[i])" |
| 103 | line 417, "pan.___", state 601, "else" |
| 104 | line 417, "pan.___", state 604, "(1)" |
| 105 | line 417, "pan.___", state 605, "(1)" |
| 106 | line 417, "pan.___", state 605, "(1)" |
| 107 | line 415, "pan.___", state 610, "((i<2))" |
| 108 | line 415, "pan.___", state 610, "((i>=2))" |
| 109 | line 422, "pan.___", state 617, "(1)" |
| 110 | line 422, "pan.___", state 618, "(!(cache_dirty_urcu_gp_ctr))" |
| 111 | line 422, "pan.___", state 618, "else" |
| 112 | line 422, "pan.___", state 621, "(1)" |
| 113 | line 422, "pan.___", state 622, "(1)" |
| 114 | line 422, "pan.___", state 622, "(1)" |
| 115 | line 426, "pan.___", state 630, "(1)" |
| 116 | line 426, "pan.___", state 631, "(!(cache_dirty_urcu_active_readers))" |
| 117 | line 426, "pan.___", state 631, "else" |
| 118 | line 426, "pan.___", state 634, "(1)" |
| 119 | line 426, "pan.___", state 635, "(1)" |
| 120 | line 426, "pan.___", state 635, "(1)" |
| 121 | line 424, "pan.___", state 640, "((i<1))" |
| 122 | line 424, "pan.___", state 640, "((i>=1))" |
| 123 | line 431, "pan.___", state 647, "(1)" |
| 124 | line 431, "pan.___", state 648, "(!(cache_dirty_rcu_ptr))" |
| 125 | line 431, "pan.___", state 648, "else" |
| 126 | line 431, "pan.___", state 651, "(1)" |
| 127 | line 431, "pan.___", state 652, "(1)" |
| 128 | line 431, "pan.___", state 652, "(1)" |
| 129 | line 435, "pan.___", state 660, "(1)" |
| 130 | line 435, "pan.___", state 661, "(!(cache_dirty_rcu_data[i]))" |
| 131 | line 435, "pan.___", state 661, "else" |
| 132 | line 435, "pan.___", state 664, "(1)" |
| 133 | line 435, "pan.___", state 665, "(1)" |
| 134 | line 435, "pan.___", state 665, "(1)" |
| 135 | line 433, "pan.___", state 670, "((i<2))" |
| 136 | line 433, "pan.___", state 670, "((i>=2))" |
| 137 | line 443, "pan.___", state 674, "(1)" |
| 138 | line 443, "pan.___", state 674, "(1)" |
| 139 | line 614, "pan.___", state 677, "cached_urcu_active_readers = (tmp+1)" |
| 140 | line 614, "pan.___", state 678, "_proc_urcu_reader = (_proc_urcu_reader|(1<<5))" |
| 141 | line 614, "pan.___", state 679, "(1)" |
| 142 | line 404, "pan.___", state 686, "cache_dirty_urcu_gp_ctr = 0" |
| 143 | line 413, "pan.___", state 718, "cache_dirty_rcu_ptr = 0" |
| 144 | line 417, "pan.___", state 732, "cache_dirty_rcu_data[i] = 0" |
| 145 | line 422, "pan.___", state 751, "(1)" |
| 146 | line 431, "pan.___", state 781, "(1)" |
| 147 | line 435, "pan.___", state 794, "(1)" |
| 148 | line 404, "pan.___", state 821, "cache_dirty_urcu_gp_ctr = 0" |
| 149 | line 413, "pan.___", state 853, "cache_dirty_rcu_ptr = 0" |
| 150 | line 417, "pan.___", state 867, "cache_dirty_rcu_data[i] = 0" |
| 151 | line 422, "pan.___", state 886, "(1)" |
| 152 | line 431, "pan.___", state 916, "(1)" |
| 153 | line 435, "pan.___", state 929, "(1)" |
| 154 | line 404, "pan.___", state 950, "cache_dirty_urcu_gp_ctr = 0" |
| 155 | line 413, "pan.___", state 982, "cache_dirty_rcu_ptr = 0" |
| 156 | line 417, "pan.___", state 996, "cache_dirty_rcu_data[i] = 0" |
| 157 | line 422, "pan.___", state 1015, "(1)" |
| 158 | line 431, "pan.___", state 1045, "(1)" |
| 159 | line 435, "pan.___", state 1058, "(1)" |
| 160 | line 404, "pan.___", state 1091, "cache_dirty_urcu_gp_ctr = 0" |
| 161 | line 413, "pan.___", state 1123, "cache_dirty_rcu_ptr = 0" |
| 162 | line 417, "pan.___", state 1137, "cache_dirty_rcu_data[i] = 0" |
| 163 | line 422, "pan.___", state 1156, "(1)" |
| 164 | line 431, "pan.___", state 1186, "(1)" |
| 165 | line 435, "pan.___", state 1199, "(1)" |
| 166 | line 748, "pan.___", state 1220, "-end-" |
| 167 | (95 of 1220 states) |
| 168 | unreached in proctype urcu_writer |
| 169 | line 837, "pan.___", state 12, "((i<1))" |
| 170 | line 837, "pan.___", state 12, "((i>=1))" |
| 171 | line 404, "pan.___", state 45, "cache_dirty_urcu_gp_ctr = 0" |
| 172 | line 404, "pan.___", state 51, "(1)" |
| 173 | line 408, "pan.___", state 59, "cache_dirty_urcu_active_readers = 0" |
| 174 | line 408, "pan.___", state 65, "(1)" |
| 175 | line 408, "pan.___", state 66, "(1)" |
| 176 | line 408, "pan.___", state 66, "(1)" |
| 177 | line 406, "pan.___", state 71, "((i<1))" |
| 178 | line 406, "pan.___", state 71, "((i>=1))" |
| 179 | line 413, "pan.___", state 77, "cache_dirty_rcu_ptr = 0" |
| 180 | line 413, "pan.___", state 83, "(1)" |
| 181 | line 413, "pan.___", state 84, "(1)" |
| 182 | line 413, "pan.___", state 84, "(1)" |
| 183 | line 417, "pan.___", state 97, "(1)" |
| 184 | line 417, "pan.___", state 98, "(1)" |
| 185 | line 417, "pan.___", state 98, "(1)" |
| 186 | line 415, "pan.___", state 103, "((i<2))" |
| 187 | line 415, "pan.___", state 103, "((i>=2))" |
| 188 | line 422, "pan.___", state 110, "(1)" |
| 189 | line 422, "pan.___", state 111, "(!(cache_dirty_urcu_gp_ctr))" |
| 190 | line 422, "pan.___", state 111, "else" |
| 191 | line 422, "pan.___", state 114, "(1)" |
| 192 | line 422, "pan.___", state 115, "(1)" |
| 193 | line 422, "pan.___", state 115, "(1)" |
| 194 | line 426, "pan.___", state 123, "(1)" |
| 195 | line 426, "pan.___", state 124, "(!(cache_dirty_urcu_active_readers))" |
| 196 | line 426, "pan.___", state 124, "else" |
| 197 | line 426, "pan.___", state 127, "(1)" |
| 198 | line 426, "pan.___", state 128, "(1)" |
| 199 | line 426, "pan.___", state 128, "(1)" |
| 200 | line 424, "pan.___", state 133, "((i<1))" |
| 201 | line 424, "pan.___", state 133, "((i>=1))" |
| 202 | line 431, "pan.___", state 140, "(1)" |
| 203 | line 431, "pan.___", state 141, "(!(cache_dirty_rcu_ptr))" |
| 204 | line 431, "pan.___", state 141, "else" |
| 205 | line 431, "pan.___", state 144, "(1)" |
| 206 | line 431, "pan.___", state 145, "(1)" |
| 207 | line 431, "pan.___", state 145, "(1)" |
| 208 | line 435, "pan.___", state 153, "(1)" |
| 209 | line 435, "pan.___", state 154, "(!(cache_dirty_rcu_data[i]))" |
| 210 | line 435, "pan.___", state 154, "else" |
| 211 | line 435, "pan.___", state 157, "(1)" |
| 212 | line 435, "pan.___", state 158, "(1)" |
| 213 | line 435, "pan.___", state 158, "(1)" |
| 214 | line 433, "pan.___", state 163, "((i<2))" |
| 215 | line 433, "pan.___", state 163, "((i>=2))" |
| 216 | line 443, "pan.___", state 167, "(1)" |
| 217 | line 443, "pan.___", state 167, "(1)" |
| 218 | line 268, "pan.___", state 176, "cache_dirty_urcu_gp_ctr = 0" |
| 219 | line 272, "pan.___", state 185, "cache_dirty_urcu_active_readers = 0" |
| 220 | line 276, "pan.___", state 198, "cache_dirty_rcu_ptr = 0" |
| 221 | line 404, "pan.___", state 238, "cache_dirty_urcu_gp_ctr = 0" |
| 222 | line 408, "pan.___", state 252, "cache_dirty_urcu_active_readers = 0" |
| 223 | line 413, "pan.___", state 270, "cache_dirty_rcu_ptr = 0" |
| 224 | line 417, "pan.___", state 284, "cache_dirty_rcu_data[i] = 0" |
| 225 | line 422, "pan.___", state 303, "(1)" |
| 226 | line 426, "pan.___", state 316, "(1)" |
| 227 | line 431, "pan.___", state 333, "(1)" |
| 228 | line 435, "pan.___", state 346, "(1)" |
| 229 | line 408, "pan.___", state 383, "cache_dirty_urcu_active_readers = 0" |
| 230 | line 413, "pan.___", state 401, "cache_dirty_rcu_ptr = 0" |
| 231 | line 417, "pan.___", state 415, "cache_dirty_rcu_data[i] = 0" |
| 232 | line 426, "pan.___", state 447, "(1)" |
| 233 | line 431, "pan.___", state 464, "(1)" |
| 234 | line 435, "pan.___", state 477, "(1)" |
| 235 | line 408, "pan.___", state 522, "cache_dirty_urcu_active_readers = 0" |
| 236 | line 413, "pan.___", state 540, "cache_dirty_rcu_ptr = 0" |
| 237 | line 417, "pan.___", state 554, "cache_dirty_rcu_data[i] = 0" |
| 238 | line 426, "pan.___", state 586, "(1)" |
| 239 | line 431, "pan.___", state 603, "(1)" |
| 240 | line 435, "pan.___", state 616, "(1)" |
| 241 | line 408, "pan.___", state 651, "cache_dirty_urcu_active_readers = 0" |
| 242 | line 413, "pan.___", state 669, "cache_dirty_rcu_ptr = 0" |
| 243 | line 417, "pan.___", state 683, "cache_dirty_rcu_data[i] = 0" |
| 244 | line 426, "pan.___", state 715, "(1)" |
| 245 | line 431, "pan.___", state 732, "(1)" |
| 246 | line 435, "pan.___", state 745, "(1)" |
| 247 | line 408, "pan.___", state 782, "cache_dirty_urcu_active_readers = 0" |
| 248 | line 413, "pan.___", state 800, "cache_dirty_rcu_ptr = 0" |
| 249 | line 417, "pan.___", state 814, "cache_dirty_rcu_data[i] = 0" |
| 250 | line 426, "pan.___", state 846, "(1)" |
| 251 | line 431, "pan.___", state 863, "(1)" |
| 252 | line 435, "pan.___", state 876, "(1)" |
| 253 | line 268, "pan.___", state 931, "cache_dirty_urcu_gp_ctr = 0" |
| 254 | line 272, "pan.___", state 940, "cache_dirty_urcu_active_readers = 0" |
| 255 | line 276, "pan.___", state 955, "(1)" |
| 256 | line 280, "pan.___", state 962, "cache_dirty_rcu_data[i] = 0" |
| 257 | line 245, "pan.___", state 978, "(1)" |
| 258 | line 249, "pan.___", state 986, "(1)" |
| 259 | line 253, "pan.___", state 998, "(1)" |
| 260 | line 257, "pan.___", state 1006, "(1)" |
| 261 | line 268, "pan.___", state 1037, "cache_dirty_urcu_gp_ctr = 0" |
| 262 | line 272, "pan.___", state 1046, "cache_dirty_urcu_active_readers = 0" |
| 263 | line 276, "pan.___", state 1059, "cache_dirty_rcu_ptr = 0" |
| 264 | line 280, "pan.___", state 1068, "cache_dirty_rcu_data[i] = 0" |
| 265 | line 245, "pan.___", state 1084, "(1)" |
| 266 | line 249, "pan.___", state 1092, "(1)" |
| 267 | line 253, "pan.___", state 1104, "(1)" |
| 268 | line 257, "pan.___", state 1112, "(1)" |
| 269 | line 272, "pan.___", state 1138, "cache_dirty_urcu_active_readers = 0" |
| 270 | line 276, "pan.___", state 1151, "cache_dirty_rcu_ptr = 0" |
| 271 | line 280, "pan.___", state 1160, "cache_dirty_rcu_data[i] = 0" |
| 272 | line 245, "pan.___", state 1176, "(1)" |
| 273 | line 249, "pan.___", state 1184, "(1)" |
| 274 | line 253, "pan.___", state 1196, "(1)" |
| 275 | line 257, "pan.___", state 1204, "(1)" |
| 276 | line 268, "pan.___", state 1235, "cache_dirty_urcu_gp_ctr = 0" |
| 277 | line 272, "pan.___", state 1244, "cache_dirty_urcu_active_readers = 0" |
| 278 | line 276, "pan.___", state 1257, "cache_dirty_rcu_ptr = 0" |
| 279 | line 280, "pan.___", state 1266, "cache_dirty_rcu_data[i] = 0" |
| 280 | line 245, "pan.___", state 1282, "(1)" |
| 281 | line 249, "pan.___", state 1290, "(1)" |
| 282 | line 253, "pan.___", state 1302, "(1)" |
| 283 | line 257, "pan.___", state 1310, "(1)" |
| 284 | line 272, "pan.___", state 1336, "cache_dirty_urcu_active_readers = 0" |
| 285 | line 276, "pan.___", state 1349, "cache_dirty_rcu_ptr = 0" |
| 286 | line 280, "pan.___", state 1358, "cache_dirty_rcu_data[i] = 0" |
| 287 | line 245, "pan.___", state 1374, "(1)" |
| 288 | line 249, "pan.___", state 1382, "(1)" |
| 289 | line 253, "pan.___", state 1394, "(1)" |
| 290 | line 257, "pan.___", state 1402, "(1)" |
| 291 | line 268, "pan.___", state 1433, "cache_dirty_urcu_gp_ctr = 0" |
| 292 | line 272, "pan.___", state 1442, "cache_dirty_urcu_active_readers = 0" |
| 293 | line 276, "pan.___", state 1455, "cache_dirty_rcu_ptr = 0" |
| 294 | line 280, "pan.___", state 1464, "cache_dirty_rcu_data[i] = 0" |
| 295 | line 245, "pan.___", state 1480, "(1)" |
| 296 | line 249, "pan.___", state 1488, "(1)" |
| 297 | line 253, "pan.___", state 1500, "(1)" |
| 298 | line 257, "pan.___", state 1508, "(1)" |
| 299 | line 272, "pan.___", state 1534, "cache_dirty_urcu_active_readers = 0" |
| 300 | line 276, "pan.___", state 1547, "cache_dirty_rcu_ptr = 0" |
| 301 | line 280, "pan.___", state 1556, "cache_dirty_rcu_data[i] = 0" |
| 302 | line 245, "pan.___", state 1572, "(1)" |
| 303 | line 249, "pan.___", state 1580, "(1)" |
| 304 | line 253, "pan.___", state 1592, "(1)" |
| 305 | line 257, "pan.___", state 1600, "(1)" |
| 306 | line 268, "pan.___", state 1631, "cache_dirty_urcu_gp_ctr = 0" |
| 307 | line 272, "pan.___", state 1640, "cache_dirty_urcu_active_readers = 0" |
| 308 | line 276, "pan.___", state 1653, "cache_dirty_rcu_ptr = 0" |
| 309 | line 280, "pan.___", state 1662, "cache_dirty_rcu_data[i] = 0" |
| 310 | line 245, "pan.___", state 1678, "(1)" |
| 311 | line 249, "pan.___", state 1686, "(1)" |
| 312 | line 253, "pan.___", state 1698, "(1)" |
| 313 | line 257, "pan.___", state 1706, "(1)" |
| 314 | line 1123, "pan.___", state 1722, "-end-" |
| 315 | (129 of 1722 states) |
| 316 | unreached in proctype :init: |
| 317 | line 1138, "pan.___", state 11, "((i<1))" |
| 318 | line 1138, "pan.___", state 11, "((i>=1))" |
| 319 | (1 of 26 states) |
| 320 | unreached in proctype :never: |
| 321 | line 1184, "pan.___", state 8, "-end-" |
| 322 | (1 of 8 states) |
| 323 | |
| 324 | pan: elapsed time 155 seconds |
| 325 | pan: rate 2784.0683 states/second |
| 326 | pan: avg transition delay 1.5479e-06 usec |
| 327 | cp .input.spin urcu_free_no_rmb.spin.input |
| 328 | cp .input.spin.trail urcu_free_no_rmb.spin.input.trail |
| 329 | make[1]: Leaving directory `/home/compudj/doc/userspace-rcu/formal-model/urcu-controldataflow-min-progress' |