Re: [squid-users] Problem with Squid-3.0.STABLE1 and ICAP

From: selvi <[email protected]>
Date: Sat, 05 Jan 2008 17:23:49 +0530

Hi Christos,

Thanks for your reply. I am posting the squid-icap server conversation
and also the debug messages here.

Christos Tsantilas wrote:
> Hi Selvi,
> selvi wrote:
>
>> Hello All,
>>
>> I am in the process of integrating squid-3.0.STABLE1 with Python based
>> ICAP Server. Here, i am not able to get the response modification.
>>
>
> Is it a custom ICAP server?
>

I am using the Python based ICAP Server.
>
>> ICAP related configurations given are:
>>
>> icap_enable on
>> icap_send_client_ip on
>> icap_service content respmod_precache 0 icap://<IP Address>:1344/respmod
>> icap_class class_1 content
>> icap_access class_1 allow all
>>
>> I am not seeing any error messages in the ICAP Server side. Squid also
>> behaves normally except the following message in cache.log
>> "essential ICAP service is invalidated by reconfigure: icap://<IP
>> Address>:1344/respmod [down,gone,!opt]". But when i perform some web
>> access through ICAP enabled squid proxy, empty page is displayed (the
>> browser displays nothing).
>>
>>
>
> This message means that the squid tried to send an options request to
> the ICAP server and the ICAP server answer was not correct (or just did
> not like to the squid)
> I am suggesting to enable debug for ICAP client in squid3:
> debug_options 93,9 0,9
> And search in debug messages to see what they say about. You can also
> post the debug messages here.
>
> Also grabbing the conversation between squid and ICAP server using
> wireshark or similar tool will be helpful...
>
> Regards,
> Christos
>
>
A snapshot of the conversation between squid and ICAP server is given
below (using tcpdump).

11:04:02.978996 IP squid225.kovaiteam.com.60111 >
squid225.kovaiteam.com.1344: S 2637131307:2637131307(0) win 32767 <mss
16396,sackOK,timestamp 1238745316 0,nop,wscale 8>
E..<..@.@.0
...........@./n+........+.....@....

    I...........
11:04:02.979009 IP squid225.kovaiteam.com.1344 >
squid225.kovaiteam.com.60111: S 2632376145:2632376145(0) ack 2637131308
win 32767 <mss 16396,sackOK,timestamp 1238745316 1238745316,nop,wscale 8>
E..<..@.@............@.....Q./n,..........@....

    I...I.......
11:04:02.979020 IP squid225.kovaiteam.com.60111 >
squid225.kovaiteam.com.1344: . ack 1 win 128 <nop,nop,timestamp
1238745316 1238745316>
E..4..@.@.0............@./n,...R.....A.....

    I...I...
11:04:02.981160 IP squid225.kovaiteam.com.60111 >
squid225.kovaiteam.com.1344: P 1:752(751) ack 1 win 128
<nop,nop,timestamp 1238745318 1238745316>
E..#..@.@.-............@./n,...R....^......
I...I...RESPMOD icap://172.16.1.225:1344/respmod ICAP/1.0
Host: 172.16.1.225:1344
Date: Fri, 04 Jan 2008 05:34:02 GMT
Encapsulated: req-hdr=0, res-hdr=277, res-body=546
Allow: 204
X-Client-IP: 172.16.1.34

GET http://172.16.1.225:8088/gui/test.html HTTP/1.0
Accept: */*
Accept-Language: en-us
Pragma: no-cache
User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1)
Host: 172.16.1.225:8088
Proxy-Connection: Keep-Alive
Authorization: Basic dmljYWNoZTp2aWNhY2hl

HTTP/1.1 200 OK
Date: Fri, 04 Jan 2008 05:34:02 GMT
Server: Apache/2.0.52 (Red Hat)
Last-Modified: Fri, 04 Jan 2008 05:25:31 GMT
ETag: "64cce8-1d-442debd2f28c0"
Accept-Ranges: bytes
Content-Length: 29
Connection: close
Content-Type: text/html; charset=UTF-8

    11:04:02.981170 IP squid225.kovaiteam.com.1344 >
squid225.kovaiteam.com.60111: . ack 752 win 128 <nop,nop,timestamp
1238745318 1238745318>
E..4..@.@..+.........@.....R./q......N.....

    I...I...
11:04:02.982351 IP squid225.kovaiteam.com.60111 >
squid225.kovaiteam.com.1344: P 752:792(40) ack 1 win 128
<nop,nop,timestamp 1238745319 1238745318>
E..\..@.@./............@./q....R....\1.....
I...I...1d
<html>
Hello Selvi
</html>

0

11:04:02.982360 IP squid225.kovaiteam.com.1344 >
squid225.kovaiteam.com.60111: . ack 792 win 128 <nop,nop,timestamp
1238745319 1238745319>
E..4..@.@..).........@.....R./qC.....$.....

    I...I...
11:04:02.987875 IP squid225.kovaiteam.com.1344 >
squid225.kovaiteam.com.60111: P 1:429(428) ack 792 win 128
<nop,nop,timestamp 1238745325 1238745319>
E.....@.@..{.........@.....R./qC....]......
I...I...ICAP/1.0 200 OK
Date: Fri, 04 Jan 2008 05:34:02 GMT
Encapsulated: res-hdr=0 res-body=269
Server: ICAP-Server-Software/1.0

HTTP/1.0 200 OK
Content-Length: 29
Accept-Ranges: bytes
Server: Apache/2.0.52 (Red Hat)
Last-Modified: Fri, 04 Jan 2008 05:25:31 GMT
Connection: close
ETag: "64cce8-1d-442debd2f28c0"
Date: Fri, 04 Jan 2008 05:34:02 GMT
Content-Type: text/html; charset=UTF-8

<html>
Hello Selvi
</html>

11:04:02.987889 IP squid225.kovaiteam.com.60111 >
squid225.kovaiteam.com.1344: . ack 429 win 128 <nop,nop,timestamp
1238745325 1238745325>
E..4..@.@.0
...........@./qC.........l.....

    I...I...
11:04:02.988074 IP squid225.kovaiteam.com.1344 >
squid225.kovaiteam.com.60111: F 429:429(0) ack 792 win 128
<nop,nop,timestamp 1238745325 1238745325>
E..4..@.@..%.........@......./qC.....k.....

    I...I...
11:04:02.993135 IP squid225.kovaiteam.com.60111 >
squid225.kovaiteam.com.1344: F 792:792(0) ack 430 win 128
<nop,nop,timestamp 1238745330 1238745325>
E..4..@.@.0............@./qC.........e.....

    I...I...
11:04:02.993148 IP squid225.kovaiteam.com.1344 >
squid225.kovaiteam.com.60111: . ack 793 win 128 <nop,nop,timestamp
1238745330 1238745330>
E..4..@.@..#.........@......./qD.....`.....

    I...I...

Debug Messages determined using ' debug_options 93,9 0,9' is given below

2008/01/05 15:19:41.342| ICAPAccessCheck constructed for RESPMOD PRECACHE
2008/01/05 15:19:41.342| ICAPAccessCheck::check
2008/01/05 15:19:41.342| ICAP/ICAPConfig.cc(277) looking for the first
matching service in class class_1
2008/01/05 15:19:41.342| ICAP/ICAPConfig.cc(315) found first matching
service in class class_1: vicontent
2008/01/05 15:19:41.342| ICAPAccessCheck::check: class 'class_1' has
candidate service 'vicontent'
2008/01/05 15:19:41.342| ICAPAccessCheckCallbackWrapper: answer=1
2008/01/05 15:19:41.342| ICAPAccessCheckCallbackWrapper matchedClass =
class_1
2008/01/05 15:19:41.342| ICAPAccessCheckCallbackEvent
2008/01/05 15:19:41.342| ICAPAccessCheck::do_callback
2008/01/05 15:19:41.343| ICAPAccessCheck::do_callback matchedClass = class_1
2008/01/05 15:19:41.343| ICAP/ICAPConfig.cc(277) looking for the first
matching up service in class class_1
2008/01/05 15:19:41.343| ICAP/ICAPConfig.cc(308) cannot skip an
essential down service
2008/01/05 15:19:41.343| ICAP/ICAPConfig.cc(315) found first matching
down-but-essential service in class class_1: vicontent
2008/01/05 15:19:41.343| ICAP/ICAPConfig.cc(262) do_callback: with
service icap://172.16.1.225:1344/respmod
2008/01/05 15:19:41.343| Server.cc(414) will send virgin reply body to
0x6000000000451288*2; size: 29
2008/01/05 15:19:41.343| ICAP/AsyncJob.cc(14) will call
AsyncJob::noteStart(0x6000000000455498)
2008/01/05 15:19:41.353| entering AsyncJob::noteStart(0x6000000000455498)
2008/01/05 15:19:41.353| ICAPModXactLauncher::noteStart called
2008/01/05 15:19:41.353| ICAP/ICAPLauncher.cc(35) launching xaction #1
2008/01/05 15:19:41.353| ICAPModXact constructed,
this=0x60000000004596b8 [icapx1]
2008/01/05 15:19:41.353| ICAPModXact initialized. [R/ icapx1]
2008/01/05 15:19:41.353| ICAP/AsyncJob.cc(14) will call
AsyncJob::noteStart(0x60000000004596b8)
2008/01/05 15:19:41.353| ICAPModXactLauncher::noteStart ended
2008/01/05 15:19:41.353| exiting AsyncJob::noteStart(0x6000000000455498)
2008/01/05 15:19:41.364| entering AsyncJob::noteStart(0x60000000004596b8)
2008/01/05 15:19:41.364| ICAPModXact::noteStart called [R/ icapx1]
2008/01/05 15:19:41.364| ICAPModXact expects virgin body from
0x6000000000451288*3; size: 29
2008/01/05 15:19:41.364| ICAPModXact will wait for the ICAP service [R/
icapx1]
2008/01/05 15:19:41.364| ICAP/ICAPServiceRep.cc(308) ICAPService is
asked to call 0x60000000004596b8 when ready [down,!opt]
2008/01/05 15:19:41.364| ICAPService will get new options [down,!opt]
2008/01/05 15:19:41.364| ICAP/AsyncJob.cc(14) will call
AsyncJob::noteStart(0x600000000045d8e8)
2008/01/05 15:19:41.364| ICAPModXact::noteStart ended [UR/ icapx1]
2008/01/05 15:19:41.364| ICAP/AsyncJob.cc(14) will call
AsyncJob::noteStart(0x600000000045d8e8)
2008/01/05 15:19:41.364| ICAPModXact::noteStart ended [UR/ icapx1]
2008/01/05 15:19:41.364| exiting AsyncJob::noteStart(0x60000000004596b8)
2008/01/05 15:19:41.375| ICAPModXact::noteMoreBodyDataAvailable called
[UR/ icapx1]
2008/01/05 15:19:41.375| ICAP/ICAPModXact.cc(182) checking whether to
write more [UR/ icapx1]
2008/01/05 15:19:41.375| ICAPModXact::noteMoreBodyDataAvailable ended
[UR/ icapx1]
2008/01/05 15:19:41.375| ICAPModXact::noteBodyProductionEnded called
[UR/ icapx1]
2008/01/05 15:19:41.375| ICAP/ICAPModXact.cc(182) checking whether to
write more [UR/ icapx1]
2008/01/05 15:19:41.375| ICAPModXact::noteBodyProductionEnded ended [UR/
icapx1]
2008/01/05 15:19:41.375| entering AsyncJob::noteStart(0x600000000045d8e8)
2008/01/05 15:19:41.375| ICAPOptXactLauncher::noteStart called
2008/01/05 15:19:41.375| ICAP/ICAPLauncher.cc(35) launching xaction #1
2008/01/05 15:19:41.375| ICAPOptXact constructed,
this=0x6000000000461b28 [icapx2]
2008/01/05 15:19:41.375| ICAP/AsyncJob.cc(14) will call
AsyncJob::noteStart(0x6000000000461b28)
2008/01/05 15:19:41.375| ICAPOptXactLauncher::noteStart ended
2008/01/05 15:19:41.375| exiting AsyncJob::noteStart(0x600000000045d8e8)
2008/01/05 15:19:41.385| entering AsyncJob::noteStart(0x6000000000461b28)
2008/01/05 15:19:41.385| ICAPOptXact::noteStart called [/ icapx2]
2008/01/05 15:19:41.385| ICAPOptXact becomes final [/ icapx2]
2008/01/05 15:19:41.385| ICAPOptXact opens connection to 172.16.1.225:1344
2008/01/05 15:19:41.385| ICAPOptXact::noteStart ended [FD 14;/ icapx2]
2008/01/05 15:19:41.385| exiting AsyncJob::noteStart(0x6000000000461b28)
2008/01/05 15:19:41.386| ICAPOptXact::noteCommConnected called [FD 14;/
icapx2]
2008/01/05 15:19:41.386| ICAPOptXact request [FD 14r;/ icapx2]:
OPTIONS icap://172.16.1.225:1344/respmod ICAP/1.0^M
Host: 172.16.1.225:1344^M
^M

2008/01/05 15:19:41.386| ICAPOptXact::noteCommConnected ended [FD 14wr;/
icapx2]
2008/01/05 15:19:41.386| ICAPOptXact::noteCommWrote called [FD 14wr;/
icapx2]
2008/01/05 15:19:41.386| ICAPOptXact finished writing 78-byte request
[FD 14r;/ icapx2]
2008/01/05 15:19:41.386| ICAPOptXact::noteCommWrote ended [FD 14r;/ icapx2]
2008/01/05 15:19:41.387| ICAP/ICAPXaction.cc(59) 0x6000000000461b28 read
returned 341
2008/01/05 15:19:41.387| ICAPOptXact::noteCommRead called [FD 14r;/ icapx2]
2008/01/05 15:19:41.387| ICAP/ICAPXaction.cc(339) read 341 bytes
2008/01/05 15:19:41.387| ICAPOptXact remains final [FD 14;/ icapx2]
2008/01/05 15:19:41.387| ICAP/ICAPOptXact.cc(71) have 341 bytes to parse
[FD 14;/ icapx2]
2008/01/05 15:19:41.387| ICAP/ICAPOptXact.cc(72)
ICAP/1.0 200 OK^M
Transfer-Preview: *^M
Preview: 200000^M
Methods: REQMOD, RESPMOD^M
Service: Python ICAP Server 1.2^M
Methods: REQMOD, RESPMOD^M
Service: Python ICAP Server 1.2^M
Service-ID: ???^M
X-Include: RESPMOD^M
Encapsulated: null-body=0^M
Options-TTL: 300^M
ISTAG: "001-000-000001"^M
X-Authenticated-User: ^M
Date: Sat, 05 Jan 2008 09:49:41 GMT^M
Max-Connections: 1000^M
Server: ICAP-Server-Software/1.0^M
^M

2008/01/05 15:19:41.387| ICAP/ICAPXaction.cc(377) have 341 head bytes to
parse
2008/01/05 15:19:41.387| ICAP/ICAPInitiate.cc(176) will call
0x600000000045d920->ICAPInitiator::noteIcapAnswer(0x60000000003e2cd0)
2008/01/05 15:19:41.387| ICAPOptXact::noteCommRead ends job [FD 14;/
icapx2]
2008/01/05 15:19:41.387| ICAP/ICAPXaction.cc(169) not reusing pconn due
to pending I/O [FD 14;/ icapx2]
2008/01/05 15:19:41.387| ICAP/ICAPXaction.cc(179) closing pconn [FD 14;/
icapx2]
2008/01/05 15:19:41.387| ICAP/ICAPInitiate.cc(82) swan sings [/ icapx2]
2008/01/05 15:19:41.387| ICAP/ICAPInitiate.cc(89) swan sang [/ icapx2]
2008/01/05 15:19:41.387| ICAPOptXact destructed, this=0x6000000000461b28
[icapx2]
2008/01/05 15:19:41.387| ICAP/AsyncJob.cc(106) ICAPOptXact::noteCommRead
ended 0x6000000000461b28
2008/01/05 15:19:41.398| entering
0x600000000045d920->ICAPInitiator::noteIcapAnswer(0x60000000003e2cd0)
2008/01/05 15:19:41.398| ICAPOptXactLauncher::noteIcapAnswer called
2008/01/05 15:19:41.398| ICAP/ICAPInitiate.cc(176) will call
0x60000000001777a8->ICAPInitiator::noteIcapAnswer(0x60000000003e2cd0)
2008/01/05 15:19:41.398| ICAPOptXactLauncher::noteIcapAnswer ends job
2008/01/05 15:19:41.398| ICAP/ICAPInitiate.cc(82) swan sings
2008/01/05 15:19:41.398| ICAP/ICAPInitiate.cc(89) swan sang
2008/01/05 15:19:41.398| ICAP/AsyncJob.cc(106)
ICAPOptXactLauncher::noteIcapAnswer ended 0x600000000045d8e8
2008/01/05 15:19:41.398| exiting
0x600000000045d920->ICAPInitiator::noteIcapAnswer(0x60000000003e2cd0)
2008/01/05 15:19:41.409| entering
0x60000000001777a8->ICAPInitiator::noteIcapAnswer(0x60000000003e2cd0)
2008/01/05 15:19:41.409| ICAPService is interpreting new options [down,!opt]
2008/01/05 15:19:41.409| ICAPOptions::cfgIntHeader Max-Connections: 1000
2008/01/05 15:19:41.409| ICAPOptions::cfgIntHeader Options-TTL: 300
2008/01/05 15:19:41.409| ICAPOptions::cfgIntHeader Preview: 200000
2008/01/05 15:19:41.409| ICAPOptions::cfgTransferList: set default
transfer to Transfer-Preview
2008/01/05 15:19:41.409| ICAPOptions::cfgTransferList: no
Transfer-Preview extensions
2008/01/05 15:19:41.409| ICAPOptions::cfgTransferList: no
Transfer-Ignore extensions
2008/01/05 15:19:41.409| ICAPOptions::cfgTransferList: no
Transfer-Complete extensions
2008/01/05 15:19:41.409| ICAPService changes options from 0 to
0x600000000045d7f0 [down,!opt]
2008/01/05 15:19:41.409| ICAPService got new options and is now [up]
2008/01/05 15:19:41.409| ICAPService changes options from 0 to
0x600000000045d7f0 [down,!opt]
2008/01/05 15:19:41.409| ICAPService got new options and is now [up]
2008/01/05 15:19:41.409| ICAPService options expire on 1199526881 >=
1199526581
2008/01/05 15:19:41.409| ICAP/ICAPServiceRep.cc(487) raw OPTIONS fetch
at 1199526861 or in 280 sec
2008/01/05 15:19:41.409| ICAP/ICAPServiceRep.cc(489) last fetched at
1199526581 or 0 sec ago
2008/01/05 15:19:41.409| ICAPService will fetch OPTIONS in 280 sec
2008/01/05 15:19:41.409| ICAPService will notify 1 clients
2008/01/05 15:19:41.409| exiting
0x60000000001777a8->ICAPInitiator::noteIcapAnswer(0x60000000003e2cd0)
2008/01/05 15:19:41.419| ICAPService notifies 1 clients [up,notif]
2008/01/05 15:19:41.419| ICAPModXact::noteServiceReady called [UR/ icapx1]
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(1192) preview disabled by
squid.conf
2008/01/05 15:19:41.420| ICAPModXact becomes final [Rw(1)/ icapx1]
2008/01/05 15:19:41.420| ICAPModXact opens connection to 172.16.1.225:1344
2008/01/05 15:19:41.420| ICAPModXact::noteServiceReady ended [FD
14;Rrw(1)/ icapx1]
2008/01/05 15:19:41.420| ICAPModXact::noteCommConnected called [FD
14;Rrw(1)/ icapx1]
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(1135) will allow 204s
outside of preview
2008/01/05 15:19:41.420| ICAPModXact ICAP will write [FD 14r;Rrw(1)B/
icapx1]:
RESPMOD icap://172.16.1.225:1344/respmod ICAP/1.0^M
Host: 172.16.1.225:1344^M
Date: Sat, 05 Jan 2008 09:49:41 GMT^M
Encapsulated: req-hdr=0, res-hdr=277, res-body=546^M
Allow: 204^M
X-Client-IP: 172.16.1.34^M
^M
GET http://172.16.1.225:8088/gui/test.html HTTP/1.0^M
Accept: */*^M
Accept-Language: en-us^M
Pragma: no-cache^M
User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1)^M
Host: 172.16.1.225:8088^M
Proxy-Connection: Keep-Alive^M
Authorization: Basic dmljYWNoZTp2aWNhY2hl^M
^M
HTTP/1.1 200 OK^M
Date: Sat, 05 Jan 2008 09:49:41 GMT^M
Server: Apache/2.0.52 (Red Hat)^M
Last-Modified: Sat, 05 Jan 2008 07:22:49 GMT^M
ETag: "64cce7-1d-442f47e85e440"^M
Accept-Ranges: bytes^M
Content-Length: 29^M
Connection: close^M
Content-Type: text/html; charset=UTF-8^M
^M
M

2008/01/05 15:19:41.420| ICAPModXact::noteCommConnected ended [FD
14wr;Rrw(2)B/ icapx1]
2008/01/05 15:19:41.420| ICAPModXact::noteCommWrote called [FD
14wr;Rrw(2)B/ icapx1]
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(154) Wrote 751 bytes
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(182) checking whether to
write more [FD 14r;Rrw(5)B/ icapx1]
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(260) will write up to 29
bytes of prime virgin body
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(271) will write 29-byte
chunk of prime virgin body
2008/01/05 15:19:41.420| consumption guards: 00
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(383) max virgin consumption
offset=29 acts 11 consumed=0 from [0<=29<=29 29+2018
pipe0x6000000000451288 cons0x6000000000459768]
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(291) will write last-chunk
of prime virgin body
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(296) will write 40 raw
bytes of prime virgin body
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(250) wrote entire body
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(418) will wait for the last
write [FD 14wr;Rrw(5)B/ icapx1]
2008/01/05 15:19:41.420| ICAPModXact::noteCommWrote ended [FD
14wr;Rrw(6)B/ icapx1]
2008/01/05 15:19:41.420| ICAPModXact::noteCommWrote called [FD
14wr;Rrw(6)B/ icapx1]
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(154) Wrote 40 bytes
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(182) checking whether to
write more [FD 14r;Rrw(6)B/ icapx1]
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(434) will no longer write
[FD 14r;Rrw(6)B/ icapx1]
2008/01/05 15:19:41.420| consumption guards: 00
2008/01/05 15:19:41.420| ICAP/ICAPModXact.cc(383) max virgin consumption
offset=29 acts 01 consumed=0 from [0<=29<=29 29+2018
pipe0x6000000000451288 cons0x6000000000459768]
2008/01/05 15:19:41.420| ICAPModXact::noteCommWrote ended [FD 14r;RrB/w
icapx1]
2008/01/05 15:19:41.425| ICAP/ICAPXaction.cc(59) 0x60000000004596b8 read
returned 428
2008/01/05 15:19:41.425| ICAPModXact::noteCommRead called [FD 14r;RrB/w
icapx1]
2008/01/05 15:19:41.425| ICAP/ICAPXaction.cc(339) read 428 bytes
2008/01/05 15:19:41.425| ICAPModXact remains final [FD 14;RrB/w icapx1]
2008/01/05 15:19:41.425| ICAP/ICAPModXact.cc(574) have 428 bytes to
parse [FD 14;RrB/w icapx1]
2008/01/05 15:19:41.425| ICAP/ICAPModXact.cc(575)
ICAP/1.0 200 OK^M
Date: Sat, 05 Jan 2008 09:49:41 GMT^M
Encapsulated: res-hdr=0 res-body=269^M
Server: ICAP-Server-Software/1.0^M
^M
HTTP/1.0 200 OK^M
Content-Length: 29^M
Accept-Ranges: bytes^M
Server: Apache/2.0.52 (Red Hat)^M
Last-Modified: Sat, 05 Jan 2008 07:22:49 GMT^M
Connection: close^M
ETag: "64cce7-1d-442f47e85e440"^M
Connection: close^M
ETag: "64cce7-1d-442f47e85e440"^M
Date: Sat, 05 Jan 2008 09:49:41 GMT^M
Content-Type: text/html; charset=UTF-8^M
^M
<html>
Hello Selvi
</html>

^M

2008/01/05 15:19:41.426| ICAP/ICAPModXact.cc(653) parse ICAP headers
2008/01/05 15:19:41.426| ICAP/ICAPModXact.cc(882) have 428 head bytes to
parse; state: 0
2008/01/05 15:19:41.426| ICAP/ICAPModXact.cc(894) parse success, consume
128 bytes, return true
2008/01/05 15:19:41.426| ICAPModXact will no longer backup [FD
14;RrBp(1)S(2)/w icapx1]
2008/01/05 15:19:41.426| consumption guards: 00
2008/01/05 15:19:41.426| ICAP/ICAPModXact.cc(383) max virgin consumption
offset=29 acts 00 consumed=0 from [0<=29<=29 29+2018
pipe0x6000000000451288 cons0x6000000000459768]
2008/01/05 15:19:41.426| ICAP/ICAPModXact.cc(395) consuming 29 out of 29
virgin body bytes
2008/01/05 15:19:41.426| ICAP/ICAPModXact.cc(567) will stop consuming
[FD 14;Rrp(1)S(2)/w icapx1]
2008/01/05 15:19:41.426| ICAP/ICAPModXact.cc(658) parse HTTP headers
2008/01/05 15:19:41.426| ICAP/ICAPModXact.cc(882) have 300 head bytes to
parse; state: 1
2008/01/05 15:19:41.426| ICAP/ICAPModXact.cc(894) parse success, consume
269 bytes, return true
2008/01/05 15:19:41.426| ICAP/ICAPModXact.cc(926) not expecting a body
2008/01/05 15:19:41.426| ICAPModXact will no longer parse [FD
14;rp(1)S(2)/Rw icapx1]
2008/01/05 15:19:41.426| ICAPModXact will no longer send [FD
14;S(2)/Rwrp icapx1]
2008/01/05 15:19:41.426| ICAP/ICAPInitiate.cc(176) will call
0x60000000004554d0->ICAPInitiator::noteIcapAnswer(0x60000000003e2cd0)
2008/01/05 15:19:41.426| ICAP/ICAPModXact.cc(474) returning from
readMore because reader or doneReading()
2008/01/05 15:19:41.426| ICAP/ICAPXaction.cc(285) ICAPModXact done with
I/O [FD 14;/RwrpS icapx1]
2008/01/05 15:19:41.426| ICAP/ICAPXaction.cc(174) pushing pconn [FD
14;/RwrpS icapx1]
2008/01/05 15:19:41.426| ICAPModXact remains final [FD 14;/RwrpS icapx1]
2008/01/05 15:19:41.426| ICAPModXact::noteCommRead ends job [/RwrpS icapx1]
2008/01/05 15:19:41.426| ICAP/ICAPModXact.cc(1062) swan sings [/RwrpS
icapx1]
2008/01/05 15:19:41.426| ICAP/ICAPInitiate.cc(82) swan sings [/RwrpS icapx1]
2008/01/05 15:19:41.426| ICAP/ICAPInitiate.cc(89) swan sang [/RwrpS icapx1]
2008/01/05 15:19:41.426| ICAPModXact destructed, this=0x60000000004596b8
[icapx1]
2008/01/05 15:19:41.426| ICAP/AsyncJob.cc(106) ICAPModXact::noteCommRead
ended 0x60000000004596b8
2008/01/05 15:19:41.426| entering
0x60000000004554d0->ICAPInitiator::noteIcapAnswer(0x60000000003e2cd0)
2008/01/05 15:19:41.426| ICAPModXactLauncher::noteIcapAnswer called
2008/01/05 15:19:41.426| ICAP/ICAPInitiate.cc(176) will call
0x600000000044c618->ICAPInitiator::noteIcapAnswer(0x60000000003e2cd0)
2008/01/05 15:19:41.426| ICAPModXactLauncher::noteIcapAnswer called
2008/01/05 15:19:41.426| ICAP/ICAPInitiate.cc(176) will call
0x600000000044c618->ICAPInitiator::noteIcapAnswer(0x60000000003e2cd0)
2008/01/05 15:19:41.426| ICAPModXactLauncher::noteIcapAnswer ends job
2008/01/05 15:19:41.427| ICAP/ICAPInitiate.cc(82) swan sings
2008/01/05 15:19:41.427| ICAP/ICAPInitiate.cc(89) swan sang
2008/01/05 15:19:41.427| ICAP/AsyncJob.cc(106)
ICAPModXactLauncher::noteIcapAnswer ended 0x6000000000455498
2008/01/05 15:19:41.427| exiting
0x60000000004554d0->ICAPInitiator::noteIcapAnswer(0x60000000003e2cd0)
2008/01/05 15:19:41.437| entering
0x600000000044c618->ICAPInitiator::noteIcapAnswer(0x60000000003e2cd0)
2008/01/05 15:19:41.437| exiting
0x600000000044c618->ICAPInitiator::noteIcapAnswer(0x60000000003e2cd0)

Thanks
Selvi
>> The same setup with squid-2.5.STABLE13 works properly (the page is
>> displayed).
>>
>> What could be the problem?
>>
>> Thanks for your assistance.
>>
>> Thanks
>> Selvi
>>
>
>
>
Received on Sat Jan 05 2008 - 04:54:41 MST

This archive was generated by hypermail pre-2.1.9 : Fri Feb 01 2008 - 12:00:04 MST