problems with 1.1.1

From: Oskar Pearson <oskar@dont-contact.us>
Date: Sat, 11 Jan 1997 11:24:50 +0200 (GMT)

Hi there

I am having a couple of problems with squid 1.1.1:
I have been running this version for a few weeks without hassles on
a linux machine, kernel 2.0.24

Yesterday our second cache (we load balance between two cache machines
just hung up (stopped accepting connections). An strace showed:

read(898, "\345\360\3433\34!\226\267]h\362\227"..., 65535) = 1460
gettimeofday({852886178, 207491}, NULL) = 0
gettimeofday({852886178, 207842}, NULL) = 0
oldselect(927, [4 5 6 7 16 18 20 21 23 28 32 33 34 36 44 47 48 50 55 60 61 62 67
 68 70 71 73 74 79 81 82 84 85 86 87 89 90 92 100 101 102 108 109 110 112 116 11
7 118 121 123 124 125 126 127 128 131 132 133 134 135 137 138 142 147 148 151 15
4 155 161 165 169 170 175 176 178 179 181 182 183 184 186 187 189 191 194 199 20
1 202 204 216 218 222 231 232 235 237 238 244 248 249 250 252 253 256 257 258 26
2 264 265 266 269 270 271 273 275 279 281 282 286 287 290 292 293 294 300 301 30
2 303 307 311 312 313 314 315 317 320 321 324 325 328 334 335 336 342 343 350 35
3 354 355 364 365 366 367 368 369 372 373 375 376 382 384 385 387 390 410 411 41
2 416 417 423 424 430 431 432 434 437 439 440 441 445 448 451 453 454 456 468 47
0 471 472 474 475 481 482 483 484 485 488 493 496 498 502 504 505 507 508 509 51
2 514 520 521 525 526 528 529 531 532 533 534 539 540 542 543 545 546 552 553 55
8 560 561 562 565 567 568 571 573 575 577 579 587 588 592 593 594 595 600 603 60
5 612 613 616 620 621 622 624 625 628 630 631 633 634 640 641 645 646 650 651 65
2 655 657 658 660 664 666 667 678 680 682 687 690 694 695 696 697 698 700 702 70
6 707 709 710 711 712 714 718 722 728 730 731 732 733 735 738 741 755 761 762 76
5 767 770 776 778 783 784 787 791 792 793 794 796 797 798 799 800 804 807 813 81
6 817 820 821 825 828 829 831 832 834 837 839 840 841 843 845 846 849 854 856 86
0 861 862 864 866 869 870 873 877 880 881 882 883 884 887 888 890 891 893 896 898 902 903 904 905 906 908 909 910 911 913 914 915 918 920 921 923 924 926], [24
25 26 27 30 35 37 38 40 41 42 43 45 52 53 54 56 59 63 64 66 72 75 77 78 91 92 93
 95 99 104 111 115 120 122 129 130 136 139 140 141 143 144 145 146 149 150 152 1
53 156 157 158 159 160 162 163 164 166 167 168 171 172 173 174 180 185 188 190 1
92 195 196 197 198 200 203 205 206 207 208 209 210 211 212 213 214 217 219 220 2
21 223 225 226 228 229 230 234 239 240 242 243 247 251 261 263 267 268 272 276 2
77 278 280 283 284 285 288 291 295 296 297 299 304 306 308 309 311 316 319 322 3
23 327 332 339 341 345 347 348 349 352 356 360 361 362 363 368 371 373 374 379 3
83 388 391 392 396 399 401 406 407 408 409 410 413 414 418 419 420 421 422 425 4
28 429 435 438 443 444 446 447 449 457 458 459 464 466 469 473 476 477 478 479 4
95 501 510 511 513 517 518 524 535 538 547 548 551 554 556 559 570 572 576 581 5
82 585 586 590 591 596 597 598 601 602 604 607 608 610 614 618 619 623 626 629 6
32 636 637 642 644 647 653 654 656 659 661 670 672 673 677 679 685 686 688 701 7
03 704 708 715 716 724 726 727 729 737 740 744 745 746 748 750 751 752 753 757 7
58 759 760 763 764 766 768 769 773 775 780 781 782 789 790 802 803 805 818 819 8
22 823 824 826 830 835 836 838 842 843 848 850 851 852 853 858 865 867 871 872 8
75 878 879 885 886 889 892 894 895 900 901], NULL, {1, 0}) = 4 (in [201 336 365]
, out [843], left {0, 990000})
gettimeofday({852886178, 222081}, NULL) = 0
oldselect(21, [20], [], NULL, {0, 0}) = 0 (Timeout)
gettimeofday({852886178, 223780}, NULL) = 0
oldselect(21, [20], [], NULL, {0, 0}) = 0 (Timeout)
gettimeofday({852886178, 225123}, NULL) = 0
oldselect(21, [20], [], NULL, {0, 0}) = 0 (Timeout)
gettimeofday({852886178, 226426}, NULL) = 0
oldselect(21, [20], [], NULL, {0, 0}) = 0 (Timeout)
write(843, "\345\360\3433\34!\226\267]h\362\227"..., 1460) = 1460
gettimeofday({852886178, 229118}, NULL) = 0

over and over again. (ie Select didn't seem to show that there was any activity on any open filehandles)

with a few
unlink("/usr/local/squid/cache/04/0B/0004C0B4") = 0
unlink("/usr/local/squid/cache/09/71/00068719") = 0
unlink("/usr/local/squid/cache/06/F1/00063F16") = 0
unlink("/usr/local/squid/cache/0A/57/0005557A") = 0
unlink("/usr/local/squid/cache/06/16/00058166") = 0
unlink("/usr/local/squid/cache/0C/30/0006330C") = 0
unlink("/usr/local/squid/cache/02/72/0004F722") = 0
unlink("/usr/local/squid/cache/0B/2D/000572DB") = 0
gettimeofday({852887256, 118399}, NULL) = 0

here and there

Of course this may be related to my patch to get linux to do more that 256
filehandles. Stangely our other squid then started acting up, and I rebooted
both the machines.

The other thing is:

Cache 1 runs squid 1.0.18
newt:~/squid/echoping> ./echoping -n 5 -h http://www.vodacom.co.za/ cache1:8080
Elapsed time: 0.164789 seconds
Elapsed time: 0.401407 seconds
Elapsed time: 0.304631 seconds
Elapsed time: 0.100538 seconds
Elapsed time: 0.142523 seconds

---
Minimum time: 0.100538 seconds (2546 bytes per sec.)
Maximum time: 0.401407 seconds (638 bytes per sec.)
Average time: 0.222777 seconds (1149 bytes per sec.)
Median time: 0.164789 seconds (1554 bytes per sec.)
Cache2 runs 1.1.1
newt:~/squid/echoping> ./echoping -n 5 -h http://www.vodacom.co.za/ cache2:8080
Elapsed time: 3.242044 seconds
Elapsed time: 3.804418 seconds
Elapsed time: 3.010914 seconds
Elapsed time: 3.101601 seconds
Elapsed time: 3.144090 seconds
---
Minimum time: 3.010914 seconds (85 bytes per sec.)
Maximum time: 3.804418 seconds (67 bytes per sec.)
Average time: 3.260613 seconds (79 bytes per sec.)
Median time: 3.144090 seconds (81 bytes per sec.)
newt:~/squid/echoping> 
Clearly squid 1.1 is getting the page from the real site every time, with
the logs showing
852973763.314   3115 196.23.0.84 TCP_REFRESH_MISS/200 4716 GET http://www.vodacom.co.za/ - DIRECT/www.vodacom.co.za text/html
But if I look at the headers there doesn't seem to be any "meta" info... and
cache manager shows:
17.http://www.vodacom.co.za/
      Verified 27Y ago, Used 3s ago, Modified 19s ago, Expires 27Y ago,
      0 bytes, 1 accesses, 1 active clients,
      STORE_PENDING, NOT_IN_MEMORY, NO_SWAP, PING_NONE,
      2 Locks, Flags: EC,KC,HT,ED,KP,HI
Any ideas?
Oskar
Received on Sat Jan 11 1997 - 01:31:52 MST

This archive was generated by hypermail pre-2.1.9 : Tue Dec 09 2003 - 16:34:03 MST