[squid-users] Vary object loop returns

Yuri yvoinov at gmail.com
Tue May 31 14:39:34 UTC 2016


Bad test. Problem is with swapped onto disk objects exactly. So, when we 
disable cache_dirs, problem is gone.


31.05.2016 20:36, Heiler Bemerguy пишет:
>
>
> Just did a test here.
>
> Disabled every cache_dir and fully restarted squid 3.5.19
>
> No more "vary object loop", but these continues:
>
> 2016/05/31 11:35:13 kid2| Bug: Missing MemObject::storeId value
> 2016/05/31 11:35:13 kid2| mem_hdr: 0x3177f40 nodes.start() 0x7370880
> 2016/05/31 11:35:13 kid2| mem_hdr: 0x3177f40 nodes.finish() 0x7370880
> 2016/05/31 11:35:13 kid2| MemObject->start_ping: 0.000000
> 2016/05/31 11:35:13 kid2| MemObject->inmem_hi: 2224
> 2016/05/31 11:35:13 kid2| MemObject->inmem_lo: 0
> 2016/05/31 11:35:13 kid2| MemObject->nclients: 0
> 2016/05/31 11:35:13 kid2| MemObject->reply: 0x29711e0
> 2016/05/31 11:35:13 kid2| MemObject->request: 0
> 2016/05/31 11:35:13 kid2| MemObject->logUri:
> 2016/05/31 11:35:13 kid2| MemObject->storeId:
> 2016/05/31 11:35:14 kid1| Bug: Missing MemObject::storeId value
> 2016/05/31 11:35:14 kid1| mem_hdr: 0x3e0d990 nodes.start() 0x1ca70540
> 2016/05/31 11:35:14 kid1| mem_hdr: 0x3e0d990 nodes.finish() 0x1ca70540
> 2016/05/31 11:35:14 kid1| MemObject->start_ping: 0.000000
> 2016/05/31 11:35:14 kid1| MemObject->inmem_hi: 193
> 2016/05/31 11:35:14 kid1| MemObject->inmem_lo: 0
> 2016/05/31 11:35:14 kid1| MemObject->nclients: 0
> 2016/05/31 11:35:14 kid1| MemObject->reply: 0x45656e0
> 2016/05/31 11:35:14 kid1| MemObject->request: 0
> 2016/05/31 11:35:14 kid1| MemObject->logUri:
> 2016/05/31 11:35:14 kid1| MemObject->storeId:
> 2016/05/31 11:35:14.418 kid1| clientProcessHit: URL mismatch, 
> '[unknown_URI]' != 'http://captive.apple.com/hotspot-detect.html'
> 2016/05/31 11:35:14 kid2| Bug: Missing MemObject::storeId value
> 2016/05/31 11:35:14 kid2| mem_hdr: 0x336b090 nodes.start() 0x2b136d0
> 2016/05/31 11:35:14 kid2| mem_hdr: 0x336b090 nodes.finish() 0x82709c0
> 2016/05/31 11:35:14 kid2| MemObject->start_ping: 0.000000
> 2016/05/31 11:35:14 kid2| MemObject->inmem_hi: 4573
> 2016/05/31 11:35:14 kid2| MemObject->inmem_lo: 0
> 2016/05/31 11:35:14 kid2| MemObject->nclients: 0
> 2016/05/31 11:35:14 kid2| MemObject->reply: 0x26bdf50
> 2016/05/31 11:35:14 kid2| MemObject->request: 0
> 2016/05/31 11:35:14 kid2| MemObject->logUri:
> 2016/05/31 11:35:14 kid2| MemObject->storeId:
>
>
> -- 
> Best Regards,
>
> Heiler Bemerguy
> Network Manager - CINBESA
> 55 91 98151-4894/3184-1751
>
> Em 31/05/2016 11:14, Yuri escreveu:
>> Without collapsed_forwarding seems ok:
>>
>> https://i1.someimage.com/E8pM9tu.png
>>
>> no warnings/errors in cache.log.
>>
>> Seems Heiler right.
>>
>> 31.05.2016 19:33, joe пишет:
>>> load this link on both browser chrome  and firefox
>>> 'http://js.statig.com.br/pub/adtags.js'
>>> keep clicking reload  on both browser  and look at your cache.log
>>> with or without collapsed_forwarding
>>>
>>>
>>> Heiler Bemerguy wrote
>>>> Yeah, I know. This kind of stuff only appeared after enabling
>>>> collapsed_forwarding, I think. Now it's off again.. but I didn't wipe
>>>> the cache too, so I don't know.....
>>>>
>>>> 2016/05/31 10:50:24 kid2| varyEvaluateMatch: Oops. Not a Vary match on
>>>> second attempt, 'http://js.statig.com.br/pub/adtags.js'
>>>> 'accept-encoding="gzip,%20deflate"'
>>>> 2016/05/31 10:50:24 kid2| clientProcessHit: Vary object loop!
>>>> 2016/05/31 10:50:25 kid2| Bug: Missing MemObject::storeId value
>>>> 2016/05/31 10:50:25 kid2| mem_hdr: 0x20573e10 nodes.start() 0x117e4ce0
>>>> 2016/05/31 10:50:25 kid2| mem_hdr: 0x20573e10 nodes.finish() 
>>>> 0x117e4ce0
>>>> 2016/05/31 10:50:25 kid2| MemObject->start_ping: 0.000000
>>>> 2016/05/31 10:50:25 kid2| MemObject->inmem_hi: 2155
>>>> 2016/05/31 10:50:25 kid2| MemObject->inmem_lo: 0
>>>> 2016/05/31 10:50:25 kid2| MemObject->nclients: 0
>>>> 2016/05/31 10:50:25 kid2| MemObject->reply: 0x7db9ce0
>>>> 2016/05/31 10:50:25 kid2| MemObject->request: 0
>>>> 2016/05/31 10:50:25 kid2| MemObject->logUri:
>>>> 2016/05/31 10:50:25 kid2| MemObject->storeId:
>>>>
>>>>
>>>> -- 
>>>> Best Regards,
>>>>
>>>> Heiler Bemerguy
>>>> Network Manager - CINBESA
>>>> 55 91 98151-4894/3184-1751
>>>>
>>>>
>>>> Em 31/05/2016 10:34, Yuri escreveu:
>>>>> This is too expensive action. Cold out cache will decrease hit-ratio
>>>>> for weeks.
>>>>>
>>>>>
>>>>> Also, I suggest force reloading objects must overwrite stale 
>>>>> cached, no?
>>>>>
>>>>>
>>>>> 31.05.2016 19:32, Heiler Bemerguy пишет:
>>>>>>
>>>>>> I swear this bug was only rarely triggered without
>>>>>> collapsed_forwarding enabled... but I think you'll have to wipe your
>>>>>> cache to really test it...
>>>>>>
>>>>>>
>>>>>>
>>>>>> -- 
>>>>>> Heiler Bemerguy - (91) 98151-4894
>>>>>> Assessor Técnico - CINBESA (91) 3184-1751
>>>>>>
>>>>>> Em 31/05/2016 08:00, Yuri escreveu:
>>>>>>> Heh,
>>>>>>>
>>>>>>> the issue occurs also with disabled collapsed_forwarding:
>>>>>>>
>>>>>>> 2016/05/31 16:58:12 kid1| varyEvaluateMatch: Oops. Not a Vary match
>>>>>>> on second attempt,
>>>>>>> 'https://ru.wikipedia.org/w/load.php?debug=false&lang=ru&modules=jquery.accessKeyLabel%2Cclient%7Cmediawiki.RegExp%2Cnotify%2Cutil%7Cmediawiki.legacy.wikibits&skin=vector&version=b5e9abd3b463' 
>>>>>>>
>>>>>>> 'accept-encoding="gzip,%20deflate,%20sdch,%20br"'
>>>>>>> 2016/05/31 16:58:12 kid1| clientProcessHit: Vary object loop!
>>>>>>> 2016/05/31 16:58:12 kid1| Error negotiating SSL on FD 260:
>>>>>>> error:14090086:SSL routines:SSL3_GET_SERVER_CERTIFICATE:certificate
>>>>>>> verify failed (1/-1/0)
>>>>>>> 2016/05/31 16:58:12 kid1| varyEvaluateMatch: Oops. Not a Vary match
>>>>>>> on second attempt,
>>>>>>> 'https://ru.wikipedia.org/w/index.php?title=MediaWiki:Secure.js&action=raw&ctype=text/javascript' 
>>>>>>>
>>>>>>> 'accept-encoding="gzip,%20deflate,%20sdch,%20br",
>>>>>>> cookie="WMF-Last-Access%3D31-May-2016%3B%20GeoIP%3DKZ%3A02%3AAlmaty%3A43.26%3A76.93%3Av4", 
>>>>>>>
>>>>>>> authorization'
>>>>>>> 2016/05/31 16:58:12 kid1| clientProcessHit: Vary object loop!
>>>>>>> 2016/05/31 16:58:12 kid1| varyEvaluateMatch: Oops. Not a Vary match
>>>>>>> on second attempt,
>>>>>>> 'https://ru.wikipedia.org/w/index.php?title=MediaWiki:Osm.js&action=raw&ctype=text/javascript' 
>>>>>>>
>>>>>>> 'accept-encoding="gzip,%20deflate,%20sdch,%20br",
>>>>>>> cookie="WMF-Last-Access%3D31-May-2016%3B%20GeoIP%3DKZ%3A02%3AAlmaty%3A43.26%3A76.93%3Av4", 
>>>>>>>
>>>>>>> authorization'
>>>>>>> 2016/05/31 16:58:12 kid1| clientProcessHit: Vary object loop!
>>>>>>> 2016/05/31 16:58:13 kid1| varyEvaluateMatch: Oops. Not a Vary match
>>>>>>> on second attempt,
>>>>>>> 'https://ru.wikipedia.org/w/index.php?title=MediaWiki:Sidebar-related.js&action=raw&ctype=text/javascript' 
>>>>>>>
>>>>>>> 'accept-encoding="gzip,%20deflate,%20sdch,%20br",
>>>>>>> cookie="WMF-Last-Access%3D31-May-2016%3B%20GeoIP%3DKZ%3A02%3AAlmaty%3A43.26%3A76.93%3Av4", 
>>>>>>>
>>>>>>> authorization'
>>>>>>> 2016/05/31 16:58:13 kid1| clientProcessHit: Vary object loop!
>>>>>>> 2016/05/31 16:58:13 kid1| varyEvaluateMatch: Oops. Not a Vary match
>>>>>>> on second attempt,
>>>>>>> 'https://ru.wikipedia.org/w/index.php?title=MediaWiki:Wikibugs.js&action=raw&ctype=text/javascript' 
>>>>>>>
>>>>>>> 'accept-encoding="gzip,%20deflate,%20sdch,%20br",
>>>>>>> cookie="WMF-Last-Access%3D31-May-2016%3B%20GeoIP%3DKZ%3A02%3AAlmaty%3A43.26%3A76.93%3Av4", 
>>>>>>>
>>>>>>> authorization'
>>>>>>> 2016/05/31 16:58:13 kid1| clientProcessHit: Vary object loop!
>>>>>>> 2016/05/31 16:58:13 kid1| varyEvaluateMatch: Oops. Not a Vary match
>>>>>>> on second attempt,
>>>>>>> 'https://ru.wikipedia.org/w/index.php?title=MediaWiki:Interwiki-links.js&action=raw&ctype=text/javascript' 
>>>>>>>
>>>>>>> 'accept-encoding="gzip,%20deflate,%20sdch,%20br",
>>>>>>> cookie="WMF-Last-Access%3D31-May-2016%3B%20GeoIP%3DKZ%3A02%3AAlmaty%3A43.26%3A76.93%3Av4", 
>>>>>>>
>>>>>>> authorization'
>>>>>>> 2016/05/31 16:58:13 kid1| clientProcessHit: Vary object loop!
>>>>>>> 2016/05/31 16:58:13 kid1| varyEvaluateMatch: Oops. Not a Vary match
>>>>>>> on second attempt,
>>>>>>> 'https://meta.wikimedia.org/w/index.php?title=MediaWiki:Wikiminiatlas.js&action=raw&ctype=text/javascript' 
>>>>>>>
>>>>>>> 'accept-encoding="gzip,%20deflate,%20sdch,%20br", cookie,
>>>>>>> authorization'
>>>>>>>
>>>>>>> 3.5.19
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> 31.05.2016 13:34, joe пишет:
>>>>>>>> no  until i test it cpl of month and i show it to the dev team 
>>>>>>>> they
>>>>>>>> might do
>>>>>>>> better
>>>>>>>>
>>>>>>>>
>>>>>>>>
>>>>>>>> -- 
>>>>>>>> View this message in context:
>>>>>>>> http://squid-web-proxy-cache.1019090.n4.nabble.com/Vary-object-loop-returns-tp4677716p4677725.html 
>>>>>>>>
>>>>>>>> Sent from the Squid - Users mailing list archive at Nabble.com.
>>>>>>>> _______________________________________________
>>>>>>>> squid-users mailing list
>>>>>>>>
>>>> squid-users at .squid-cache
>>>>>>>> http://lists.squid-cache.org/listinfo/squid-users
>>>>>>> _______________________________________________
>>>>>>> squid-users mailing list
>>>>>>>
>>>> squid-users at .squid-cache
>>>>>>> http://lists.squid-cache.org/listinfo/squid-users
>>>>>>
>>>>>>
>>>>>> _______________________________________________
>>>>>> squid-users mailing list
>>>>>>
>>>> squid-users at .squid-cache
>>>>>> http://lists.squid-cache.org/listinfo/squid-users
>>>>>
>>>>>
>>>>> _______________________________________________
>>>>> squid-users mailing list
>>>>>
>>>> squid-users at .squid-cache
>>>>> http://lists.squid-cache.org/listinfo/squid-users
>>>>
>>>> _______________________________________________
>>>> squid-users mailing list
>>>> squid-users at .squid-cache
>>>> http://lists.squid-cache.org/listinfo/squid-users
>>>
>>>
>>>
>>>
>>> -- 
>>> View this message in context: 
>>> http://squid-web-proxy-cache.1019090.n4.nabble.com/Vary-object-loop-returns-tp4677716p4677737.html
>>> Sent from the Squid - Users mailing list archive at Nabble.com.
>>> _______________________________________________
>>> squid-users mailing list
>>> squid-users at lists.squid-cache.org
>>> http://lists.squid-cache.org/listinfo/squid-users
>>
>> _______________________________________________
>> squid-users mailing list
>> squid-users at lists.squid-cache.org
>> http://lists.squid-cache.org/listinfo/squid-users
>
>
>
> _______________________________________________
> squid-users mailing list
> squid-users at lists.squid-cache.org
> http://lists.squid-cache.org/listinfo/squid-users

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.squid-cache.org/pipermail/squid-users/attachments/20160531/374bcb7d/attachment-0001.html>


More information about the squid-users mailing list