snik_1900
-
Публикации
151 -
Зарегистрирован
-
Посещение
Сообщения, опубликованные пользователем snik_1900
-
-
7 минут назад, rm_ сказал:
На скорости 1 гигабайт в секунду, в случае всего одной десяточки, например. (Ржаку про tcpdump оставим за скобками).
Диск на 10 ТБ заполняется за 2.5 часа. А вам надо 6 месяцев. И ещё как-то бить по абонентам и по этому всему искать. И вспоминаем что HDD одновременно пишут и читают на скорости на порядок меньшей, чем что-то одно.
Я прошу прощения, а вы про сетевые хранилища слышали? Кластеры? Вас кто-то заставляет писать на единственный диск установленный в сервер?
5 минут назад, alibek сказал:Если речь идет о фильтрации, то есть вполне работающие решения.
Не работает у тех кто этого не хочет или не может сделать.
-
1 минуту назад, Ivan_83 сказал:
Ну будет ФСБ всех любить, от этого никто не научится писать терабайты так чтобы потом показать можно было, и денег на это очевидно нет.
Просто позакрываются нахер, или введут помегабайтные тарифы, вернутся локальный чаты и файлопомойки.
Про tcpdump я вообще комментировать не хочу, очевидно что ты даже не пытался думать над проблемой даже записи, не говоря уже о том, чтобы потом это отдавать по запросу.
Да нет, мы уже давно посчитали объемы хранения данных, результат впечатлил. А tcpdump был упомянут для примера, это не есть конечная реализация.
Кластерную организацию еще никто не отменял. "Под лежачий камень вода не течет". Закрыться не сложно. "Выжить" сложнее.
-
19 минут назад, sdy_moscow сказал:
Отвлекитесь на секунду...
Обратите внимание, пока Греф кричит "Блок чейн спасет мир!". Китайцы тихой сапой запрещают биткоин. А знаете почему?
Потому, что у одних в стране принято с утра до вечера пи..деть, а у других работать.
Вот и я о том. Авральные работы до добра не доводят. Для начало хотя бы проработать обобщенную концепцию. Которую каждый сможет "допелить" под себя.
-
1 минуту назад, sdy_moscow сказал:
Вы только вспомните, сколько времени прошло и что написано в законе. Всё-таки тот закон хоть и вредный и бесполезный, но исполнимый! Изначально мы должны фильтровать просто по ИП, что вполне реализуемо за разумные деньги, а все проблемы возникли от желания "и сесть, и съесть". К тому-же реализация его серьезных затрат не требовала. И ведь всё равно не работает!.
В чем не реализуемость закона Яровой? В деньгах? ФСБ глубоко безразличны проблемы провайдеров. Их будут "любить" вышестоящее начальство не за сочувствие операторам, а за не выполнение закона. Когда мы СОРМ устанавливали это ощутили в полной мере. Повторений не хочется.
Минимальные требования (без учета хранилища) реализовать возможно. Mirror на порту центрального коммутатора в сторону сервера, допустим c FreeBSD. Далее, например, tcpdump для сбора данных в файл. И переброска данных в хранилище. Вопрос в объеме хранилища.
-
Только что, sdy_moscow сказал:
А зачем тратить на это время?
Вот предположим, станет известно, что ерез 6 месяцев на солнце произойдет мощная вспышка.
Наши "мудрые" законодатели решат, что "надо потушить солнце через 6 месяцев" и издадут закон.
Вот скажите, вы тоже будете с космонавтами технические аспекты обсуждать или с метростроевцами и строить убежища и создавать резервы продовольствия.
Все оператором давно и ясно - реализовать весь этот бред в нынешней постановке и нынешних рыночных условиях - НЕ ВОЗМОЖНО.
Так зачем тратить своё время и деньги? Вот уже прошел год, пройдет 6 месяцев, потом еще 6 месяцев, потом еще 6 месяцев... а там глядишь или Шах умрет, или Осел сдохнет или Ходжа отправится к праотцам.
Забейте!
Что-то подобное было когда поднимался вопрос с фильтрацией. И ничего заставили. Криво, косо но этот бред работает.
"Пятилетка за 3 дня" это не самое увлекательное занятие. Никто не заставляет покупать оборудование, но осмыслить проблему не мешало бы.
-
8 минут назад, Умник сказал:
На мой взгляд уже очевидно, что "Яровую" будут делать на базе уже работающих у провайдеров СОРМ-ов. Новый софт + больше HDD/SSD. А если СОРМ-а у провайдера по какой-то причине еще еще не стоит, начнут интенсивней выкручивать руки - отвертеться станет гораздо сложнее.
Возможно. Но хранилища скорее всего будут делать "у" и "за" счет провайдера. Сейчас провайдер не может/не должен иметь доступ к СОРМу. Соответственно сделать оператора "козлом отпущения" в случае сбоев в работе системы будет гораздо сложнее. Так что наиболее вероятно, что как в ситуацией с фильтрацией трафика, оператор будет решать свои проблемы сам. Хотя бы на начальном этапе.
-
Вопрос не "когда?", а "как?"
Реализовывать придется. 6 месяцев это ОЧЕНЬ МАЛО. И никого не будет интересовать как операторы это сделают.
"Должон съесть!" (с)
Как мне кажется первоначально нужно разработать способ сбора данных.
За-тем нужно будет добавить анализ и хранение.
Самый простой вариант mirror с центрального коммутатора на какой то сервер, который собирёт, отсортирует и сохранит перхваченый трафик.
На текущий момент можно обсудить все плюсы/минусы различных сборщиков, способы фильтрации/сортировки.
-
Опубликовано · Изменено пользователем snik_1900 · Жалоба на ответ
"Уж полночь близится, а Германа все нет"...
Не так много осталось времени до того момента как у компетентных органов возникнут вопросы к операторам по состоянию дел по закону Яровой.
На ткущий момент разговоров на тему "безпредела/глупости/и т. д." много.
А вот технических решений пока нет. А время идет. Может стоит обсудить именно техническую сторону:
1. метод сбора (оборудование, софт....)
2. хранения (объемы, оборудование, ПО)
3. управление/мониторинг
4. организация доступа ФСБ/МВД/... к данным
-
Ташовыкажете?
Попробуй подмени сертификат без установленного своего у клиента. Забудьте это. Хватит курить манулы двухлетней давности.
А без подмены никак?
На шаге step1 глянуть сайт и либо не пропускать совсем, либо пропустить, но не изменять пакет пришедший от клиента.
Вроде бы splice, по документации из поставки самого squid, должен пропускать клиентский пакет к серверу без модификации. Или я чего-то не правильно понял?
-
Опубликовано · Изменено пользователем snik_1900 · Жалоба на ответ
Может это поможет найти проблему.
Более детальный лог:
2017/08/11 00:24:35.492 kid1| 83,2| client_side.cc(3828) clientNegotiateSSL: clientNegotiateSSL: New session 0x8320c4a00 on FD 77 (EEE.FFF.GGG.HHHH:52244)
2017/08/11 00:24:35.492 kid1| 83,3| client_side.cc(3832) clientNegotiateSSL: clientNegotiateSSL: FD 77 negotiated cipher AES128-GCM-SHA256
2017/08/11 00:24:35.492 kid1| 83,5| client_side.cc(3848) clientNegotiateSSL: clientNegotiateSSL: FD 77 has no certificate.
2017/08/11 00:24:35.492 kid1| 33,4| client_side.cc(231) readSomeData: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52244 FD 77 flags=33: reading request...
2017/08/11 00:24:35.492 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x83210f718=6
2017/08/11 00:24:35.492 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f718
2017/08/11 00:24:35.492 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x83210f718=7
2017/08/11 00:24:35.492 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f718
2017/08/11 00:24:35.492 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x83210f718=8
2017/08/11 00:24:35.492 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x83210f718=9
2017/08/11 00:24:35.492 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f718=8
2017/08/11 00:24:35.492 kid1| 33,5| AsyncCall.cc(26) AsyncCall: The AsyncCall ConnStateData::clientReadRequest constructed, this=0x8322486e0 [call1683]
2017/08/11 00:24:35.492 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f718
2017/08/11 00:24:35.492 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x83210f718=9
2017/08/11 00:24:35.492 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x83210f718=10
2017/08/11 00:24:35.492 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f718=9
2017/08/11 00:24:35.492 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f718=8
2017/08/11 00:24:35.492 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f718=7
2017/08/11 00:24:35.493 kid1| 5,5| Read.cc(58) comm_read_base: comm_read, queueing read for local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52244 FD 77 flags=33; asynCall 0x8322486e0*1
2017/08/11 00:24:35.493 kid1| 5,5| ModKqueue.cc(174) SetSelect: FD 77, type=1, handler=1, client_data=0x8246028f0, timeout=0
2017/08/11 00:24:35.493 kid1| 83,5| bio.cc(118) read: FD 78 read 11 <= 11
2017/08/11 00:24:35.493 kid1| 83,5| bio.cc(144) readAndBuffer: read 11 out of 11 bytes
2017/08/11 00:24:35.493 kid1| 83,5| bio.cc(148) readAndBuffer: recorded 11 bytes of TLS client Hello
2017/08/11 00:24:35.493 kid1| 83,7| bio.cc(247) read: SSL Header: 1603010200010001fc0303102c318c03ce1ede451c1b09d9f3f11830ecb6268b4faa7230b571d915a4ee3220a944e53879f9f759c4f6bd749bdee85bc6f251a834b4db985fa11345d6952b53001ccca9cca8cc14cc13c02bc02fc00ac014c009c013009c0035002f000a01000197ff0100010000000011000f00000c7777772e617669746f2e727500170000002300c0fac6c40c4f055a5d06c17cbda137846514bc861d812d34e376b4e921a43ccf51589633baa2bcf6fb90c06b413f4ec7cca1aa1ff6c32e9f480b591ff6a87297605812c07105144cec80c84653e7c7a129fde45c10f1572cbc16aa68cb919b3bbd0c8008c5731ab6bcf4284ec48b92ae92a225e36f9d656b3a70166f242349d027c560d2256cf39173c779b36eeb2807860c34266ebb207542974e32ffbfe094e23d5876a452b800c6635dc602736ed72c892a962014e693bb96579866b26376fc000d001600140601060305010503040104030301030302010203000500050100000000337400000012000000100017001502683208737064792f332e3108687474702f312e3175500000000b00020100000a000600040017001800150057000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
2017/08/11 00:24:35.493 kid1| 83,7| bio.cc(761) parseMsgHead: SSL Header: 1603010200010001fc0303102c318c03ce1ede451c1b09d9f3f11830ecb6268b4faa7230b571d915a4ee3220a944e53879f9f759c4f6bd749bdee85bc6f251a834b4db985fa11345d6952b53001ccca9cca8cc14cc13c02bc02fc00ac014c009c013009c0035002f000a01000197ff0100010000000011000f00000c7777772e617669746f2e727500170000002300c0fac6c40c4f055a5d06c17cbda137846514bc861d812d34e376b4e921a43ccf51589633baa2bcf6fb90c06b413f4ec7cca1aa1ff6c32e9f480b591ff6a87297605812c07105144cec80c84653e7c7a129fde45c10f1572cbc16aa68cb919b3bbd0c8008c5731ab6bcf4284ec48b92ae92a225e36f9d656b3a70166f242349d027c560d2256cf39173c779b36eeb2807860c34266ebb207542974e32ffbfe094e23d5876a452b800c6635dc602736ed72c892a962014e693bb96579866b26376fc000d001600140601060305010503040104030301030302010203000500050100000000337400000012000000100017001502683208737064792f332e3108687474702f312e3175500000000b00020100000a000600040017001800150057000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
2017/08/11 00:24:35.493 kid1| 24,7| SBuf.cc(242) append: from c-string to id SBuf2071
2017/08/11 00:24:35.493 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 517 for SBuf2071
2017/08/11 00:24:35.493 kid1| 24,8| SBuf.cc(956) cow: SBuf2071 new size:517
2017/08/11 00:24:35.493 kid1| 24,8| SBuf.cc(926) reAlloc: SBuf2071 new size: 517
2017/08/11 00:24:35.493 kid1| 24,9| MemBlob.cc(57) MemBlob: constructed, this=0x832130510 id=blob656 reserveSize=517
2017/08/11 00:24:35.493 kid1| 24,8| MemBlob.cc(102) memAlloc: blob656 memAlloc: requested=517, received=1024
2017/08/11 00:24:35.493 kid1| 24,7| SBuf.cc(935) reAlloc: SBuf2071 new store capacity: 1024
2017/08/11 00:24:35.493 kid1| 83,7| bio.cc(995) parseV3Hello: Get fake features from v3 ClientHello message.
2017/08/11 00:24:35.493 kid1| 83,7| bio.cc(998) parseV3Hello: ClientHello message size: 512
2017/08/11 00:24:35.493 kid1| 83,7| bio.cc(1021) parseV3Hello: Client random: 102c318c03ce1ede451c1b09d9f3f11830ecb6268b4faa7230b571d915a4ee32
2017/08/11 00:24:35.493 kid1| 83,7| bio.cc(1025) parseV3Hello: Session ID Length: 32
2017/08/11 00:24:35.493 kid1| 24,6| SBuf.cc(153) assign: SBuf2070 from c-string, n=32)
2017/08/11 00:24:35.493 kid1| 24,7| SBuf.cc(242) append: from c-string to id SBuf2070
2017/08/11 00:24:35.493 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 32 for SBuf2070
2017/08/11 00:24:35.493 kid1| 24,8| SBuf.cc(956) cow: SBuf2070 new size:32
2017/08/11 00:24:35.493 kid1| 24,8| SBuf.cc(926) reAlloc: SBuf2070 new size: 32
2017/08/11 00:24:35.493 kid1| 24,9| MemBlob.cc(57) MemBlob: constructed, this=0x832130990 id=blob657 reserveSize=32
2017/08/11 00:24:35.493 kid1| 24,8| MemBlob.cc(102) memAlloc: blob657 memAlloc: requested=32, received=40
2017/08/11 00:24:35.493 kid1| 24,7| SBuf.cc(935) reAlloc: SBuf2070 new store capacity: 40
2017/08/11 00:24:35.493 kid1| 83,7| bio.cc(1067) parseV3Hello: Ciphers requested by client: ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-RSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-RSA-AES128-SHA:AES128-GCM-SHA256:AES256-SHA:AES128-SHA:DES-CBC3-SHA
2017/08/11 00:24:35.493 kid1| 83,7| bio.cc(1076) parseV3Hello: SSL compression methods number: 1
2017/08/11 00:24:35.493 kid1| 83,7| bio.cc(1094) parseV3Hello: TLS Extension: ff01 of size:1
2017/08/11 00:24:35.493 kid1| 83,7| bio.cc(1094) parseV3Hello: TLS Extension: 0 of size:11
2017/08/11 00:24:35.493 kid1| 24,6| SBuf.cc(153) assign: SBuf2068 from c-string, n=12)
2017/08/11 00:24:35.493 kid1| 24,7| SBuf.cc(242) append: from c-string to id SBuf2068
2017/08/11 00:24:35.493 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 12 for SBuf2068
2017/08/11 00:24:35.493 kid1| 24,8| SBuf.cc(956) cow: SBuf2068 new size:12
2017/08/11 00:24:35.493 kid1| 24,8| SBuf.cc(926) reAlloc: SBuf2068 new size: 12
2017/08/11 00:24:35.493 kid1| 24,9| MemBlob.cc(57) MemBlob: constructed, this=0x832130960 id=blob658 reserveSize=12
2017/08/11 00:24:35.493 kid1| 24,8| MemBlob.cc(102) memAlloc: blob658 memAlloc: requested=12, received=40
2017/08/11 00:24:35.493 kid1| 24,7| SBuf.cc(935) reAlloc: SBuf2068 new store capacity: 40
2017/08/11 00:24:35.493 kid1| 83,7| bio.cc(1110) parseV3Hello: Found server name: www.avito.ru
2017/08/11 00:24:35.493 kid1| 83,7| bio.cc(1094) parseV3Hello: TLS Extension: 17 of size:0
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(1094) parseV3Hello: TLS Extension: 23 of size:c0
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(1094) parseV3Hello: TLS Extension: d of size:16
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(1094) parseV3Hello: TLS Extension: 5 of size:5
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(1094) parseV3Hello: TLS Extension: 3374 of size:0
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(1094) parseV3Hello: TLS Extension: 12 of size:0
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(1094) parseV3Hello: TLS Extension: 10 of size:17
2017/08/11 00:24:35.494 kid1| 24,6| SBuf.cc(153) assign: SBuf2069 from c-string, n=21)
2017/08/11 00:24:35.494 kid1| 24,7| SBuf.cc(242) append: from c-string to id SBuf2069
2017/08/11 00:24:35.494 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 21 for SBuf2069
2017/08/11 00:24:35.494 kid1| 24,8| SBuf.cc(956) cow: SBuf2069 new size:21
2017/08/11 00:24:35.494 kid1| 24,8| SBuf.cc(926) reAlloc: SBuf2069 new size: 21
2017/08/11 00:24:35.494 kid1| 24,9| MemBlob.cc(57) MemBlob: constructed, this=0x832130900 id=blob659 reserveSize=21
2017/08/11 00:24:35.494 kid1| 24,8| MemBlob.cc(102) memAlloc: blob659 memAlloc: requested=21, received=40
2017/08/11 00:24:35.494 kid1| 24,7| SBuf.cc(935) reAlloc: SBuf2069 new store capacity: 40
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(1094) parseV3Hello: TLS Extension: 7550 of size:0
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(1094) parseV3Hello: TLS Extension: b of size:2
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(1094) parseV3Hello: TLS Extension: a of size:6
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(1094) parseV3Hello: TLS Extension: 15 of size:57
2017/08/11 00:24:35.494 kid1| 83,5| bio.cc(574) squid_bio_ctrl: 0x80318fd40 6(0, 0x80318f720)
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2001 3RCH_A (SSLv3 read client hello A)
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2001 3WSH_A (SSLv3 write server hello A)
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2001 3WSC_A (SSLv3 write certificate A)
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2001 3WSD_A (SSLv3 write server done A)
2017/08/11 00:24:35.494 kid1| 83,5| bio.cc(95) write: FD 78 wrote 826 <= 826
2017/08/11 00:24:35.494 kid1| 83,5| bio.cc(574) squid_bio_ctrl: 0x80318fd40 11(0, 0x0)
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2001 3FLUSH (SSLv3 flush data)
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2001 3RCC_A (SSLv3 read client certificate A)
2017/08/11 00:24:35.494 kid1| 83,5| bio.cc(118) read: FD 78 read -1 <= 5
2017/08/11 00:24:35.494 kid1| 83,5| bio.cc(123) read: error: 35 ignored: 1
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2002 3RCKEA (SSLv3 read client key exchange A)
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2002 3RCKEA (SSLv3 read client key exchange A)
2017/08/11 00:24:35.494 kid1| 5,5| ModKqueue.cc(174) SetSelect: FD 78, type=1, handler=1, client_data=0x83210f998, timeout=0
2017/08/11 00:24:35.494 kid1| 83,5| bio.cc(118) read: FD 79 read 11 <= 11
2017/08/11 00:24:35.494 kid1| 83,5| bio.cc(144) readAndBuffer: read 11 out of 11 bytes
2017/08/11 00:24:35.494 kid1| 83,5| bio.cc(148) readAndBuffer: recorded 11 bytes of TLS client Hello
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(247) read: SSL Header: 1603010200010001fc0303fbb9c140a0fceed6a16bab1bd2df76f3c2306c3b8170cd07bd3fb7aa1d18668b20a944e53879f9f759c4f6bd749bdee85bc6f251a834b4db985fa11345d6952b53001ccca9cca8cc14cc13c02bc02fc00ac014c009c013009c0035002f000a01000197ff0100010000000011000f00000c7777772e617669746f2e727500170000002300c0fac6c40c4f055a5d06c17cbda137846514bc861d812d34e376b4e921a43ccf51589633baa2bcf6fb90c06b413f4ec7cca1aa1ff6c32e9f480b591ff6a87297605812c07105144cec80c84653e7c7a129fde45c10f1572cbc16aa68cb919b3bbd0c8008c5731ab6bcf4284ec48b92ae92a225e36f9d656b3a70166f242349d027c560d2256cf39173c779b36eeb2807860c34266ebb207542974e32ffbfe094e23d5876a452b800c6635dc602736ed72c892a962014e693bb96579866b26376fc000d001600140601060305010503040104030301030302010203000500050100000000337400000012000000100017001502683208737064792f332e3108687474702f312e3175500000000b00020100000a00060004001700180015005700000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
2017/08/11 00:24:35.494 kid1| 83,7| bio.cc(168) stateChanged: FD 79 now: 0x2002 23RCHA (SSLv2/v3 read client hello A)
2017/08/11 00:24:35.494 kid1| 5,5| ModKqueue.cc(174) SetSelect: FD 79, type=1, handler=1, client_data=0x83210fc18, timeout=0
2017/08/11 00:24:35.494 kid1| 83,5| bio.cc(118) read: FD 80 read 11 <= 11
2017/08/11 00:24:35.494 kid1| 83,5| bio.cc(144) readAndBuffer: read 11 out of 11 bytes
2017/08/11 00:24:35.494 kid1| 83,5| bio.cc(148) readAndBuffer: recorded 11 bytes of TLS client Hello
2017/08/11 00:24:35.495 kid1| 83,7| bio.cc(247) read: SSL Header: 1603010200010001fc0303366070b673672d2875627f31f4dac5db9a67575e26e5d15a9a57384ced70e12a20a944e53879f9f759c4f6bd749bdee85bc6f251a834b4db985fa11345d6952b53001ccca9cca8cc14cc13c02bc02fc00ac014c009c013009c0035002f000a01000197ff0100010000000011000f00000c7777772e617669746f2e727500170000002300c0fac6c40c4f055a5d06c17cbda137846514bc861d812d34e376b4e921a43ccf51589633baa2bcf6fb90c06b413f4ec7cca1aa1ff6c32e9f480b591ff6a87297605812c07105144cec80c84653e7c7a129fde45c10f1572cbc16aa68cb919b3bbd0c8008c5731ab6bcf4284ec48b92ae92a225e36f9d656b3a70166f242349d027c560d2256cf39173c779b36eeb2807860c34266ebb207542974e32ffbfe094e23d5876a452b800c6635dc602736ed72c892a962014e693bb96579866b26376fc000d001600140601060305010503040104030301030302010203000500050100000000337400000012000000100017001502683208737064792f332e3108687474702f312e3175500000000b00020100000a0006000400170018001500570000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
2017/08/11 00:24:35.495 kid1| 83,7| bio.cc(168) stateChanged: FD 80 now: 0x2002 23RCHA (SSLv2/v3 read client hello A)
2017/08/11 00:24:35.495 kid1| 5,5| ModKqueue.cc(174) SetSelect: FD 80, type=1, handler=1, client_data=0x83210fe98, timeout=0
2017/08/11 00:24:35.495 kid1| 5,5| Write.cc(66) HandleWrite: local=AAA.BBB.CCC.DDD:55024 remote=185.5.53.26:80 FD 75 flags=1: off 0, sz 478.
2017/08/11 00:24:35.495 kid1| 5,5| Write.cc(108) HandleWrite: write() returns 478
2017/08/11 00:24:35.495 kid1| 5,3| IoCallback.cc(116) finish: called for local=AAA.BBB.CCC.DDD:55024 remote=185.5.53.26:80 FD 75 flags=1 (0, 0)
2017/08/11 00:24:35.495 kid1| 11,5| AsyncCall.cc(93) ScheduleCall: IoCallback.cc(135) will call HttpStateData::sentRequestBody(local=AAA.BBB.CCC.DDD:55024 remote=185.5.53.26:80 FD 75 flags=1, data=0x8321f4a18) [call1681]
2017/08/11 00:24:35.495 kid1| 5,5| AsyncCallQueue.cc(55) fireNext: entering httpsAccept(local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33, MXID_55)
2017/08/11 00:24:35.495 kid1| 5,5| AsyncCall.cc(38) make: make call httpsAccept [call1682]
2017/08/11 00:24:35.495 kid1| 33,4| client_side.cc(3952) httpsAccept: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33 accepted, starting SSL negotiation.
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x832110118
2017/08/11 00:24:35.495 kid1| 93,5| AsyncJob.cc(34) AsyncJob: AsyncJob constructed, this=0x8321102e0 type=Http::Server [job209]
2017/08/11 00:24:35.495 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2128 created
2017/08/11 00:24:35.495 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2129 created
2017/08/11 00:24:35.495 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2130 created
2017/08/11 00:24:35.495 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2131 created
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=1
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=2
2017/08/11 00:24:35.495 kid1| 93,5| AsyncCall.cc(26) AsyncCall: The AsyncCall AsyncJob::start constructed, this=0x80318edf0 [call1684]
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=3
2017/08/11 00:24:35.495 kid1| 93,5| AsyncCall.cc(93) ScheduleCall: AsyncJob.cc(26) will call AsyncJob::start() [call1684]
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=2
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=1
2017/08/11 00:24:35.495 kid1| 5,5| AsyncCallQueue.cc(57) fireNext: leaving httpsAccept(local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33, MXID_55)
2017/08/11 00:24:35.495 kid1| 33,5| AsyncCallQueue.cc(55) fireNext: entering ConnStateData::clientReadRequest(local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52242 FD 74 flags=33, data=0x83210f218)
2017/08/11 00:24:35.495 kid1| 33,5| AsyncCall.cc(38) make: make call ConnStateData::clientReadRequest [call1669]
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f218
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f218
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f218
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f218
2017/08/11 00:24:35.495 kid1| 33,5| AsyncJob.cc(123) callStart: Http::Server status in: [ job191]
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f218
2017/08/11 00:24:35.495 kid1| 33,5| client_side.cc(3283) clientReadRequest: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52242 FD 74 flags=33
2017/08/11 00:24:35.495 kid1| 24,8| SBuf.cc(169) reserve: SBuf1960 was: 0+0+64=128
2017/08/11 00:24:35.495 kid1| 24,8| SBuf.cc(956) cow: SBuf1960 new size:4096
2017/08/11 00:24:35.495 kid1| 24,8| SBuf.cc(926) reAlloc: SBuf1960 new size: 4096
2017/08/11 00:24:35.495 kid1| 24,9| MemBlob.cc(57) MemBlob: constructed, this=0x8321309c0 id=blob660 reserveSize=4096
2017/08/11 00:24:35.495 kid1| 24,8| MemBlob.cc(102) memAlloc: blob660 memAlloc: requested=4096, received=4096
2017/08/11 00:24:35.495 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x8320c8de0 id=blob616 capacity=128 size=64
2017/08/11 00:24:35.495 kid1| 24,7| SBuf.cc(935) reAlloc: SBuf1960 new store capacity: 4096
2017/08/11 00:24:35.495 kid1| 24,7| SBuf.cc(185) reserve: SBuf1960 now: 0+0+4096=4096
2017/08/11 00:24:35.495 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x83210f218=8
2017/08/11 00:24:35.495 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 4096 for SBuf1960
2017/08/11 00:24:35.495 kid1| 24,7| SBuf.cc(200) rawSpace: SBuf1960 not growing
2017/08/11 00:24:35.495 kid1| 83,5| bio.cc(118) read: FD 74 read 0 <= 5
2017/08/11 00:24:35.496 kid1| 5,3| Read.cc(91) ReadNow: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52242 FD 74 flags=33, size 4096, retval 0, errno 0
2017/08/11 00:24:35.496 kid1| 33,5| client_side.cc(3323) clientReadRequest: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52242 FD 74 flags=33 closed?
2017/08/11 00:24:35.496 kid1| 33,4| client_side.cc(2421) connFinishedWithConn: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52242 FD 74 flags=33 closed
2017/08/11 00:24:35.496 kid1| 5,3| comm.cc(868) _comm_close: comm_close: start closing FD 74
2017/08/11 00:24:35.496 kid1| 5,4| AsyncCall.cc(26) AsyncCall: The AsyncCall commStartSslClose constructed, this=0x80318f9c0 [call1685]
2017/08/11 00:24:35.496 kid1| 5,4| AsyncCall.cc(93) ScheduleCall: comm.cc(902) will call commStartSslClose(FD 74) [call1685]
2017/08/11 00:24:35.496 kid1| 5,3| comm.cc(540) commUnsetFdTimeout: Remove timeout for FD 74
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f218=7
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f218=6
2017/08/11 00:24:35.496 kid1| 5,5| comm.cc(721) commCallCloseHandlers: commCallCloseHandlers: FD 74
2017/08/11 00:24:35.496 kid1| 5,5| comm.cc(729) commCallCloseHandlers: commCallCloseHandlers: ch->handler=0x8320a7d00*1
2017/08/11 00:24:35.496 kid1| 33,5| AsyncCall.cc(93) ScheduleCall: comm.cc(730) will call ConnStateData::connStateClosed(FD -1, data=0x83210f218) [call1601]
2017/08/11 00:24:35.496 kid1| 5,4| AsyncCall.cc(26) AsyncCall: The AsyncCall comm_close_complete constructed, this=0x80318fa30 [call1686]
2017/08/11 00:24:35.496 kid1| 5,4| AsyncCall.cc(93) ScheduleCall: comm.cc(941) will call comm_close_complete(FD 74) [call1686]
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f218=5
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f218
2017/08/11 00:24:35.496 kid1| 33,5| AsyncJob.cc(152) callEnd: Http::Server status out: [ job191]
2017/08/11 00:24:35.496 kid1| 33,5| AsyncCallQueue.cc(57) fireNext: leaving ConnStateData::clientReadRequest(local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52242 flags=33, data=0x83210f218)
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f218=4
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f218=3
2017/08/11 00:24:35.496 kid1| 33,5| AsyncCallQueue.cc(55) fireNext: entering ConnStateData::clientReadRequest(local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52243 FD 76 flags=33, data=0x83210f498)
2017/08/11 00:24:35.496 kid1| 33,5| AsyncCall.cc(38) make: make call ConnStateData::clientReadRequest [call1671]
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f498
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f498
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f498
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f498
2017/08/11 00:24:35.496 kid1| 33,5| AsyncJob.cc(123) callStart: Http::Server status in: [ job194]
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f498
2017/08/11 00:24:35.496 kid1| 33,5| client_side.cc(3283) clientReadRequest: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52243 FD 76 flags=33
2017/08/11 00:24:35.496 kid1| 24,8| SBuf.cc(169) reserve: SBuf1997 was: 0+0+64=128
2017/08/11 00:24:35.496 kid1| 24,8| SBuf.cc(956) cow: SBuf1997 new size:4096
2017/08/11 00:24:35.496 kid1| 24,8| SBuf.cc(926) reAlloc: SBuf1997 new size: 4096
2017/08/11 00:24:35.496 kid1| 24,9| MemBlob.cc(57) MemBlob: constructed, this=0x8320c8de0 id=blob661 reserveSize=4096
2017/08/11 00:24:35.496 kid1| 24,8| MemBlob.cc(102) memAlloc: blob661 memAlloc: requested=4096, received=4096
2017/08/11 00:24:35.496 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x8320922c0 id=blob621 capacity=128 size=64
2017/08/11 00:24:35.496 kid1| 24,7| SBuf.cc(935) reAlloc: SBuf1997 new store capacity: 4096
2017/08/11 00:24:35.496 kid1| 24,7| SBuf.cc(185) reserve: SBuf1997 now: 0+0+4096=4096
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x83210f498=8
2017/08/11 00:24:35.496 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 4096 for SBuf1997
2017/08/11 00:24:35.496 kid1| 24,7| SBuf.cc(200) rawSpace: SBuf1997 not growing
2017/08/11 00:24:35.496 kid1| 83,5| bio.cc(118) read: FD 76 read 0 <= 5
2017/08/11 00:24:35.496 kid1| 5,3| Read.cc(91) ReadNow: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52243 FD 76 flags=33, size 4096, retval 0, errno 0
2017/08/11 00:24:35.496 kid1| 33,5| client_side.cc(3323) clientReadRequest: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52243 FD 76 flags=33 closed?
2017/08/11 00:24:35.496 kid1| 33,4| client_side.cc(2421) connFinishedWithConn: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52243 FD 76 flags=33 closed
2017/08/11 00:24:35.496 kid1| 5,3| comm.cc(868) _comm_close: comm_close: start closing FD 76
2017/08/11 00:24:35.496 kid1| 5,4| AsyncCall.cc(26) AsyncCall: The AsyncCall commStartSslClose constructed, this=0x803190280 [call1687]
2017/08/11 00:24:35.496 kid1| 5,4| AsyncCall.cc(93) ScheduleCall: comm.cc(902) will call commStartSslClose(FD 76) [call1687]
2017/08/11 00:24:35.496 kid1| 5,3| comm.cc(540) commUnsetFdTimeout: Remove timeout for FD 76
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f498=7
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f498=6
2017/08/11 00:24:35.496 kid1| 5,5| comm.cc(721) commCallCloseHandlers: commCallCloseHandlers: FD 76
2017/08/11 00:24:35.496 kid1| 5,5| comm.cc(729) commCallCloseHandlers: commCallCloseHandlers: ch->handler=0x8320a8fc0*1
2017/08/11 00:24:35.496 kid1| 33,5| AsyncCall.cc(93) ScheduleCall: comm.cc(730) will call ConnStateData::connStateClosed(FD -1, data=0x83210f498) [call1613]
2017/08/11 00:24:35.496 kid1| 5,4| AsyncCall.cc(26) AsyncCall: The AsyncCall comm_close_complete constructed, this=0x8031902f0 [call1688]
2017/08/11 00:24:35.496 kid1| 5,4| AsyncCall.cc(93) ScheduleCall: comm.cc(941) will call comm_close_complete(FD 76) [call1688]
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f498=5
2017/08/11 00:24:35.496 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f498
2017/08/11 00:24:35.497 kid1| 33,5| AsyncJob.cc(152) callEnd: Http::Server status out: [ job194]
2017/08/11 00:24:35.497 kid1| 33,5| AsyncCallQueue.cc(57) fireNext: leaving ConnStateData::clientReadRequest(local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52243 flags=33, data=0x83210f498)
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f498=4
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f498=3
2017/08/11 00:24:35.497 kid1| 11,5| AsyncCallQueue.cc(55) fireNext: entering HttpStateData::sentRequestBody(local=AAA.BBB.CCC.DDD:55024 remote=185.5.53.26:80 FD 75 flags=1, data=0x8321f4a18)
2017/08/11 00:24:35.497 kid1| 11,5| AsyncCall.cc(38) make: make call HttpStateData::sentRequestBody [call1681]
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x8321f4a18
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x8321f4a18
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x8321f4a18
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x8321f4a18
2017/08/11 00:24:35.497 kid1| 11,5| AsyncJob.cc(123) callStart: HttpStateData status in: [ job208]
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x8321f4a18
2017/08/11 00:24:35.497 kid1| 11,5| Client.cc(360) sentRequestBody: sentRequestBody: FD 75: size 478: errflag 0.
2017/08/11 00:24:35.497 kid1| 32,3| Client.cc(361) sentRequestBody: sentRequestBody called
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x8321f4a18
2017/08/11 00:24:35.497 kid1| 91,7| BodyPipe.cc(381) postConsume: consumed 143 bytes [143<=143<=143 0+2047 pipe0x8320a9258 cons0x8321f4a28]
2017/08/11 00:24:35.497 kid1| 9,3| Client.cc(417) sendMoreRequestBody: will write 143 request body bytes
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x8321f4a18=10
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x8321f4a18
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x8321f4a18=11
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x8321f4a18
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x8321f4a18=12
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x8321f4a18=13
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x8321f4a18=12
2017/08/11 00:24:35.497 kid1| 93,3| AsyncCall.cc(26) AsyncCall: The AsyncCall Client::sentRequestBody constructed, this=0x832248500 [call1689]
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x8321f4a18
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x8321f4a18=13
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x8321f4a18=14
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x8321f4a18=13
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x8321f4a18=12
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x8321f4a18=11
2017/08/11 00:24:35.497 kid1| 5,5| Write.cc(35) Write: local=AAA.BBB.CCC.DDD:55024 remote=185.5.53.26:80 FD 75 flags=1: sz 143: asynCall 0x832248500*1
2017/08/11 00:24:35.497 kid1| 5,5| ModKqueue.cc(174) SetSelect: FD 75, type=2, handler=1, client_data=0x824602820, timeout=0
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x8321f4a18
2017/08/11 00:24:35.497 kid1| 11,5| AsyncJob.cc(152) callEnd: HttpStateData status out: [ job208]
2017/08/11 00:24:35.497 kid1| 11,5| AsyncCallQueue.cc(57) fireNext: leaving HttpStateData::sentRequestBody(local=AAA.BBB.CCC.DDD:55024 remote=185.5.53.26:80 FD 75 flags=1, data=0x8321f4a18)
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x8321f4a18=10
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x8321f4a18=9
2017/08/11 00:24:35.497 kid1| 93,5| AsyncCallQueue.cc(55) fireNext: entering AsyncJob::start()
2017/08/11 00:24:35.497 kid1| 93,5| AsyncCall.cc(38) make: make call AsyncJob::start [call1684]
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.497 kid1| 93,5| AsyncJob.cc(123) callStart: Http::Server status in: [ job209]
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=2
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=3
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=4
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=5
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=4
2017/08/11 00:24:35.497 kid1| 33,5| AsyncCall.cc(26) AsyncCall: The AsyncCall ConnStateData::connStateClosed constructed, this=0x8322485a0 [call1690]
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=5
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=6
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=5
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=4
2017/08/11 00:24:35.497 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=3
2017/08/11 00:24:35.498 kid1| 5,5| comm.cc(994) comm_add_close_handler: comm_add_close_handler: FD 82, AsyncCall=0x8322485a0*1
2017/08/11 00:24:35.498 kid1| 33,5| client_side.cc(3970) postHttpsAccept: accept transparent connection: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33
2017/08/11 00:24:35.498 kid1| 55,7| HttpHeader.cc(446) HttpHeader: init-ing hdr: 0x802d98d18 owner: 2
2017/08/11 00:24:35.498 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2132 created
2017/08/11 00:24:35.498 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2133 created
2017/08/11 00:24:35.498 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2134 created
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(139) assign: assigning SBuf2133 from SBuf2134
2017/08/11 00:24:35.498 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2134 destructed
2017/08/11 00:24:35.498 kid1| 23,3| ../src/HttpRequest.h(82) SetHost: HttpRequest::SetHost() given IP: 185.89.12.132
2017/08/11 00:24:35.498 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x802e16f18
2017/08/11 00:24:35.498 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x802b760f8=1
2017/08/11 00:24:35.498 kid1| 28,3| Checklist.cc(70) preCheck: 0x802e16f18 checking slow rules
2017/08/11 00:24:35.498 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=4
2017/08/11 00:24:35.498 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.498 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x802b760f8
2017/08/11 00:24:35.498 kid1| 28,5| Acl.cc(138) matches: checking (ssl_bump rules)
2017/08/11 00:24:35.498 kid1| 28,5| Checklist.cc(400) bannedAction: Action 'ALLOWED/3' is not banned
2017/08/11 00:24:35.498 kid1| 28,5| Acl.cc(138) matches: checking (ssl_bump rule)
2017/08/11 00:24:35.498 kid1| 28,5| Acl.cc(138) matches: checking step1
2017/08/11 00:24:35.498 kid1| 28,3| Acl.cc(158) matches: checked: step1 = 1
2017/08/11 00:24:35.498 kid1| 28,3| Acl.cc(158) matches: checked: (ssl_bump rule) = 1
2017/08/11 00:24:35.498 kid1| 28,3| Acl.cc(158) matches: checked: (ssl_bump rules) = 1
2017/08/11 00:24:35.498 kid1| 28,3| Checklist.cc(63) markFinished: 0x802e16f18 answer ALLOWED for match
2017/08/11 00:24:35.498 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x802b760f8=0
2017/08/11 00:24:35.498 kid1| 28,3| Checklist.cc(163) checkCallback: ACLChecklist::checkCallback: 0x802e16f18 answer=ALLOWED
2017/08/11 00:24:35.498 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.498 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=3
2017/08/11 00:24:35.498 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.498 kid1| 33,2| client_side.cc(3928) httpsSslBumpAccessCheckDone: sslBump needed for local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33 method 3
2017/08/11 00:24:35.498 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2135 created
2017/08/11 00:24:35.498 kid1| 24,6| SBuf.cc(153) assign: SBuf2135 from c-string, n=4294967295)
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(242) append: from c-string to id SBuf2135
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 17 for SBuf2135
2017/08/11 00:24:35.498 kid1| 24,8| SBuf.cc(956) cow: SBuf2135 new size:17
2017/08/11 00:24:35.498 kid1| 24,8| SBuf.cc(926) reAlloc: SBuf2135 new size: 17
2017/08/11 00:24:35.498 kid1| 24,9| MemBlob.cc(57) MemBlob: constructed, this=0x8321306c0 id=blob662 reserveSize=17
2017/08/11 00:24:35.498 kid1| 24,8| MemBlob.cc(102) memAlloc: blob662 memAlloc: requested=17, received=40
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(935) reAlloc: SBuf2135 new store capacity: 40
2017/08/11 00:24:35.498 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2136 created
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(242) append: from c-string to id SBuf2136
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 8 for SBuf2136
2017/08/11 00:24:35.498 kid1| 24,8| SBuf.cc(956) cow: SBuf2136 new size:8
2017/08/11 00:24:35.498 kid1| 24,8| SBuf.cc(926) reAlloc: SBuf2136 new size: 8
2017/08/11 00:24:35.498 kid1| 24,9| MemBlob.cc(57) MemBlob: constructed, this=0x832130a50 id=blob663 reserveSize=8
2017/08/11 00:24:35.498 kid1| 24,8| MemBlob.cc(102) memAlloc: blob663 memAlloc: requested=8, received=40
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(935) reAlloc: SBuf2136 new store capacity: 40
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 17 for SBuf2136
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(200) rawSpace: SBuf2136 not growing
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(242) append: from c-string to id SBuf2136
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 17 for SBuf2136
2017/08/11 00:24:35.498 kid1| 24,8| SBuf.cc(956) cow: SBuf2136 new size:42
2017/08/11 00:24:35.498 kid1| 24,8| SBuf.cc(926) reAlloc: SBuf2136 new size: 42
2017/08/11 00:24:35.498 kid1| 24,9| MemBlob.cc(57) MemBlob: constructed, this=0x832130ab0 id=blob664 reserveSize=42
2017/08/11 00:24:35.498 kid1| 24,8| MemBlob.cc(102) memAlloc: blob664 memAlloc: requested=42, received=128
2017/08/11 00:24:35.498 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x832130a50 id=blob663 capacity=40 size=25
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(935) reAlloc: SBuf2136 new store capacity: 128
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 17 for SBuf2136
2017/08/11 00:24:35.498 kid1| 24,7| SBuf.cc(200) rawSpace: SBuf2136 not growing
2017/08/11 00:24:35.499 kid1| 24,7| SBuf.cc(242) append: from c-string to id SBuf2136
2017/08/11 00:24:35.499 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 4 for SBuf2136
2017/08/11 00:24:35.499 kid1| 24,7| SBuf.cc(200) rawSpace: SBuf2136 not growing
2017/08/11 00:24:35.499 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 0 for SBuf2136
2017/08/11 00:24:35.499 kid1| 24,7| SBuf.cc(200) rawSpace: SBuf2136 not growing
2017/08/11 00:24:35.499 kid1| 24,7| SBuf.cc(139) assign: assigning SBuf2128 from SBuf2136
2017/08/11 00:24:35.499 kid1| 33,5| client_side.cc(3232) clientParseRequests: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33: attempting to parse
2017/08/11 00:24:35.499 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 1 for SBuf2128
2017/08/11 00:24:35.499 kid1| 24,7| SBuf.cc(200) rawSpace: SBuf2128 not growing
2017/08/11 00:24:35.499 kid1| 74,5| HttpParser.cc(37) reset: Request buffer is CONNECT 185.89.12.132:443 HTTP/1.1
Host: 185.89.12.132:443
2017/08/11 00:24:35.499 kid1| 74,5| HttpParser.cc(47) parseRequestFirstLine: parsing possible request: CONNECT 185.89.12.132:443 HTTP/1.1
Host: 185.89.12.132:443
2017/08/11 00:24:35.499 kid1| 74,5| HttpParser.cc(257) HttpParserParseReqLine: Parser: retval 1: from 0->35: method 0->6; url 8->24; version 26->33 (1/1)
2017/08/11 00:24:35.499 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2137 created
2017/08/11 00:24:35.499 kid1| 24,7| SBuf.cc(139) assign: assigning SBuf2131 from SBuf2137
2017/08/11 00:24:35.499 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2137 destructed
2017/08/11 00:24:35.499 kid1| 33,3| client_side.cc(2277) parseHttpRequest: parseHttpRequest: req_hdr = {Host: 185.89.12.132:443
}
2017/08/11 00:24:35.499 kid1| 33,3| client_side.cc(2281) parseHttpRequest: parseHttpRequest: end = {
}
2017/08/11 00:24:35.499 kid1| 33,3| client_side.cc(2285) parseHttpRequest: parseHttpRequest: prefix_sz = 63, req_line_sz = 36
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x802e16b98
2017/08/11 00:24:35.499 kid1| 93,5| AsyncJob.cc(34) AsyncJob: AsyncJob constructed, this=0x802e16ce0 type=ClientHttpRequest [job210]
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=4
2017/08/11 00:24:35.499 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2138 created
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x8321a9818
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x83216c418
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x802e16b98=1
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x8320d2818
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x8320d2818=1
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x8320d2698
2017/08/11 00:24:35.499 kid1| 87,3| clientStream.cc(144) clientStreamInsertHead: clientStreamInsertHead: Inserted node 0x8320d2698 with data 0x8321aa890 after head
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x8320d2698=1
2017/08/11 00:24:35.499 kid1| 33,5| client_side.cc(2301) parseHttpRequest: parseHttpRequest: Request Header is
Host: 185.89.12.132:443
2017/08/11 00:24:35.499 kid1| 33,5| client_side.cc(2322) parseHttpRequest: Prepare absolute URL from intercept
2017/08/11 00:24:35.499 kid1| 33,5| client_side.cc(2361) parseHttpRequest: parseHttpRequest: Complete request received
2017/08/11 00:24:35.499 kid1| 11,2| client_side.cc(2364) parseHttpRequest: HTTP Client local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33
2017/08/11 00:24:35.499 kid1| 11,2| client_side.cc(2365) parseHttpRequest: HTTP Client REQUEST:
---------
CONNECT 185.89.12.132:443 HTTP/1.1
Host: 185.89.12.132:443
----------
2017/08/11 00:24:35.499 kid1| 33,5| client_side.cc(3253) clientParseRequests: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33: done parsing a request
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x802e16b98=2
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x802e16b98=3
2017/08/11 00:24:35.499 kid1| 5,4| AsyncCall.cc(26) AsyncCall: The AsyncCall clientLifetimeTimeout constructed, this=0x803190360 [call1691]
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x802e16b98=4
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x802e16b98=3
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x802e16b98=2
2017/08/11 00:24:35.499 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33 timeout 86400
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.499 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.499 kid1| 23,3| url.cc(371) urlParse: urlParse: Split URL '185.89.12.132:443' into proto='', host='185.89.12.132', port='443', path=''
2017/08/11 00:24:35.499 kid1| 55,7| HttpHeader.cc(446) HttpHeader: init-ing hdr: 0x832216318 owner: 2
2017/08/11 00:24:35.499 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2139 created
2017/08/11 00:24:35.499 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2140 created
2017/08/11 00:24:35.499 kid1| 93,7| HttpRequest.cc(47) HttpRequest: constructed, this=0x832216300 id=117
2017/08/11 00:24:35.499 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2141 created
2017/08/11 00:24:35.499 kid1| 24,7| SBuf.cc(139) assign: assigning SBuf2140 from SBuf2141
2017/08/11 00:24:35.499 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2141 destructed
2017/08/11 00:24:35.499 kid1| 24,7| SBuf.cc(139) assign: assigning SBuf2139 from SBuf2131
2017/08/11 00:24:35.499 kid1| 23,3| ../src/HttpRequest.h(82) SetHost: HttpRequest::SetHost() given IP: 185.89.12.132
2017/08/11 00:24:35.499 kid1| 55,7| HttpHeader.cc(643) parse: parsing hdr: (0x832216318)
Host: 185.89.12.132:443
2017/08/11 00:24:35.499 kid1| 55,9| HttpHeader.cc(1702) parse: parsing HttpHeaderEntry: near 'Host: 185.89.12.132:443'
2017/08/11 00:24:35.499 kid1| 55,9| HttpHeader.cc(1744) parse: parsed HttpHeaderEntry: 'Host: 185.89.12.132:443'
2017/08/11 00:24:35.500 kid1| 55,9| HttpHeader.cc(1645) HttpHeaderEntry: created HttpHeaderEntry 0x8320afd40: 'Host : 185.89.12.132:443
2017/08/11 00:24:35.500 kid1| 55,7| HttpHeader.cc(1027) addEntry: 0x832216318 adding entry: 29 at 0
2017/08/11 00:24:35.500 kid1| 55,9| HttpHeader.cc(1247) has: 0x832216318 lookup for 62
2017/08/11 00:24:35.500 kid1| 55,9| HttpHeader.cc(1247) has: 0x832216318 lookup for 29
2017/08/11 00:24:35.500 kid1| 24,7| SBuf.cc(242) append: from c-string to id SBuf2142
2017/08/11 00:24:35.500 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 13 for SBuf2142
2017/08/11 00:24:35.500 kid1| 24,8| SBuf.cc(956) cow: SBuf2142 new size:13
2017/08/11 00:24:35.500 kid1| 24,8| SBuf.cc(926) reAlloc: SBuf2142 new size: 13
2017/08/11 00:24:35.500 kid1| 24,9| MemBlob.cc(57) MemBlob: constructed, this=0x832130bd0 id=blob665 reserveSize=13
2017/08/11 00:24:35.500 kid1| 24,8| MemBlob.cc(102) memAlloc: blob665 memAlloc: requested=13, received=40
2017/08/11 00:24:35.500 kid1| 24,7| SBuf.cc(935) reAlloc: SBuf2142 new store capacity: 40
2017/08/11 00:24:35.500 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 6 for SBuf2142
2017/08/11 00:24:35.500 kid1| 24,7| SBuf.cc(200) rawSpace: SBuf2142 not growing
2017/08/11 00:24:35.500 kid1| 33,5| client_side.cc(2689) clientProcessRequest: URL domain 185.89.12.132:443 overrides header Host: 185.89.12.132:443
2017/08/11 00:24:35.500 kid1| 55,8| HttpHeader.cc(954) delById: 0x832216318 del-by-id 29
2017/08/11 00:24:35.500 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x8320afd40: 'Host: 185.89.12.132:443'
2017/08/11 00:24:35.500 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 1 for SBuf2142
2017/08/11 00:24:35.500 kid1| 24,7| SBuf.cc(200) rawSpace: SBuf2142 not growing
2017/08/11 00:24:35.500 kid1| 55,9| HttpHeader.cc(1645) HttpHeaderEntry: created HttpHeaderEntry 0x8320afd40: 'Host : 185.89.12.132:443
2017/08/11 00:24:35.500 kid1| 55,7| HttpHeader.cc(1027) addEntry: 0x832216318 adding entry: 29 at 1
2017/08/11 00:24:35.500 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2142 destructed
2017/08/11 00:24:35.500 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x832130bd0 id=blob665 capacity=40 size=18
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=5
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=6
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=5
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=6
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=5
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=6
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=5
2017/08/11 00:24:35.500 kid1| 55,9| HttpHeader.cc(1247) has: 0x832216318 lookup for 62
2017/08/11 00:24:35.500 kid1| 55,9| HttpHeader.cc(1247) has: 0x832216318 lookup for 62
2017/08/11 00:24:35.500 kid1| 55,9| HttpHeader.cc(1247) has: 0x832216318 lookup for 25
2017/08/11 00:24:35.500 kid1| 33,3| client_side.cc(890) clientSetKeepaliveFlag: http_ver = HTTP/1.1
2017/08/11 00:24:35.500 kid1| 33,3| client_side.cc(891) clientSetKeepaliveFlag: method = CONNECT
2017/08/11 00:24:35.500 kid1| 55,9| HttpHeader.cc(1247) has: 0x832216318 lookup for 11
2017/08/11 00:24:35.500 kid1| 55,9| HttpHeader.cc(1247) has: 0x832216318 lookup for 49
2017/08/11 00:24:35.500 kid1| 33,3| ../src/client_side.h(96) mayUseConnection: This 0x8321a9818 marked 1
2017/08/11 00:24:35.500 kid1| 24,8| SBuf.cc(521) consume: SBuf2128 consume 63
2017/08/11 00:24:35.500 kid1| 24,8| SBuf.cc(87) SBuf: SBuf2143 created from id SBuf2128
2017/08/11 00:24:35.500 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2143 destructed
2017/08/11 00:24:35.500 kid1| 33,5| client_side.cc(2439) consumeInput: in.buf has 0 unused bytes
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x8031903e8
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x802e16b98=3
2017/08/11 00:24:35.500 kid1| 85,3| client_side_request.cc(130) ClientRequestContext: 0x8031903e8 ClientRequestContext constructed
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.500 kid1| 83,3| client_side_request.cc(1687) doCallouts: Doing calloutContext->hostHeaderVerify()
2017/08/11 00:24:35.500 kid1| 85,3| client_side_request.cc(634) hostHeaderVerify: validate host=185.89.12.132, port=443, portStr=443
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.500 kid1| 14,4| ipcache.cc(493) ipcache_nbgethostbyname: ipcache_nbgethostbyname: Name '185.89.12.132'.
2017/08/11 00:24:35.500 kid1| 14,4| ipcache.cc(802) ipcacheCheckNumeric: ipcacheCheckNumeric: HIT_BYPASS for '185.89.12.132' == 185.89.12.132
2017/08/11 00:24:35.500 kid1| 14,4| ipcache.cc(506) ipcache_nbgethostbyname: ipcache_nbgethostbyname: BYPASS for '185.89.12.132' (already numeric)
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.500 kid1| 85,3| client_side_request.cc(526) hostHeaderIpVerify: validate IP 185.89.12.132:443 possible from Host:
2017/08/11 00:24:35.500 kid1| 83,3| client_side_request.cc(1694) doCallouts: Doing calloutContext->clientAccessCheck()
2017/08/11 00:24:35.500 kid1| 55,9| HttpHeader.cc(1247) has: 0x832216318 lookup for 73
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.500 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x802e17618
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x802b76398=1
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=6
2017/08/11 00:24:35.501 kid1| 28,3| Checklist.cc(70) preCheck: 0x802e17618 checking slow rules
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x8031903e8=1
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x8031903e8
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x802b76398
2017/08/11 00:24:35.501 kid1| 28,5| Acl.cc(138) matches: checking http_access
2017/08/11 00:24:35.501 kid1| 28,5| Checklist.cc(400) bannedAction: Action 'ALLOWED/0' is not banned
2017/08/11 00:24:35.501 kid1| 28,5| Acl.cc(138) matches: checking http_access#1
2017/08/11 00:24:35.501 kid1| 28,5| Acl.cc(138) matches: checking manager
2017/08/11 00:24:35.501 kid1| 28,3| RegexData.cc(51) match: aclRegexData::match: checking '185.89.12.132:443'
2017/08/11 00:24:35.501 kid1| 28,3| RegexData.cc(62) match: aclRegexData::match: looking for '(^cache_object://)'
2017/08/11 00:24:35.501 kid1| 28,3| RegexData.cc(62) match: aclRegexData::match: looking for '(^https?://[^/]+/squid-internal-mgr/)'
2017/08/11 00:24:35.501 kid1| 28,3| Acl.cc(158) matches: checked: manager = 0
2017/08/11 00:24:35.501 kid1| 28,3| Acl.cc(158) matches: checked: http_access#1 = 0
2017/08/11 00:24:35.501 kid1| 28,5| Checklist.cc(400) bannedAction: Action 'DENIED/0' is not banned
2017/08/11 00:24:35.501 kid1| 28,5| Acl.cc(138) matches: checking http_access#2
2017/08/11 00:24:35.501 kid1| 28,5| Acl.cc(138) matches: checking manager
2017/08/11 00:24:35.501 kid1| 28,3| RegexData.cc(51) match: aclRegexData::match: checking '185.89.12.132:443'
2017/08/11 00:24:35.501 kid1| 28,3| RegexData.cc(62) match: aclRegexData::match: looking for '(^cache_object://)'
2017/08/11 00:24:35.501 kid1| 28,3| RegexData.cc(62) match: aclRegexData::match: looking for '(^https?://[^/]+/squid-internal-mgr/)'
2017/08/11 00:24:35.501 kid1| 28,3| Acl.cc(158) matches: checked: manager = 0
2017/08/11 00:24:35.501 kid1| 28,3| Acl.cc(158) matches: checked: http_access#2 = 0
2017/08/11 00:24:35.501 kid1| 28,5| Checklist.cc(400) bannedAction: Action 'ALLOWED/0' is not banned
2017/08/11 00:24:35.501 kid1| 28,5| Acl.cc(138) matches: checking http_access#3
2017/08/11 00:24:35.501 kid1| 28,5| Acl.cc(138) matches: checking purge
2017/08/11 00:24:35.501 kid1| 24,8| SBuf.cc(87) SBuf: SBuf2144 created from id SBuf2139
2017/08/11 00:24:35.501 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2144 destructed
2017/08/11 00:24:35.501 kid1| 28,3| Acl.cc(158) matches: checked: purge = 0
2017/08/11 00:24:35.501 kid1| 28,3| Acl.cc(158) matches: checked: http_access#3 = 0
2017/08/11 00:24:35.501 kid1| 28,5| Checklist.cc(400) bannedAction: Action 'DENIED/0' is not banned
2017/08/11 00:24:35.501 kid1| 28,5| Acl.cc(138) matches: checking http_access#4
2017/08/11 00:24:35.501 kid1| 28,5| Acl.cc(138) matches: checking purge
2017/08/11 00:24:35.501 kid1| 24,8| SBuf.cc(87) SBuf: SBuf2145 created from id SBuf2139
2017/08/11 00:24:35.501 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2145 destructed
2017/08/11 00:24:35.501 kid1| 28,3| Acl.cc(158) matches: checked: purge = 0
2017/08/11 00:24:35.501 kid1| 28,3| Acl.cc(158) matches: checked: http_access#4 = 0
2017/08/11 00:24:35.501 kid1| 28,5| Checklist.cc(400) bannedAction: Action 'DENIED/0' is not banned
2017/08/11 00:24:35.501 kid1| 28,5| Acl.cc(138) matches: checking http_access#5
2017/08/11 00:24:35.501 kid1| 28,5| Acl.cc(138) matches: checking !Safe_ports
2017/08/11 00:24:35.501 kid1| 28,5| Acl.cc(138) matches: checking Safe_ports
2017/08/11 00:24:35.501 kid1| 28,3| Acl.cc(158) matches: checked: Safe_ports = 0
2017/08/11 00:24:35.501 kid1| 28,3| Acl.cc(158) matches: checked: !Safe_ports = 1
2017/08/11 00:24:35.501 kid1| 28,3| Acl.cc(158) matches: checked: http_access#5 = 1
2017/08/11 00:24:35.501 kid1| 28,3| Acl.cc(158) matches: checked: http_access = 1
2017/08/11 00:24:35.501 kid1| 28,3| Checklist.cc(63) markFinished: 0x802e17618 answer DENIED for match
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x802b76398=0
2017/08/11 00:24:35.501 kid1| 28,3| Checklist.cc(163) checkCallback: ACLChecklist::checkCallback: 0x802e17618 answer=DENIED
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x8031903e8
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x8031903e8=0
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x802e16b98
2017/08/11 00:24:35.501 kid1| 85,2| client_side_request.cc(744) clientAccessCheckDone: The request CONNECT 185.89.12.132:443 is DENIED; last ACL checked: Safe_ports
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.501 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.501 kid1| 28,5| Gadgets.cc(83) aclIsProxyAuth: aclIsProxyAuth: called for Safe_ports
2017/08/11 00:24:35.501 kid1| 28,9| Acl.cc(99) FindByName: ACL::FindByName 'Safe_ports'
2017/08/11 00:24:35.501 kid1| 28,5| Gadgets.cc(88) aclIsProxyAuth: aclIsProxyAuth: returning 0
2017/08/11 00:24:35.501 kid1| 85,5| client_side_request.cc(760) clientAccessCheckDone: Access Denied: 185.89.12.132:443
2017/08/11 00:24:35.501 kid1| 85,5| client_side_request.cc(761) clientAccessCheckDone: AclMatchedName = Safe_ports
2017/08/11 00:24:35.501 kid1| 28,8| Gadgets.cc(51) aclGetDenyInfoPage: got called for Safe_ports
2017/08/11 00:24:35.502 kid1| 28,8| Gadgets.cc(70) aclGetDenyInfoPage: aclGetDenyInfoPage: no match
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x83209a218
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=7
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=6
2017/08/11 00:24:35.502 kid1| 28,4| FilledChecklist.cc(66) ~ACLFilledChecklist: ACLFilledChecklist destroyed 0x7fffffffd2f0
2017/08/11 00:24:35.502 kid1| 28,4| Checklist.cc(197) ~ACLChecklist: ACLChecklist::~ACLChecklist: destroyed 0x7fffffffd2f0
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=7
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=6
2017/08/11 00:24:35.502 kid1| 28,4| FilledChecklist.cc(66) ~ACLFilledChecklist: ACLFilledChecklist destroyed 0x7fffffffd150
2017/08/11 00:24:35.502 kid1| 28,4| Checklist.cc(197) ~ACLChecklist: ACLChecklist::~ACLChecklist: destroyed 0x7fffffffd150
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 85,5| client_side_request.cc(1422) sslBumpAccessCheck: SslBump already decided (3), ignoring ssl_bump for 0x832110118
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.502 kid1| 83,3| client_side_request.cc(1531) sslBumpNeed: sslBump required: peek
2017/08/11 00:24:35.502 kid1| 73,3| HttpRequest.cc(692) storeId: sent back canonicalUrl:185.89.12.132:443
2017/08/11 00:24:35.502 kid1| 20,3| store.cc(805) storeCreatePureEntry: storeCreateEntry: '185.89.12.132:443'
2017/08/11 00:24:35.502 kid1| 20,5| store.cc(370) StoreEntry: StoreEntry constructed, this=0x803190440
2017/08/11 00:24:35.502 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2146 created
2017/08/11 00:24:35.502 kid1| 19,9| stmem.cc(375) mem_hdr: 0x8320c4ea0 hi: 0
2017/08/11 00:24:35.502 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2147 created
2017/08/11 00:24:35.502 kid1| 20,3| MemObject.cc(97) MemObject: new MemObject 0x8320c4e80
2017/08/11 00:24:35.502 kid1| 55,7| HttpHeader.cc(446) HttpHeader: init-ing hdr: 0x8321f4b58 owner: 3
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x83212c3d8
2017/08/11 00:24:35.502 kid1| 55,9| HttpHeader.cc(1247) has: 0x8321f4b58 lookup for 11
2017/08/11 00:24:35.502 kid1| 55,9| HttpHeader.cc(1247) has: 0x8321f4b58 lookup for 49
2017/08/11 00:24:35.502 kid1| 55,9| HttpHeader.cc(1247) has: 0x8321f4b58 lookup for 26
2017/08/11 00:24:35.502 kid1| 24,7| SBuf.cc(139) assign: assigning SBuf2146 from SBuf2139
2017/08/11 00:24:35.502 kid1| 20,3| store.cc(499) setReleaseFlag: StoreEntry::setReleaseFlag: '[null_store_key]'
2017/08/11 00:24:35.502 kid1| 20,3| store_key_md5.cc(89) storeKeyPrivate: storeKeyPrivate: CONNECT 185.89.12.132:443
2017/08/11 00:24:35.502 kid1| 20,3| store.cc(447) hashInsert: StoreEntry::hashInsert: Inserting Entry e:=XI/0x803190440*0 key '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.502 kid1| 20,3| store.cc(483) lock: storeCreateEntry locked key 08D19C5DFA9A8243A8EE6FA30CDA1B5F e:=XIV/0x803190440*1
2017/08/11 00:24:35.502 kid1| 83,3| client_side_request.cc(1531) sslBumpNeed: sslBump required: client-first
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x8031904c8
2017/08/11 00:24:35.502 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2148 created
2017/08/11 00:24:35.502 kid1| 33,4| ServerBump.cc(27) ServerBump: will peek at 185.89.12.132:443
2017/08/11 00:24:35.502 kid1| 20,3| store.cc(483) lock: Ssl::ServerBump locked key 08D19C5DFA9A8243A8EE6FA30CDA1B5F e:=XIV/0x803190440*2
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x8320a88f8
2017/08/11 00:24:35.502 kid1| 4,4| errorpage.cc(602) errorAppendEntry: Creating an error page for entry 0x803190440 with errorstate 0x83209a218 page id 1
2017/08/11 00:24:35.502 kid1| 55,7| HttpHeader.cc(446) HttpHeader: init-ing hdr: 0x8321f4c98 owner: 3
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x83212c318
2017/08/11 00:24:35.502 kid1| 55,9| HttpHeader.cc(1247) has: 0x8321f4c98 lookup for 11
2017/08/11 00:24:35.502 kid1| 55,9| HttpHeader.cc(1247) has: 0x8321f4c98 lookup for 49
2017/08/11 00:24:35.502 kid1| 55,9| HttpHeader.cc(1247) has: 0x8321f4c98 lookup for 26
2017/08/11 00:24:35.502 kid1| 55,9| HttpHeader.cc(1067) getList: 0x832216318 joining for id 3
2017/08/11 00:24:35.502 kid1| 4,2| errorpage.cc(1261) BuildContent: No existing error page language negotiated for ERR_ACCESS_DENIED. Using default error file.
2017/08/11 00:24:35.502 kid1| 45,9| cbdata.cc(299) cbdataInternalAlloc: Allocating 0x83212c358
2017/08/11 00:24:35.503 kid1| 4,3| errorpage.cc(1100) Convert: errorConvert: %%l --> '/*
* Copyright © 1996-2016 The Squid Software Foundation and contributors
*
* Squid software is distributed under GPLv2+ license and includes
* contributions from numerous individuals and organizations.
* Please see the COPYING and CONTRIBUTORS files for details.
*/
/*
Stylesheet for Squid Error pages
Adapted from design by Free CSS Templates
http://www.freecsstemplates.org
Released for free under a Creative Commons Attribution 2.5 License
*/
/* Page basics */
* {
font-family: verdana, sans-serif;
}
html body {
margin: 0;
padding: 0;
background: #efefef;
font-size: 12px;
color: #1e1e1e;
}
/* Page displayed title area */
#titles {
margin-left: 15px;
padding: 10px;
padding-left: 100px;
background: url('/squid-internal-static/icons/SN.png') no-repeat left;
}
/* initial title */
#titles h1 {
color: #000000;
}
#titles h2 {
color: #000000;
}
/* special event: FTP success page titles */
#titles ftpsuccess {
background-color:#00ff00;
width:100%;
}
/* Page displayed body content area */
#content {
padding: 10px;
background: #ffffff;
}
/* General text */
p {
}
/* error brief description */
#error p {
}
/* some data which may have caused the problem */
#data {
}
/* the error message received from the system or other software */
#sysmsg {
}
pre {
font-family:sans-serif;
}
/* special event: FTP / Gopher directory listing */
#dirmsg {
font-family: courier;
color: black;
font-size: 10pt;
}
#dirlisting {
margin-left: 2%;
margin-right: 2%;
}
#dirlisting tr.entry td.icon,td.filename,td.size,td.date {
border-bottom: groove;
}
#dirlisting td.size {
width: 50px;
text-align: right;
padding-right: 5px;
}
/* horizontal lines */
hr {
margin: 0;
}
/* page displayed footer area */
#footer {
font-size: 9px;
padding-left: 10px;
}
'
2017/08/11 00:24:35.503 kid1| 4,3| errorpage.cc(1100) Convert: errorConvert: %%; --> '%;'
2017/08/11 00:24:35.503 kid1| 4,3| errorpage.cc(1100) Convert: errorConvert: %%c --> 'ERR_ACCESS_DENIED'
2017/08/11 00:24:35.503 kid1| 4,3| errorpage.cc(1100) Convert: errorConvert: %%U --> 'https://185.89.12.132/*'
2017/08/11 00:24:35.503 kid1| 4,3| errorpage.cc(1100) Convert: errorConvert: %%U --> 'https://185.89.12.132/*'
2017/08/11 00:24:35.503 kid1| 4,3| errorpage.cc(1100) Convert: errorConvert: %%w --> 'root@insatcom-v.ru'
2017/08/11 00:24:35.503 kid1| 55,7| HttpHeader.cc(817) packInto: 0x832216318 into 0x7fffffffcd78
2017/08/11 00:24:35.503 kid1| 4,3| errorpage.cc(1100) Convert: errorConvert: %%W --> '?subject=CacheErrorInfo%20-%20ERR_ACCESS_DENIED&body=CacheHost%3A%20reestr-proxy%0D%0AErrPage%3A%20ERR_ACCESS_DENIED%0D%0AErr%3A%20%5Bnone%5D%0D%0ATimeStamp%3A%20Thu,%2010%20Aug%202017%2021%3A24%3A35%20GMT%0D%0A%0D%0AClientIP%3A%20EEE.FFF.GGG.HHHH%0D%0A%0D%0AHTTP%20Request%3A%0D%0ACONNECT%20%2F%20HTTP%2F1.1%0AHost%3A%20185.89.12.132%3A443%0D%0A%0D%0A%0D%0A'
2017/08/11 00:24:35.503 kid1| 4,3| errorpage.cc(1100) Convert: errorConvert: %%w --> 'root@insatcom-v.ru'
2017/08/11 00:24:35.503 kid1| 4,3| errorpage.cc(1100) Convert: errorConvert: %%T --> 'Thu, 10 Aug 2017 21:24:35 GMT'
2017/08/11 00:24:35.503 kid1| 4,3| errorpage.cc(1100) Convert: errorConvert: %%h --> 'reestr-proxy'
2017/08/11 00:24:35.503 kid1| 4,3| errorpage.cc(1100) Convert: errorConvert: %%s --> 'squid/3.5.23'
2017/08/11 00:24:35.503 kid1| 4,3| errorpage.cc(1100) Convert: errorConvert: %%c --> 'ERR_ACCESS_DENIED'
2017/08/11 00:24:35.503 kid1| 55,9| HttpHeader.cc(1645) HttpHeaderEntry: created HttpHeaderEntry 0x83212c380: 'Server : squid/3.5.23
2017/08/11 00:24:35.503 kid1| 55,7| HttpHeader.cc(1027) addEntry: 0x8321f4c98 adding entry: 56 at 0
2017/08/11 00:24:35.503 kid1| 55,9| HttpHeader.cc(1645) HttpHeaderEntry: created HttpHeaderEntry 0x8320afd00: 'Mime-Version : 1.0
2017/08/11 00:24:35.503 kid1| 55,7| HttpHeader.cc(1027) addEntry: 0x8321f4c98 adding entry: 42 at 1
2017/08/11 00:24:35.503 kid1| 55,9| HttpHeader.cc(1645) HttpHeaderEntry: created HttpHeaderEntry 0x8320afd80: 'Date : Thu, 10 Aug 2017 21:24:35 GMT
2017/08/11 00:24:35.503 kid1| 55,7| HttpHeader.cc(1027) addEntry: 0x8321f4c98 adding entry: 23 at 2
2017/08/11 00:24:35.503 kid1| 55,9| HttpHeader.cc(1645) HttpHeaderEntry: created HttpHeaderEntry 0x83212c2c0: 'Content-Type : text/html;charset=utf-8
2017/08/11 00:24:35.503 kid1| 55,7| HttpHeader.cc(1027) addEntry: 0x8321f4c98 adding entry: 20 at 3
2017/08/11 00:24:35.503 kid1| 55,9| HttpHeader.cc(1645) HttpHeaderEntry: created HttpHeaderEntry 0x8320cca40: 'Content-Length : 3470
2017/08/11 00:24:35.503 kid1| 55,7| HttpHeader.cc(1027) addEntry: 0x8321f4c98 adding entry: 16 at 4
2017/08/11 00:24:35.503 kid1| 55,9| HttpHeader.cc(1645) HttpHeaderEntry: created HttpHeaderEntry 0x8320afe00: 'X-Squid-Error : ERR_ACCESS_DENIED 0
2017/08/11 00:24:35.503 kid1| 55,7| HttpHeader.cc(1027) addEntry: 0x8321f4c98 adding entry: 75 at 5
2017/08/11 00:24:35.503 kid1| 55,8| HttpHeader.cc(954) delById: 0x8321f4c98 del-by-id 67
2017/08/11 00:24:35.503 kid1| 55,9| HttpHeader.cc(1645) HttpHeaderEntry: created HttpHeaderEntry 0x83212c400: 'Vary : Accept-Language
2017/08/11 00:24:35.503 kid1| 55,7| HttpHeader.cc(1027) addEntry: 0x8321f4c98 adding entry: 67 at 6
2017/08/11 00:24:35.503 kid1| 55,9| HttpHeader.cc(1645) HttpHeaderEntry: created HttpHeaderEntry 0x83212c440: 'Content-Language : en
2017/08/11 00:24:35.503 kid1| 55,7| HttpHeader.cc(1027) addEntry: 0x8321f4c98 adding entry: 15 at 7
2017/08/11 00:24:35.503 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x83212c318
2017/08/11 00:24:35.503 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x83212c318
2017/08/11 00:24:35.503 kid1| 11,5| HttpRequest.cc(472) detailError: current error details: 1/0
2017/08/11 00:24:35.503 kid1| 20,3| store.cc(483) lock: StoreEntry::storeErrorResponse locked key 08D19C5DFA9A8243A8EE6FA30CDA1B5F e:=XIV/0x803190440*3
2017/08/11 00:24:35.503 kid1| 20,3| store.cc(1906) replaceHttpReply: StoreEntry::replaceHttpReply: 185.89.12.132:443
2017/08/11 00:24:35.503 kid1| 55,7| HttpHeader.cc(480) clean: cleaning hdr: 0x8321f4b58 owner: 3
2017/08/11 00:24:35.503 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x83212c3d8
2017/08/11 00:24:35.503 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x83212c3d8
2017/08/11 00:24:35.503 kid1| 55,7| HttpHeader.cc(480) clean: cleaning hdr: 0x8321f4b58 owner: 3
2017/08/11 00:24:35.503 kid1| 57,9| StatusLine.cc(64) packInto: packing sline 0x8321f4d38 using 0x7fffffffcfa0:
2017/08/11 00:24:35.503 kid1| 57,9| StatusLine.cc(65) packInto: FORMAT=HTTP/%d.%d %3d %s
2017/08/11 00:24:35.503 kid1| 57,9| StatusLine.cc(66) packInto: HTTP/1.1 403 Forbidden
2017/08/11 00:24:35.503 kid1| 20,5| store.cc(860) write: storeWrite: writing 24 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.503 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 0 len 24
2017/08/11 00:24:35.503 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [0,24) object end 0
2017/08/11 00:24:35.503 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 0
2017/08/11 00:24:35.503 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 24
2017/08/11 00:24:35.503 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 24
2017/08/11 00:24:35.503 kid1| 55,7| HttpHeader.cc(817) packInto: 0x8321f4c98 into 0x7fffffffcfa0
2017/08/11 00:24:35.503 kid1| 20,5| store.cc(860) write: storeWrite: writing 6 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.503 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 24 len 6
2017/08/11 00:24:35.503 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [24,30) object end 24
2017/08/11 00:24:35.503 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 24
2017/08/11 00:24:35.503 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 30
2017/08/11 00:24:35.503 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 30
2017/08/11 00:24:35.504 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.504 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 30 len 2
2017/08/11 00:24:35.504 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [30,32) object end 30
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 30
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 32
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 32
2017/08/11 00:24:35.504 kid1| 20,5| store.cc(860) write: storeWrite: writing 12 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.504 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 32 len 12
2017/08/11 00:24:35.504 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [32,44) object end 32
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 32
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 44
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 44
2017/08/11 00:24:35.504 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.504 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 44 len 2
2017/08/11 00:24:35.504 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [44,46) object end 44
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 44
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 46
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 46
2017/08/11 00:24:35.504 kid1| 20,5| store.cc(860) write: storeWrite: writing 12 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.504 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 46 len 12
2017/08/11 00:24:35.504 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [46,58) object end 46
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 46
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 58
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 58
2017/08/11 00:24:35.504 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.504 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 58 len 2
2017/08/11 00:24:35.504 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [58,60) object end 58
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 58
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 60
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 60
2017/08/11 00:24:35.504 kid1| 20,5| store.cc(860) write: storeWrite: writing 3 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.504 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 60 len 3
2017/08/11 00:24:35.504 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [60,63) object end 60
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 60
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 63
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 63
2017/08/11 00:24:35.504 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.504 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 63 len 2
2017/08/11 00:24:35.504 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [63,65) object end 63
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 63
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 65
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 65
2017/08/11 00:24:35.504 kid1| 20,5| store.cc(860) write: storeWrite: writing 4 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.504 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 65 len 4
2017/08/11 00:24:35.504 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [65,69) object end 65
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 65
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 69
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 69
2017/08/11 00:24:35.504 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.504 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 69 len 2
2017/08/11 00:24:35.504 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [69,71) object end 69
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 69
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 71
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 71
2017/08/11 00:24:35.504 kid1| 20,5| store.cc(860) write: storeWrite: writing 29 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.504 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 71 len 29
2017/08/11 00:24:35.504 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [71,100) object end 71
2017/08/11 00:24:35.504 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 71
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 100
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 100
2017/08/11 00:24:35.505 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.505 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 100 len 2
2017/08/11 00:24:35.505 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [100,102) object end 100
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 100
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 102
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 102
2017/08/11 00:24:35.505 kid1| 20,5| store.cc(860) write: storeWrite: writing 12 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.505 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 102 len 12
2017/08/11 00:24:35.505 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [102,114) object end 102
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 102
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 114
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 114
2017/08/11 00:24:35.505 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.505 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 114 len 2
2017/08/11 00:24:35.505 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [114,116) object end 114
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 114
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 116
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 116
2017/08/11 00:24:35.505 kid1| 20,5| store.cc(860) write: storeWrite: writing 23 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.505 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 116 len 23
2017/08/11 00:24:35.505 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [116,139) object end 116
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 116
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 139
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 139
2017/08/11 00:24:35.505 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.505 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 139 len 2
2017/08/11 00:24:35.505 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [139,141) object end 139
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 139
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 141
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 141
2017/08/11 00:24:35.505 kid1| 20,5| store.cc(860) write: storeWrite: writing 14 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.505 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 141 len 14
2017/08/11 00:24:35.505 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [141,155) object end 141
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 141
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 155
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 155
2017/08/11 00:24:35.505 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.505 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 155 len 2
2017/08/11 00:24:35.505 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [155,157) object end 155
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 155
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 157
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 157
2017/08/11 00:24:35.505 kid1| 20,5| store.cc(860) write: storeWrite: writing 4 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.505 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 157 len 4
2017/08/11 00:24:35.505 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [157,161) object end 157
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 157
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 161
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 161
2017/08/11 00:24:35.505 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.505 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 161 len 2
2017/08/11 00:24:35.505 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [161,163) object end 161
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 161
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 163
2017/08/11 00:24:35.505 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 163
2017/08/11 00:24:35.505 kid1| 20,5| store.cc(860) write: storeWrite: writing 13 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.505 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 163 len 13
2017/08/11 00:24:35.506 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [163,176) object end 163
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 163
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 176
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 176
2017/08/11 00:24:35.506 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.506 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 176 len 2
2017/08/11 00:24:35.506 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [176,178) object end 176
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 176
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 178
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 178
2017/08/11 00:24:35.506 kid1| 20,5| store.cc(860) write: storeWrite: writing 19 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.506 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 178 len 19
2017/08/11 00:24:35.506 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [178,197) object end 178
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 178
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 197
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 197
2017/08/11 00:24:35.506 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.506 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 197 len 2
2017/08/11 00:24:35.506 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [197,199) object end 197
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 197
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 199
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 199
2017/08/11 00:24:35.506 kid1| 20,5| store.cc(860) write: storeWrite: writing 4 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.506 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 199 len 4
2017/08/11 00:24:35.506 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [199,203) object end 199
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 199
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 203
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 203
2017/08/11 00:24:35.506 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.506 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 203 len 2
2017/08/11 00:24:35.506 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [203,205) object end 203
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 203
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 205
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 205
2017/08/11 00:24:35.506 kid1| 20,5| store.cc(860) write: storeWrite: writing 15 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.506 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 205 len 15
2017/08/11 00:24:35.506 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [205,220) object end 205
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 205
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 220
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 220
2017/08/11 00:24:35.506 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.506 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 220 len 2
2017/08/11 00:24:35.506 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [220,222) object end 220
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 220
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 222
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 222
2017/08/11 00:24:35.506 kid1| 20,5| store.cc(860) write: storeWrite: writing 16 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.506 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 222 len 16
2017/08/11 00:24:35.506 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [222,238) object end 222
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 222
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 238
2017/08/11 00:24:35.506 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 238
2017/08/11 00:24:35.506 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.506 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 238 len 2
2017/08/11 00:24:35.506 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [238,240) object end 238
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 238
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 240
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 240
2017/08/11 00:24:35.507 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.507 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 240 len 2
2017/08/11 00:24:35.507 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [240,242) object end 240
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 240
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 242
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 242
2017/08/11 00:24:35.507 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.507 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 242 len 2
2017/08/11 00:24:35.507 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [242,244) object end 242
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 242
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 244
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 244
2017/08/11 00:24:35.507 kid1| 20,5| store.cc(860) write: storeWrite: writing 2 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.507 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 244 len 2
2017/08/11 00:24:35.507 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [244,246) object end 244
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 244
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 246
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 246
2017/08/11 00:24:35.507 kid1| 20,5| store.cc(860) write: storeWrite: writing 3470 bytes for '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.507 kid1| 19,6| MemObject.cc(151) write: memWrite: offset 246 len 3470
2017/08/11 00:24:35.507 kid1| 19,6| stmem.cc(344) write: mem_hdr::write: 0x8320c4ea0 [246,3716) object end 246
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(124) writeAvailable: 0x8320c4ea0 hi: 246
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(131) writeAvailable: 0x8320c4ea0 hi: 3716
2017/08/11 00:24:35.507 kid1| 19,9| stmem.cc(132) writeAvailable: 0x8320c4ea0 hi: 3716
2017/08/11 00:24:35.507 kid1| 20,2| store.cc(980) checkCachable: StoreEntry::checkCachable: NO: not cachable
2017/08/11 00:24:35.507 kid1| 20,3| store_swapout.cc(381) mayStartSwapOut: not cachable
2017/08/11 00:24:35.507 kid1| 20,2| store.cc(980) checkCachable: StoreEntry::checkCachable: NO: not cachable
2017/08/11 00:24:35.507 kid1| 20,7| store_dir.cc(821) memoryOut: keepInLocalMemory: 0
2017/08/11 00:24:35.507 kid1| 88,7| store.cc(2009) trimMemory: e:=XIV/0x803190440*3 inmem_lo=0
2017/08/11 00:24:35.507 kid1| 90,3| store_client.cc(732) invokeHandlers: InvokeHandlers: 08D19C5DFA9A8243A8EE6FA30CDA1B5F
2017/08/11 00:24:35.507 kid1| 90,3| store_client.cc(738) invokeHandlers: StoreEntry::InvokeHandlers: checking client #0
2017/08/11 00:24:35.507 kid1| 20,3| store.cc(1079) complete: storeComplete: '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.507 kid1| 20,3| store.cc(1368) validLength: storeEntryValidLength: Checking '08D19C5DFA9A8243A8EE6FA30CDA1B5F'
2017/08/11 00:24:35.507 kid1| 20,5| store.cc(1370) validLength: storeEntryValidLength: object_len = 3716
2017/08/11 00:24:35.507 kid1| 20,5| store.cc(1371) validLength: storeEntryValidLength: hdr_sz = 246
2017/08/11 00:24:35.507 kid1| 20,5| store.cc(1372) validLength: storeEntryValidLength: content_length = 3470
2017/08/11 00:24:35.507 kid1| 20,3| store_swapout.cc(356) mayStartSwapOut: already rejected
2017/08/11 00:24:35.507 kid1| 20,2| store.cc(980) checkCachable: StoreEntry::checkCachable: NO: not cachable
2017/08/11 00:24:35.507 kid1| 20,7| store_dir.cc(821) memoryOut: keepInLocalMemory: 0
2017/08/11 00:24:35.507 kid1| 88,7| store.cc(2009) trimMemory: e:=sXIV/0x803190440*3 inmem_lo=0
2017/08/11 00:24:35.507 kid1| 90,3| store_client.cc(732) invokeHandlers: InvokeHandlers: 08D19C5DFA9A8243A8EE6FA30CDA1B5F
2017/08/11 00:24:35.507 kid1| 90,3| store_client.cc(738) invokeHandlers: StoreEntry::InvokeHandlers: checking client #0
2017/08/11 00:24:35.507 kid1| 20,3| store.cc(521) unlock: StoreEntry::storeErrorResponse unlocking key 08D19C5DFA9A8243A8EE6FA30CDA1B5F e:=sXINV/0x803190440*3
2017/08/11 00:24:35.507 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x83209a218
2017/08/11 00:24:35.507 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x83209a218
2017/08/11 00:24:35.507 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.507 kid1| 20,3| store.cc(521) unlock: ClientHttpRequest::doCallouts+sslBumpNeeded unlocking key 08D19C5DFA9A8243A8EE6FA30CDA1B5F e:=sXINV/0x803190440*2
2017/08/11 00:24:35.507 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x802e16b98=2
2017/08/11 00:24:35.507 kid1| 85,3| client_side_request.cc(115) ~ClientRequestContext: 0x8031903e8 ClientRequestContext destructed
2017/08/11 00:24:35.507 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x8031903e8
2017/08/11 00:24:35.507 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x8031903e8
2017/08/11 00:24:35.507 kid1| 83,3| client_side_request.cc(1831) doCallouts: calling processRequest()
2017/08/11 00:24:35.507 kid1| 85,4| client_side_request.cc(1494) processRequest: CONNECT 185.89.12.132:443
2017/08/11 00:24:35.507 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.507 kid1| 85,5| client_side_request.cc(1577) sslBumpStart: Confirming client-first-bumped CONNECT tunnel on FD local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33
2017/08/11 00:24:35.507 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x802e16b98=3
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x802e16b98=4
2017/08/11 00:24:35.508 kid1| 85,5| AsyncCall.cc(26) AsyncCall: The AsyncCall ClientSocketContext::sslBumpEstablish constructed, this=0x8320d2780 [call1692]
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x802e16b98=5
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x802e16b98=4
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x802e16b98=3
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.508 kid1| 85,5| AsyncCall.cc(93) ScheduleCall: client_side_request.cc(1587) will call ClientSocketContext::sslBumpEstablish(local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33, data=0x802e16b98) [call1692]
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=5
2017/08/11 00:24:35.508 kid1| 28,4| FilledChecklist.cc(66) ~ACLFilledChecklist: ACLFilledChecklist destroyed 0x802e17618
2017/08/11 00:24:35.508 kid1| 28,4| Checklist.cc(197) ~ACLChecklist: ACLChecklist::~ACLChecklist: destroyed 0x802e17618
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x802e17618
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x802e17618
2017/08/11 00:24:35.508 kid1| 33,3| client_side.cc(3265) clientParseRequests: Not parsing new requests, as this request may need the connection
2017/08/11 00:24:35.508 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2136 destructed
2017/08/11 00:24:35.508 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2135 destructed
2017/08/11 00:24:35.508 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x8321306c0 id=blob662 capacity=40 size=17
2017/08/11 00:24:35.508 kid1| 55,7| HttpHeader.cc(480) clean: cleaning hdr: 0x802d98d18 owner: 2
2017/08/11 00:24:35.508 kid1| 93,7| HttpRequest.cc(55) ~HttpRequest: destructed, this=0x802d98d00
2017/08/11 00:24:35.508 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2133 destructed
2017/08/11 00:24:35.508 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2132 destructed
2017/08/11 00:24:35.508 kid1| 55,7| HttpHeader.cc(480) clean: cleaning hdr: 0x802d98d18 owner: 2
2017/08/11 00:24:35.508 kid1| 28,4| FilledChecklist.cc(66) ~ACLFilledChecklist: ACLFilledChecklist destroyed 0x802e16f18
2017/08/11 00:24:35.508 kid1| 28,4| Checklist.cc(197) ~ACLChecklist: ACLChecklist::~ACLChecklist: destroyed 0x802e16f18
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x802e16f18
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x802e16f18
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.508 kid1| 93,5| AsyncJob.cc(152) callEnd: Http::Server status out: [ job209]
2017/08/11 00:24:35.508 kid1| 93,5| AsyncCallQueue.cc(57) fireNext: leaving AsyncJob::start()
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=4
2017/08/11 00:24:35.508 kid1| 5,4| AsyncCallQueue.cc(55) fireNext: entering commStartSslClose(FD 74)
2017/08/11 00:24:35.508 kid1| 5,4| AsyncCall.cc(38) make: make call commStartSslClose [call1685]
2017/08/11 00:24:35.508 kid1| 83,5| bio.cc(95) write: FD 74 wrote 31 <= 31
2017/08/11 00:24:35.508 kid1| 83,7| bio.cc(168) stateChanged: FD 74 now: 0x4008 SSLOK (SSL negotiation finished successfully)
2017/08/11 00:24:35.508 kid1| 5,4| AsyncCallQueue.cc(57) fireNext: leaving commStartSslClose(FD 74)
2017/08/11 00:24:35.508 kid1| 33,5| AsyncCallQueue.cc(55) fireNext: entering ConnStateData::connStateClosed(FD -1, data=0x83210f218)
2017/08/11 00:24:35.508 kid1| 33,5| AsyncCall.cc(38) make: make call ConnStateData::connStateClosed [call1601]
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f218
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f218
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f218
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f218
2017/08/11 00:24:35.508 kid1| 33,5| AsyncJob.cc(123) callStart: Http::Server status in: [ job191]
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f218
2017/08/11 00:24:35.508 kid1| 93,4| AsyncJob.cc(55) deleteThis: Http::Server will NOT delete in-call job, reason: ConnStateData::connStateClosed
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f218
2017/08/11 00:24:35.508 kid1| 93,5| AsyncJob.cc(137) callEnd: ConnStateData::connStateClosed(FD -1, data=0x83210f218) ends job [stopped, reason:ConnStateData::connStateClosed job191]
2017/08/11 00:24:35.508 kid1| 33,2| client_side.cc(832) swanSong: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52242 flags=33
2017/08/11 00:24:35.508 kid1| 33,3| client_side.cc(5136) unpinConnection:
2017/08/11 00:24:35.508 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1963 destructed
2017/08/11 00:24:35.508 kid1| 33,3| client_side.cc(863) ~ConnStateData: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52242 flags=33
2017/08/11 00:24:35.508 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f218
2017/08/11 00:24:35.508 kid1| 33,4| ServerBump.cc(44) ~ServerBump: destroying
2017/08/11 00:24:35.508 kid1| 33,4| ServerBump.cc(46) ~ServerBump: e:=sXINV/0x80318f560*1
2017/08/11 00:24:35.508 kid1| 90,3| store_client.cc(664) storeUnregister: storeUnregister: called for '2AA92E43789F0881470061A0E3F894E2'
2017/08/11 00:24:35.508 kid1| 20,3| store_swapout.cc(356) mayStartSwapOut: already rejected
2017/08/11 00:24:35.508 kid1| 20,2| store.cc(980) checkCachable: StoreEntry::checkCachable: NO: not cachable
2017/08/11 00:24:35.508 kid1| 20,7| store_dir.cc(821) memoryOut: keepInLocalMemory: 0
2017/08/11 00:24:35.509 kid1| 19,8| stmem.cc(83) freeDataUpto: 0x8320c2620 up to 3717
2017/08/11 00:24:35.509 kid1| 88,7| store.cc(2009) trimMemory: e:=sXINV/0x80318f560*1 inmem_lo=3717
2017/08/11 00:24:35.509 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x8320a9938
2017/08/11 00:24:35.509 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x8320a9938
2017/08/11 00:24:35.509 kid1| 20,3| store.cc(483) lock: storeUnregister locked key 2AA92E43789F0881470061A0E3F894E2 e:=sXINV/0x80318f560*2
2017/08/11 00:24:35.509 kid1| 90,3| store_client.cc(758) storePendingNClients: storePendingNClients: returning 0
2017/08/11 00:24:35.509 kid1| 20,3| store.cc(521) unlock: storeUnregister unlocking key 2AA92E43789F0881470061A0E3F894E2 e:=sXINV/0x80318f560*2
2017/08/11 00:24:35.509 kid1| 20,3| store.cc(521) unlock: Ssl::ServerBump unlocking key 2AA92E43789F0881470061A0E3F894E2 e:=sXINV/0x80318f560*1
2017/08/11 00:24:35.509 kid1| 90,3| store_client.cc(758) storePendingNClients: storePendingNClients: returning 0
2017/08/11 00:24:35.509 kid1| 20,3| store.cc(1270) release: releasing e:=sXINV/0x80318f560*0 2AA92E43789F0881470061A0E3F894E2
2017/08/11 00:24:35.509 kid1| 20,3| store.cc(402) destroyMemObject: destroyMemObject 0x8320c2600
2017/08/11 00:24:35.509 kid1| 20,3| MemObject.cc(110) ~MemObject: del MemObject 0x8320c2600
2017/08/11 00:24:35.509 kid1| ctx: enter level 0: '185.89.12.132:443'
2017/08/11 00:24:35.509 kid1| 19,9| stmem.cc(63) freeContent: 0x8320c2620 hi: 0
2017/08/11 00:24:35.509 kid1| 55,7| HttpHeader.cc(480) clean: cleaning hdr: 0x832079ed8 owner: 3
2017/08/11 00:24:35.509 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x8031fd480: 'Server: squid/3.5.23'
2017/08/11 00:24:35.509 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x8320afc40: 'Mime-Version: 1.0'
2017/08/11 00:24:35.509 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x8320af6c0: 'Date: Thu, 10 Aug 2017 21:24:35 GMT'
2017/08/11 00:24:35.509 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x83209be00: 'Content-Type: text/html;charset=utf-8'
2017/08/11 00:24:35.509 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x83209b100: 'Content-Length: 3470'
2017/08/11 00:24:35.509 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x8320c5b80: 'X-Squid-Error: ERR_ACCESS_DENIED 0'
2017/08/11 00:24:35.509 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x83209b5c0: 'Vary: Accept-Language'
2017/08/11 00:24:35.509 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x8320cc040: 'Content-Language: en'
2017/08/11 00:24:35.509 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x8320af9d8
2017/08/11 00:24:35.509 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x8320af9d8
2017/08/11 00:24:35.509 kid1| 55,7| HttpHeader.cc(480) clean: cleaning hdr: 0x832079ed8 owner: 3
2017/08/11 00:24:35.509 kid1| ctx: exit level 0
2017/08/11 00:24:35.509 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1991 destructed
2017/08/11 00:24:35.509 kid1| 19,9| stmem.cc(63) freeContent: 0x8320c2620 hi: 0
2017/08/11 00:24:35.509 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1990 destructed
2017/08/11 00:24:35.509 kid1| 20,3| store.cc(420) destroyStoreEntry: destroyStoreEntry: destroying 0x80318f568
2017/08/11 00:24:35.509 kid1| 20,3| store.cc(402) destroyMemObject: destroyMemObject 0x0
2017/08/11 00:24:35.509 kid1| 20,5| store.cc(375) ~StoreEntry: StoreEntry destructed, this=0x80318f560
2017/08/11 00:24:35.509 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1992 destructed
2017/08/11 00:24:35.509 kid1| 55,7| HttpHeader.cc(480) clean: cleaning hdr: 0x802d97f18 owner: 2
2017/08/11 00:24:35.509 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x8031fd4c0: 'Host: 185.89.12.132:443'
2017/08/11 00:24:35.509 kid1| 93,7| HttpRequest.cc(55) ~HttpRequest: destructed, this=0x802d97f00
2017/08/11 00:24:35.509 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f218=2
2017/08/11 00:24:35.509 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1984 destructed
2017/08/11 00:24:35.509 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1983 destructed
2017/08/11 00:24:35.509 kid1| 55,7| HttpHeader.cc(480) clean: cleaning hdr: 0x802d97f18 owner: 2
2017/08/11 00:24:35.509 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x80318f3b8
2017/08/11 00:24:35.509 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x80318f3b8
2017/08/11 00:24:35.509 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1962 destructed
2017/08/11 00:24:35.509 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1961 destructed
2017/08/11 00:24:35.509 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x8320a3e00 id=blob618 capacity=40 size=13
2017/08/11 00:24:35.509 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1960 destructed
2017/08/11 00:24:35.509 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x8321309c0 id=blob660 capacity=4096 size=0
2017/08/11 00:24:35.509 kid1| 93,5| AsyncJob.cc(40) ~AsyncJob: AsyncJob destructed, this=0x83210f3e0 type=Http::Server [job191]
2017/08/11 00:24:35.509 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x83210f218
2017/08/11 00:24:35.509 kid1| 45,9| cbdata.cc(333) cbdataInternalFree: 0x83210f218 has 2 locks, not freeing
2017/08/11 00:24:35.509 kid1| 93,6| AsyncJob.cc(147) callEnd: ConnStateData::connStateClosed(FD -1, data=0x83210f218) ended 0x83210f3e0
2017/08/11 00:24:35.509 kid1| 33,5| AsyncCallQueue.cc(57) fireNext: leaving ConnStateData::connStateClosed(FD -1, data=0x83210f218)
2017/08/11 00:24:35.509 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f218=1
2017/08/11 00:24:35.509 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f218=0
2017/08/11 00:24:35.509 kid1| 45,9| cbdata.cc(449) cbdataInternalUnlock: Freeing 0x83210f218
2017/08/11 00:24:35.509 kid1| 5,4| AsyncCallQueue.cc(55) fireNext: entering comm_close_complete(FD 74)
2017/08/11 00:24:35.509 kid1| 5,4| AsyncCall.cc(38) make: make call comm_close_complete [call1686]
2017/08/11 00:24:35.510 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1996 destructed
2017/08/11 00:24:35.510 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x832093130 id=blob644 capacity=1024 size=517
2017/08/11 00:24:35.510 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1995 destructed
2017/08/11 00:24:35.510 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x8320a2600 id=blob645 capacity=40 size=32
2017/08/11 00:24:35.510 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1994 destructed
2017/08/11 00:24:35.510 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x8320c9500 id=blob647 capacity=40 size=21
2017/08/11 00:24:35.510 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1993 destructed
2017/08/11 00:24:35.510 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x8320c82a0 id=blob646 capacity=40 size=12
2017/08/11 00:24:35.510 kid1| 83,7| bio.cc(83) ~Bio: Bio destructing, this=0x832038a00 FD 74
2017/08/11 00:24:35.510 kid1| 51,3| fd.cc(93) fd_close: fd_close FD 74 client https start
2017/08/11 00:24:35.510 kid1| 5,5| ModKqueue.cc(174) SetSelect: FD 74, type=1, handler=0, client_data=0x0, timeout=0
2017/08/11 00:24:35.510 kid1| 5,5| ModKqueue.cc(174) SetSelect: FD 74, type=2, handler=0, client_data=0x0, timeout=0
2017/08/11 00:24:35.510 kid1| 5,5| AcceptLimiter.cc(55) kick: size=0
2017/08/11 00:24:35.510 kid1| 5,4| AsyncCallQueue.cc(57) fireNext: leaving comm_close_complete(FD 74)
2017/08/11 00:24:35.510 kid1| 5,4| AsyncCallQueue.cc(55) fireNext: entering commStartSslClose(FD 76)
2017/08/11 00:24:35.510 kid1| 5,4| AsyncCall.cc(38) make: make call commStartSslClose [call1687]
2017/08/11 00:24:35.510 kid1| 83,5| bio.cc(95) write: FD 76 wrote 31 <= 31
2017/08/11 00:24:35.510 kid1| 83,7| bio.cc(168) stateChanged: FD 76 now: 0x4008 SSLOK (SSL negotiation finished successfully)
2017/08/11 00:24:35.510 kid1| 5,4| AsyncCallQueue.cc(57) fireNext: leaving commStartSslClose(FD 76)
2017/08/11 00:24:35.510 kid1| 33,5| AsyncCallQueue.cc(55) fireNext: entering ConnStateData::connStateClosed(FD -1, data=0x83210f498)
2017/08/11 00:24:35.510 kid1| 33,5| AsyncCall.cc(38) make: make call ConnStateData::connStateClosed [call1613]
2017/08/11 00:24:35.510 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f498
2017/08/11 00:24:35.510 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f498
2017/08/11 00:24:35.510 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f498
2017/08/11 00:24:35.510 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f498
2017/08/11 00:24:35.510 kid1| 33,5| AsyncJob.cc(123) callStart: Http::Server status in: [ job194]
2017/08/11 00:24:35.510 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f498
2017/08/11 00:24:35.510 kid1| 93,4| AsyncJob.cc(55) deleteThis: Http::Server will NOT delete in-call job, reason: ConnStateData::connStateClosed
2017/08/11 00:24:35.510 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f498
2017/08/11 00:24:35.510 kid1| 93,5| AsyncJob.cc(137) callEnd: ConnStateData::connStateClosed(FD -1, data=0x83210f498) ends job [stopped, reason:ConnStateData::connStateClosed job194]
2017/08/11 00:24:35.510 kid1| 33,2| client_side.cc(832) swanSong: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52243 flags=33
2017/08/11 00:24:35.510 kid1| 33,3| client_side.cc(5136) unpinConnection:
2017/08/11 00:24:35.510 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2000 destructed
2017/08/11 00:24:35.510 kid1| 33,3| client_side.cc(863) ~ConnStateData: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52243 flags=33
2017/08/11 00:24:35.510 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x83210f498
2017/08/11 00:24:35.510 kid1| 33,4| ServerBump.cc(44) ~ServerBump: destroying
2017/08/11 00:24:35.510 kid1| 33,4| ServerBump.cc(46) ~ServerBump: e:=sXINV/0x80318fb10*1
2017/08/11 00:24:35.510 kid1| 90,3| store_client.cc(664) storeUnregister: storeUnregister: called for '893DB4F3F0E1D92F9A3D947CF0375632'
2017/08/11 00:24:35.510 kid1| 20,3| store_swapout.cc(356) mayStartSwapOut: already rejected
2017/08/11 00:24:35.510 kid1| 20,2| store.cc(980) checkCachable: StoreEntry::checkCachable: NO: not cachable
2017/08/11 00:24:35.510 kid1| 20,7| store_dir.cc(821) memoryOut: keepInLocalMemory: 0
2017/08/11 00:24:35.510 kid1| 19,8| stmem.cc(83) freeDataUpto: 0x8320c30a0 up to 3717
2017/08/11 00:24:35.510 kid1| 88,7| store.cc(2009) trimMemory: e:=sXINV/0x80318fb10*1 inmem_lo=3717
2017/08/11 00:24:35.510 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x8320a8cb8
2017/08/11 00:24:35.510 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x8320a8cb8
2017/08/11 00:24:35.510 kid1| 20,3| store.cc(483) lock: storeUnregister locked key 893DB4F3F0E1D92F9A3D947CF0375632 e:=sXINV/0x80318fb10*2
2017/08/11 00:24:35.510 kid1| 90,3| store_client.cc(758) storePendingNClients: storePendingNClients: returning 0
2017/08/11 00:24:35.510 kid1| 20,3| store.cc(521) unlock: storeUnregister unlocking key 893DB4F3F0E1D92F9A3D947CF0375632 e:=sXINV/0x80318fb10*2
2017/08/11 00:24:35.510 kid1| 20,3| store.cc(521) unlock: Ssl::ServerBump unlocking key 893DB4F3F0E1D92F9A3D947CF0375632 e:=sXINV/0x80318fb10*1
2017/08/11 00:24:35.510 kid1| 90,3| store_client.cc(758) storePendingNClients: storePendingNClients: returning 0
2017/08/11 00:24:35.510 kid1| 20,3| store.cc(1270) release: releasing e:=sXINV/0x80318fb10*0 893DB4F3F0E1D92F9A3D947CF0375632
2017/08/11 00:24:35.510 kid1| 20,3| store.cc(402) destroyMemObject: destroyMemObject 0x8320c3080
2017/08/11 00:24:35.510 kid1| 20,3| MemObject.cc(110) ~MemObject: del MemObject 0x8320c3080
2017/08/11 00:24:35.510 kid1| ctx: enter level 0: '185.89.12.132:443'
2017/08/11 00:24:35.510 kid1| 19,9| stmem.cc(63) freeContent: 0x8320c30a0 hi: 0
2017/08/11 00:24:35.510 kid1| 55,7| HttpHeader.cc(480) clean: cleaning hdr: 0x832078858 owner: 3
2017/08/11 00:24:35.511 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x83209b580: 'Server: squid/3.5.23'
2017/08/11 00:24:35.511 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x83212ce00: 'Mime-Version: 1.0'
2017/08/11 00:24:35.511 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x83212cd00: 'Date: Thu, 10 Aug 2017 21:24:35 GMT'
2017/08/11 00:24:35.511 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x83212cc80: 'Content-Type: text/html;charset=utf-8'
2017/08/11 00:24:35.511 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x83212cdc0: 'Content-Length: 3470'
2017/08/11 00:24:35.511 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x83212cd40: 'X-Squid-Error: ERR_ACCESS_DENIED 0'
2017/08/11 00:24:35.511 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x8031fd5c0: 'Vary: Accept-Language'
2017/08/11 00:24:35.511 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x8031fd600: 'Content-Language: en'
2017/08/11 00:24:35.511 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x8320af758
2017/08/11 00:24:35.511 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x8320af758
2017/08/11 00:24:35.511 kid1| 55,7| HttpHeader.cc(480) clean: cleaning hdr: 0x832078858 owner: 3
2017/08/11 00:24:35.511 kid1| ctx: exit level 0
2017/08/11 00:24:35.511 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2016 destructed
2017/08/11 00:24:35.511 kid1| 19,9| stmem.cc(63) freeContent: 0x8320c30a0 hi: 0
2017/08/11 00:24:35.511 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2015 destructed
2017/08/11 00:24:35.511 kid1| 20,3| store.cc(420) destroyStoreEntry: destroyStoreEntry: destroying 0x80318fb18
2017/08/11 00:24:35.511 kid1| 20,3| store.cc(402) destroyMemObject: destroyMemObject 0x0
2017/08/11 00:24:35.511 kid1| 20,5| store.cc(375) ~StoreEntry: StoreEntry destructed, this=0x80318fb10
2017/08/11 00:24:35.511 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2017 destructed
2017/08/11 00:24:35.511 kid1| 55,7| HttpHeader.cc(480) clean: cleaning hdr: 0x832214018 owner: 2
2017/08/11 00:24:35.511 kid1| 55,9| HttpHeader.cc(1651) ~HttpHeaderEntry: destroying entry 0x83209b200: 'Host: 185.89.12.132:443'
2017/08/11 00:24:35.511 kid1| 93,7| HttpRequest.cc(55) ~HttpRequest: destructed, this=0x832214000
2017/08/11 00:24:35.511 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f498=2
2017/08/11 00:24:35.511 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2009 destructed
2017/08/11 00:24:35.511 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2008 destructed
2017/08/11 00:24:35.511 kid1| 55,7| HttpHeader.cc(480) clean: cleaning hdr: 0x832214018 owner: 2
2017/08/11 00:24:35.511 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x80318fb98
2017/08/11 00:24:35.511 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x80318fb98
2017/08/11 00:24:35.511 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1999 destructed
2017/08/11 00:24:35.511 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1998 destructed
2017/08/11 00:24:35.511 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x8320a2b10 id=blob623 capacity=40 size=13
2017/08/11 00:24:35.511 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf1997 destructed
2017/08/11 00:24:35.511 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x8320c8de0 id=blob661 capacity=4096 size=0
2017/08/11 00:24:35.511 kid1| 93,5| AsyncJob.cc(40) ~AsyncJob: AsyncJob destructed, this=0x83210f660 type=Http::Server [job194]
2017/08/11 00:24:35.511 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x83210f498
2017/08/11 00:24:35.511 kid1| 45,9| cbdata.cc(333) cbdataInternalFree: 0x83210f498 has 2 locks, not freeing
2017/08/11 00:24:35.511 kid1| 93,6| AsyncJob.cc(147) callEnd: ConnStateData::connStateClosed(FD -1, data=0x83210f498) ended 0x83210f660
2017/08/11 00:24:35.511 kid1| 33,5| AsyncCallQueue.cc(57) fireNext: leaving ConnStateData::connStateClosed(FD -1, data=0x83210f498)
2017/08/11 00:24:35.511 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f498=1
2017/08/11 00:24:35.511 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x83210f498=0
2017/08/11 00:24:35.511 kid1| 45,9| cbdata.cc(449) cbdataInternalUnlock: Freeing 0x83210f498
2017/08/11 00:24:35.511 kid1| 5,4| AsyncCallQueue.cc(55) fireNext: entering comm_close_complete(FD 76)
2017/08/11 00:24:35.511 kid1| 5,4| AsyncCall.cc(38) make: make call comm_close_complete [call1688]
2017/08/11 00:24:35.511 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2021 destructed
2017/08/11 00:24:35.511 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x832130270 id=blob648 capacity=1024 size=517
2017/08/11 00:24:35.511 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2020 destructed
2017/08/11 00:24:35.511 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x8321302a0 id=blob649 capacity=40 size=32
2017/08/11 00:24:35.511 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2019 destructed
2017/08/11 00:24:35.511 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x832130360 id=blob651 capacity=40 size=21
2017/08/11 00:24:35.511 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2018 destructed
2017/08/11 00:24:35.511 kid1| 24,9| MemBlob.cc(83) ~MemBlob: destructed, this=0x832130300 id=blob650 capacity=40 size=12
2017/08/11 00:24:35.511 kid1| 83,7| bio.cc(83) ~Bio: Bio destructing, this=0x8320c3200 FD 76
2017/08/11 00:24:35.511 kid1| 51,3| fd.cc(93) fd_close: fd_close FD 76 client https start
2017/08/11 00:24:35.511 kid1| 5,5| ModKqueue.cc(174) SetSelect: FD 76, type=1, handler=0, client_data=0x0, timeout=0
2017/08/11 00:24:35.511 kid1| 5,5| ModKqueue.cc(174) SetSelect: FD 76, type=2, handler=0, client_data=0x0, timeout=0
2017/08/11 00:24:35.511 kid1| 5,5| AcceptLimiter.cc(55) kick: size=0
2017/08/11 00:24:35.511 kid1| 5,4| AsyncCallQueue.cc(57) fireNext: leaving comm_close_complete(FD 76)
2017/08/11 00:24:35.511 kid1| 85,5| AsyncCallQueue.cc(55) fireNext: entering ClientSocketContext::sslBumpEstablish(local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33, data=0x802e16b98)
2017/08/11 00:24:35.512 kid1| 85,5| AsyncCall.cc(38) make: make call ClientSocketContext::sslBumpEstablish [call1692]
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x802e16b98
2017/08/11 00:24:35.512 kid1| 85,5| client_side_request.cc(1540) SslBumpEstablish: responded to CONNECT: 0x802e16b98 ? 0
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x802e16b98
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.512 kid1| 24,6| SBuf.cc(153) assign: SBuf2129 from c-string, n=4294967295)
2017/08/11 00:24:35.512 kid1| 24,7| SBuf.cc(242) append: from c-string to id SBuf2129
2017/08/11 00:24:35.512 kid1| 24,7| SBuf.cc(193) rawSpace: reserving 13 for SBuf2129
2017/08/11 00:24:35.512 kid1| 24,8| SBuf.cc(956) cow: SBuf2129 new size:13
2017/08/11 00:24:35.512 kid1| 24,8| SBuf.cc(926) reAlloc: SBuf2129 new size: 13
2017/08/11 00:24:35.512 kid1| 24,9| MemBlob.cc(57) MemBlob: constructed, this=0x832130420 id=blob666 reserveSize=13
2017/08/11 00:24:35.512 kid1| 24,8| MemBlob.cc(102) memAlloc: blob666 memAlloc: requested=13, received=40
2017/08/11 00:24:35.512 kid1| 24,7| SBuf.cc(935) reAlloc: SBuf2129 new store capacity: 40
2017/08/11 00:24:35.512 kid1| 33,5| client_side.cc(4268) switchToHttps: converting local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33 to SSL
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.512 kid1| 87,3| clientStream.cc(202) clientStreamDetach: clientStreamDetach: Detaching node 0x8320d2698
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x8320d2818=2
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x8320d2698=0
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x8320d2698
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x8320d2698
2017/08/11 00:24:35.512 kid1| 87,3| clientStream.cc(287) clientStreamFree: Freeing clientStreamNode 0x8320d2698
2017/08/11 00:24:35.512 kid1| 87,3| clientStream.cc(223) clientStreamDetach: clientStreamDetach: Calling 1 with cbdata 0x83216d4d0
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x8320d2818
2017/08/11 00:24:35.512 kid1| 87,3| clientStream.cc(202) clientStreamDetach: clientStreamDetach: Detaching node 0x8320d2818
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x8320d2818=1
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x8320d2818
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(333) cbdataInternalFree: 0x8320d2818 has 1 locks, not freeing
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x8320d2818=0
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(449) cbdataInternalUnlock: Freeing 0x8320d2818
2017/08/11 00:24:35.512 kid1| 87,3| clientStream.cc(287) clientStreamFree: Freeing clientStreamNode 0x8320d2818
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x802e16b98=2
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x83216c418
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x83216c418
2017/08/11 00:24:35.512 kid1| 33,3| client_side_request.cc(248) ~ClientHttpRequest: httpRequestFree: 185.89.12.132:443
2017/08/11 00:24:35.512 kid1| 33,9| client_side.cc(593) logRequest: clientLogRequest: al.url='185.89.12.132:443'
2017/08/11 00:24:35.512 kid1| 33,9| client_side.cc(603) logRequest: clientLogRequest: http.code='200'
2017/08/11 00:24:35.512 kid1| 24,7| SBuf.cc(139) assign: assigning SBuf2138 from SBuf2139
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=5
2017/08/11 00:24:35.512 kid1| 28,3| Checklist.cc(70) preCheck: 0x7fffffffe200 checking fast ACLs
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x802b76718=1
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x802b76718
2017/08/11 00:24:35.512 kid1| 28,5| Acl.cc(138) matches: checking access_log daemon:/var/log/squid/access.log
2017/08/11 00:24:35.512 kid1| 28,5| Acl.cc(138) matches: checking (access_log daemon:/var/log/squid/access.log line)
2017/08/11 00:24:35.512 kid1| 28,3| Acl.cc(158) matches: checked: (access_log daemon:/var/log/squid/access.log line) = 1
2017/08/11 00:24:35.512 kid1| 28,3| Acl.cc(158) matches: checked: access_log daemon:/var/log/squid/access.log = 1
2017/08/11 00:24:35.512 kid1| 28,3| Checklist.cc(63) markFinished: 0x7fffffffe200 answer ALLOWED for match
2017/08/11 00:24:35.512 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x802b76718=0
2017/08/11 00:24:35.512 kid1| 50,3| ModDaemon.cc(172) logfile_mod_daemon_append: logfile_mod_daemon_append: daemon:/var/log/squid/access.log: appending 1 bytes
2017/08/11 00:24:35.512 kid1| 50,3| ModDaemon.cc(176) logfile_mod_daemon_append: logfile_mod_daemon_append: current buffer has 3273 of 32768 bytes before append
2017/08/11 00:24:35.512 kid1| 24,7| SBuf.cc(139) assign: assigning SBuf638 from SBuf6
2017/08/11 00:24:35.512 kid1| 50,3| ModDaemon.cc(172) logfile_mod_daemon_append: logfile_mod_daemon_append: daemon:/var/log/squid/access.log: appending 94 bytes
2017/08/11 00:24:35.512 kid1| 50,3| ModDaemon.cc(176) logfile_mod_daemon_append: logfile_mod_daemon_append: current buffer has 3274 of 32768 bytes before append
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=4
2017/08/11 00:24:35.513 kid1| 28,4| FilledChecklist.cc(66) ~ACLFilledChecklist: ACLFilledChecklist destroyed 0x7fffffffe200
2017/08/11 00:24:35.513 kid1| 28,4| Checklist.cc(197) ~ACLChecklist: ACLChecklist::~ACLChecklist: destroyed 0x7fffffffe200
2017/08/11 00:24:35.513 kid1| 93,5| AsyncCall.cc(26) AsyncCall: The AsyncCall Initiate::noteInitiatorAborted constructed, this=0x8031902f0 [call1693]
2017/08/11 00:24:35.513 kid1| 93,5| AsyncCall.cc(93) ScheduleCall: Initiator.cc(40) will call Initiate::noteInitiatorAborted() [call1693]
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=3
2017/08/11 00:24:35.513 kid1| 24,8| SBuf.cc(124) ~SBuf: SBuf2138 destructed
2017/08/11 00:24:35.513 kid1| 93,5| AsyncJob.cc(40) ~AsyncJob: AsyncJob destructed, this=0x802e16ce0 type=ClientHttpRequest [job210]
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x802e16b98
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(333) cbdataInternalFree: 0x802e16b98 has 2 locks, not freeing
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(321) cbdataInternalFree: 0x8321a9818
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(338) cbdataInternalFree: Freeing 0x8321a9818
2017/08/11 00:24:35.513 kid1| 33,5| client_side.cc(4236) getSslContextDone: Using static ssl context.
2017/08/11 00:24:35.513 kid1| 83,5| bio.cc(574) squid_bio_ctrl: 0x80318f950 104(6000, 0x7fffffffe43c)
2017/08/11 00:24:35.513 kid1| 83,7| bio.cc(78) Bio: Bio constructed, this=0x8320c4700 FD 82
2017/08/11 00:24:35.513 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2149 created
2017/08/11 00:24:35.513 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2150 created
2017/08/11 00:24:35.513 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2151 created
2017/08/11 00:24:35.513 kid1| 24,8| SBuf.cc(79) SBuf: SBuf2152 created
2017/08/11 00:24:35.513 kid1| 33,5| client_side.cc(3725) httpsCreate: will negotate SSL on local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=4
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=5
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=6
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=7
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=6
2017/08/11 00:24:35.513 kid1| 33,5| AsyncCall.cc(26) AsyncCall: The AsyncCall ConnStateData::requestTimeout constructed, this=0x832248280 [call1694]
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(492) cbdataReferenceValid: 0x832110118
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=7
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(394) cbdataInternalLock: 0x832110118=8
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=7
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=6
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x832110118=5
2017/08/11 00:24:35.513 kid1| 5,3| comm.cc(553) commSetConnTimeout: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33 timeout 300
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x802e16b98=1
2017/08/11 00:24:35.513 kid1| 5,5| ModKqueue.cc(174) SetSelect: FD 82, type=1, handler=0, client_data=0x0, timeout=0
2017/08/11 00:24:35.513 kid1| 5,5| ModKqueue.cc(174) SetSelect: FD 82, type=1, handler=1, client_data=0x832110118, timeout=0
2017/08/11 00:24:35.513 kid1| 85,5| AsyncCallQueue.cc(57) fireNext: leaving ClientSocketContext::sslBumpEstablish(local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52248 FD 82 flags=33, data=0x802e16b98)
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(426) cbdataInternalUnlock: 0x802e16b98=0
2017/08/11 00:24:35.513 kid1| 45,9| cbdata.cc(449) cbdataInternalUnlock: Freeing 0x802e16b98
2017/08/11 00:24:35.513 kid1| 93,5| AsyncCallQueue.cc(55) fireNext: entering Initiate::noteInitiatorAborted()
2017/08/11 00:24:35.513 kid1| 93,5| AsyncCall.cc(38) make: make call Initiate::noteInitiatorAborted [call1693]
2017/08/11 00:24:35.513 kid1| 93,5| AsyncCall.cc(56) cancel: will not call Initiate::noteInitiatorAborted [call1693] because job gone
2017/08/11 00:24:35.513 kid1| 93,5| AsyncCall.cc(48) make: will not call Initiate::noteInitiatorAborted [call1693] because of job gone
2017/08/11 00:24:35.513 kid1| 93,5| AsyncCallQueue.cc(57) fireNext: leaving Initiate::noteInitiatorAborted()
2017/08/11 00:24:35.513 kid1| 1,9| EventLoop.cc(41) checkEngine: Engine 0x7fffffffe7d0 is idle.
2017/08/11 00:24:35.513 kid1| 1,9| EventLoop.cc(41) checkEngine: Engine 0x7fffffffe7c8 is idle.
2017/08/11 00:24:35.513 kid1| 5,2| TcpAcceptor.cc(220) doAccept: New connection on FD 40
2017/08/11 00:24:35.513 kid1| 5,2| TcpAcceptor.cc(295) acceptNext: connection on local=192.168.2.230:3128 remote=[::] FD 40 flags=41
2017/08/11 00:24:35.513 kid1| 51,3| fd.cc(198) fd_open: fd_open() FD 74 HTTP Request
2017/08/11 00:24:35.513 kid1| 89,5| Intercept.cc(401) Lookup: address BEGIN: me/client= 5.144.130.34:80, destination/me= 188.43.36.22:62589
2017/08/11 00:24:35.513 kid1| 89,5| Intercept.cc(187) IpfwInterception: address NAT: local=5.144.130.34:80 remote=188.43.36.22:62589 FD 74 flags=33
2017/08/11 00:24:35.514 kid1| 5,5| TcpAcceptor.cc(287) acceptOne: Listener: local=192.168.2.230:3128 remote=[::] FD 40 flags=41 accepted new connection local=5.144.130.34:80 remote=188.43.36.22:62589 FD 74 flags=33 handler Subscription: 0x83204a540*1
2017/08/11 00:24:35.514 kid1| 5,5| AsyncCall.cc(26) AsyncCall: The AsyncCall httpAccept constructed, this=0x8320d2780 [call1695]
2017/08/11 00:24:35.514 kid1| 5,5| AsyncCall.cc(93) ScheduleCall: TcpAcceptor.cc(317) will call httpAccept(local=5.144.130.34:80 remote=188.43.36.22:62589 FD 74 flags=33, MXID_56) [call1695]
2017/08/11 00:24:35.514 kid1| 5,5| ModKqueue.cc(174) SetSelect: FD 40, type=1, handler=1, client_data=0x802f2c658, timeout=0
2017/08/11 00:24:35.514 kid1| 5,2| TcpAcceptor.cc(220) doAccept: New connection on FD 41
2017/08/11 00:24:35.514 kid1| 5,2| TcpAcceptor.cc(295) acceptNext: connection on local=192.168.2.230:3129 remote=[::] FD 41 flags=41
2017/08/11 00:24:35.514 kid1| 51,3| fd.cc(198) fd_open: fd_open() FD 76 HTTP Request
2017/08/11 00:24:35.514 kid1| 89,5| Intercept.cc(401) Lookup: address BEGIN: me/client= 185.89.12.132:443, destination/me= EEE.FFF.GGG.HHHH:52249
2017/08/11 00:24:35.514 kid1| 89,5| Intercept.cc(187) IpfwInterception: address NAT: local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52249 FD 76 flags=33
2017/08/11 00:24:35.514 kid1| 5,5| TcpAcceptor.cc(287) acceptOne: Listener: local=192.168.2.230:3129 remote=[::] FD 41 flags=41 accepted new connection local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52249 FD 76 flags=33 handler Subscription: 0x83204a560*1
2017/08/11 00:24:35.514 kid1| 5,5| AsyncCall.cc(26) AsyncCall: The AsyncCall httpsAccept constructed, this=0x8320d2800 [call1696]
2017/08/11 00:24:35.514 kid1| 5,5| AsyncCall.cc(93) ScheduleCall: TcpAcceptor.cc(317) will call httpsAccept(local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52249 FD 76 flags=33, MXID_57) [call1696]
2017/08/11 00:24:35.514 kid1| 5,5| ModKqueue.cc(174) SetSelect: FD 41, type=1, handler=1, client_data=0x802f2c578, timeout=0
2017/08/11 00:24:35.514 kid1| 5,3| IoCallback.cc(116) finish: called for local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52244 FD 77 flags=33 (0, 0)
2017/08/11 00:24:35.514 kid1| 33,5| AsyncCall.cc(93) ScheduleCall: IoCallback.cc(135) will call ConnStateData::clientReadRequest(local=185.89.12.132:443 remote=EEE.FFF.GGG.HHHH:52244 FD 77 flags=33, data=0x83210f718) [call1683]
2017/08/11 00:24:35.514 kid1| 83,5| bio.cc(118) read: FD 78 read 5 <= 5
2017/08/11 00:24:35.514 kid1| 83,5| bio.cc(118) read: FD 78 read 134 <= 134
2017/08/11 00:24:35.515 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2001 3RCKEA (SSLv3 read client key exchange A)
2017/08/11 00:24:35.515 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2001 3RCV_A (SSLv3 read certificate verify A)
2017/08/11 00:24:35.515 kid1| 83,5| bio.cc(118) read: FD 78 read 5 <= 5
2017/08/11 00:24:35.515 kid1| 83,5| bio.cc(118) read: FD 78 read 1 <= 1
2017/08/11 00:24:35.515 kid1| 83,5| bio.cc(118) read: FD 78 read 5 <= 5
2017/08/11 00:24:35.515 kid1| 83,5| bio.cc(118) read: FD 78 read 40 <= 40
2017/08/11 00:24:35.515 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2001 3RFINA (SSLv3 read finished A)
2017/08/11 00:24:35.515 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2001 UNKWN (SSLv3 write session ticket A)
2017/08/11 00:24:35.515 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2001 3WCCSA (SSLv3 write change cipher spec A)
2017/08/11 00:24:35.515 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2001 3WFINA (SSLv3 write finished A)
2017/08/11 00:24:35.515 kid1| 83,5| bio.cc(95) write: FD 78 wrote 242 <= 242
2017/08/11 00:24:35.515 kid1| 83,5| bio.cc(574) squid_bio_ctrl: 0x80318fd40 11(0, 0x0)
2017/08/11 00:24:35.515 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2001 3FLUSH (SSLv3 flush data)
2017/08/11 00:24:35.515 kid1| 83,5| bio.cc(574) squid_bio_ctrl: 0x80318fd40 7(0, 0x80318f720)
2017/08/11 00:24:35.515 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x20 SSLOK (SSL negotiation finished successfully)
2017/08/11 00:24:35.515 kid1| 83,7| bio.cc(168) stateChanged: FD 78 now: 0x2002 SSLOK (SSL negotiation finished successfully)
-----BEGIN SSL SESSION PARAMETERS-----
MGECAQECAgMDBAIAnAQABDBX/OVEnd+Nx3kPvUOLDEs59tGXbfE187o3fphgTDM2
vvTRtTikwO51F14/TO+JNqWhBgIEWYzPE6IEAgIBLKQCBACmDgQMd3d3LmF2aXRv
LnJ1
-----END SSL SESSION PARAMETERS-----
-
Опубликовано · Изменено пользователем snik_1900 · Жалоба на ответ
snik_1900 (Сегодня, 13:05) писал:
А это тогда что?
Сейчас - "ip". Точно.
Во сколько реестр выгружен? Посмотрю в архиве.
Каждый час. Последний раз домен стоял сегодня в 11:00
в 12:00 уже ip
-
snik_1900 (Сегодня, 12:46) писал:
Имелось в виду тип блокировки domain, а при этом сами домены не указаны.
Во всех трёх записях blocktype=ip вобщето.
А это тогда что? (dump.xml период 23:00 08/08/2017 - 09:00 09/08/2017)
<content id="600670" includeTime="2017-04-12T20:34:48" entryType="4" blockType="domain" hash="8B8887C907B31C787E48044637C7750A"><decision date="2017-04-12" number="4-Restricting" org="inniiiaaci??"/><ip>52.23.186.53</ip></content>
<content id="600697" includeTime="2017-04-28T18:53:56" entryType="4" blockType="domain" hash="4F249FCF98812B92102FF2CC22E249D8"><decision date="2017-04-28" number="7-Restricting" org="inniiiaaci??"/><ipSubnet>203.104.144.0/21</ipSubnet></content>
<content id="600698" includeTime="2017-04-28T18:53:56" entryType="4" blockType="domain" hash="E9B43C13C10A8CDF1F8C5E3A8EA00339"><decision date="2017-04-28" number="7-Restricting" org="inniiiaaci??"/><ipSubnet>203.104.128.0/20</ipSubnet></content>
-
Опубликовано · Изменено пользователем snik_1900 · Жалоба на ответ
Имелось в виду тип блокировки domain, а при этом сами домены не указаны.
-
Опубликовано · Изменено пользователем snik_1900 · Жалоба на ответ
Как вам?
content id="600697"
content id="600698"
content id="600670"
-
Опубликовано · Изменено пользователем snik_1900 · Жалоба на ответ
Удалось добиться:
При попытке открыть https://www.avito.ru сначала попадаю на страничку с предупреждение о возможной подмене сертификата, а потом страничка access denied от squida. Стали генерироваться squid'ом временные сертификаты. Сайт https://www.avito.ru не должен блокироваться (в файле /var/db/zapret-info/denied_https.conf (см. конфиг в первом посте) его нет). То же самое происходит и с https-сайтами имеющимися в /var/db/zapret-info/denied_https.conf.
1) cache.log
2017/07/17 21:17:38 kid1| Error negotiating SSL connection on FD 6660: error:14094416:SSL routines:ssl3_read_bytes:sslv3 alert certificate unknown (1/0)
2017/07/17 21:17:39 kid1| Error negotiating SSL connection on FD 6660: error:14094416:SSL routines:ssl3_read_bytes:sslv3 alert certificate unknown (1/0)
2017/07/17 21:17:39 kid1| Error negotiating SSL connection on FD 6660: error:14094416:SSL routines:ssl3_read_bytes:sslv3 alert certificate unknown (1/0)
2017/07/17 21:17:39 kid1| Error negotiating SSL connection on FD 7909: error:14094416:SSL routines:ssl3_read_bytes:sslv3 alert certificate unknown (1/0)
2017/07/17 21:17:44 kid1| Error negotiating SSL connection on FD 1280: error:14094416:SSL routines:ssl3_read_bytes:sslv3 alert certificate unknown (1/0)
2) access.log
Mon Jul 17 21:17:09 2017 0 AAA.BBB.CCC.DDD TCP_DENIED/200 0 CONNECT 185.89.12.132:443 - HIER_NONE/- -
Mon Jul 17 21:17:09 2017 0 AAA.BBB.CCC.DDD TAG_NONE/403 3851 GET https://www.avito.ru/ - HIER_NONE/- text/html
Mon Jul 17 21:17:09 2017 0 AAA.BBB.CCC.DDD TCP_DENIED/200 0 CONNECT 185.89.12.132:443 - HIER_NONE/- -
Mon Jul 17 21:17:09 2017 0 AAA.BBB.CCC.DDD TCP_DENIED/200 0 CONNECT 185.89.12.132:443 - HIER_NONE/- -
Mon Jul 17 21:17:09 2017 0 AAA.BBB.CCC.DDD TAG_NONE/403 3851 GET http://reestr-proxy:9090/squid-internal-static/icons/SN.png - HIER_NONE/- text/html
Mon Jul 17 21:17:09 2017 0 AAA.BBB.CCC.DDD TCP_DENIED/200 0 CONNECT 185.89.12.132:443 - HIER_NONE/- -
Mon Jul 17 21:17:09 2017 0 AAA.BBB.CCC.DDD TCP_DENIED/200 0 CONNECT 185.89.12.132:443 - HIER_NONE/- -
Mon Jul 17 21:17:09 2017 0 AAA.BBB.CCC.DDD TAG_NONE/403 3851 GET https://www.avito.ru/favicon.ico - HIER_NONE/- text/html
3) данные в файле denied_https.conf хранятся в формате без "https://":
azartpay1.com
azartpay1.top
azartpay1.xyz
azartplay-casino-game.club
azartplay-casino-game1.space
azartplay-casino-games.top
azartplay-casino-online.com
azartplay-casino.club
azartplay-casinogamez.top
azartplay-club.com
azartplay-online.com
azartplay-online.foot-bol.com
azartplay.casino-rate.com
azartplay.cc
azartplay.com
azartplay.foot-bol.com
azartplay.igryflash.com
-
Опубликовано · Изменено пользователем snik_1900 · Жалоба на ответ
Разбираемся с белыми списками.
Строка 826 *.livejournal.com
Открываем реестр:. Куча записей на блокировку с ".livejournal.com".
Задали вопрос РКН. В ответ сказали: "Напишите письмо. Эскалируем проблему в вышестоящие органы."
"И как теперь после этого в цирк с ребенком ходить? Там же скукотища будет..."
-
Нам сегодня с РКН прислали письмо от 07.07.2017 со списком белых адресов, которые не надо блокировать. В списках только 873 домена и ни одного IP.
Кто-то еще получил подобное письмо счастья?
-
Я вот не уверен что fwd делает dst nat.
У меня на PF rdr вроде делает
Но правила:
00050 fwd 192.168.2.230,3128 tcp from not me to any dst-port 80 via igb1 in
00050 fwd 192.168.2.230,3128 tcp from not me to any dst-port 8001 via igb1 in
00050 fwd 192.168.2.230,3128 tcp from not me to any dst-port 8080 via igb1 in
00050 fwd 192.168.2.230,3128 tcp from not me to any dst-port 8081 via igb1 in
00050 fwd 192.168.2.230,3128 tcp from not me to any dst-port 81 via igb1 in
00050 fwd 192.168.2.230,3128 tcp from not me to any dst-port 888 via igb1 in
работают.
Думаю попробовать изменить в настройках squid
wccp2_service dynamic 0
wccp2_service_info 0 protocol=tcp flags=dst_ip_hash,dst_port_hash priority=240 ports=80,8001,8080,8081,81,888
wccp2_service dynamic 70
wccp2_service_info 70 protocol=tcp flags=dst_ip_hash,dst_port_hash priority=240 ports=16869,443
на
wccp2_service dynamic 80
wccp2_service_info 80 protocol=tcp flags=dst_ip_hash,dst_port_hash priority=240 ports=80,8001,8080,8081,81,888
wccp2_service dynamic 70
wccp2_service_info 70 protocol=tcp flags=dst_ip_hash,dst_port_hash priority=240 ports=16869,443
Может он тупо не понимает dynamic с № меньше 50 и весть трафик гонит на 0й?
-
snik_1900 (27 июня 2017 - 09:50) писал:
SQUID в прозрачном режиме. Соответсвенно у клиентов сертификат не устанавливается. А сам по себе он существует.
Сертификат у клиента должен стоять в любом случае, иначе он будет показывать ошибку, потому что не доверенное.
Да и флаг переполнения ему в регистр. Если ssl_bump сработает то клиент ничего не получит вообще, а если нет то запрос дальше полетит без изменений.
Что тебе тцпдам показывает?
Ну например вот такое:
#tcpdump -i igb1
16:52:31.245083 IP AAA.BBB.CCC.DDD.4299 > 13.32.121.171.https: Flags , seq 2500963771, win 65535, options [mss 1440,nop,nop,sackOK], length 0
или такое:
#tcpdump -i igb1
16:52:31.262933 IP AAA.BBB.CCC.DDD.58348 > ec2-52-58-31-242.eu-central-1.compute.amazonaws.com.https: Flags , seq 1501894797, win 14600, options [mss 1440,sackOK,TS val 252272 ecr 0,nop,wscale 6], length 0
Как я понимаю до сервака пакеты добегают.
А правило ipfw:
00050 fwd 192.168.2.230,3129 tcp from not me to any dst-port 443 via igb1 in
перегоняет с 443 порта на 3129.
Или я чего-то недопонимаю?
-
Свежих мыслей нет? Или SQUID редкий зверь?
-
Опубликовано · Изменено пользователем snik_1900 · Жалоба на ответ
Кстати, в конфиге я не вижу ssl_bump terminate ssl_sites
А зачем? Есть: ssl_bump bump ssl_sites и ssl_bump peek step1.
-
snik_1900 (Сегодня, 09:50) писал:
В логах ничего не отображается.
Так надо tcpdump смотреть.
Если погасить squid, то https-ные сайты начинают открываться не блокируясь. Значит на сервак запросы попадают.
snik_1900 (Сегодня, 09:50) писал:
SQUID в прозрачном режиме. Соответсвенно у клиентов сертификат не устанавливается. А сам по себе он существует.
Какая-то загадочная фраза.
Насколько я знаю, через сквид пропускать https-трафик только с помощью MITM, установив у клиентов свой корневой CA и подписывая им поддельные сертификаты.
Может конечно путаю с чем-то, но каким образом сквид извлечет из зашифрованного трафика строку запроса?
Как я понимаю он смотрит в step1 SslBump1 имя домена.
-
Запросы на сквид вообще не приходят?
В логах ничего не отображается.
Или приходят, но не обслуживаются?Скорее всего.
Коневой самоподписанный CA установлен у клиентов?SQUID в прозрачном режиме. Соответсвенно у клиентов сертификат не устанавливается. А сам по себе он существует.
А вообще я бы советовал не тратить время, через сквид блокировку сайтов не обеспечить.Уже 2 года работает. Проблема только с https. Раньше не обращали внимания, а после проблем с DNSами озадачились.
-
Доброго дня.
Может кто сталкивался с такой проблемой. Уже месяц бьюсь, решить не могу.
Есть cisco по wccp2 подключенная к серверу на FreeBSD 11.0 с установленным SQUID.
SQUID выполняет фильтрацию.
CISCO в соответствии с Access-list заворачивает по IP нужные сайты на squid
По HTTP фильтрует отлично. А по HTTPS не работает. Не пропускает все пришедшие запросы от cisco на HTTPS.
#squid -v
Squid Cache: Version 3.5.23
Service Name: squid
configure options: '--with-default-user=squid' '--bindir=/usr/local/sbin' '--sbindir=/usr/local/sbin' '--datadir=/usr/local/etc/squid' '--libexecdir=/usr/local/libexec/squid' '--localstatedir=/var' '--sysconfdir=/usr/local/etc/squid' '--with-logdir=/var/log/squid' '--with-pidfile=/var/run/squid/squid.pid' '--with-swapdir=/var/squid/cache' '--without-gnutls' '--enable-auth' '--enable-zph-qos' '--enable-build-info' '--enable-loadable-modules' '--enable-removal-policies=lru heap' '--disable-epoll' '--disable-linux-netfilter' '--disable-linux-tproxy' '--disable-translation' '--disable-arch-native' '--enable-eui' '--enable-cache-digests' '--enable-delay-pools' '--disable-ecap' '--disable-esi' '--enable-follow-x-forwarded-for' '--enable-htcp' '--enable-icap-client' '--enable-icmp' '--enable-ident-lookups' '--enable-ipv6' '--enable-kqueue' '--with-large-files' '--enable-http-violations' '--without-nettle' '--enable-snmp' '--enable-ssl' '--with-openssl=/usr/local' 'LIBOPENSSL_CFLAGS=-I/usr/local/include' 'LIBOPENSSL_LIBS=-lcrypto -lssl' '--enable-ssl-crtd' '--disable-stacktraces' '--enable-forw-via-db' '--enable-wccp' '--enable-wccpv2' '--without-heimdal-krb5' '--without-mit-krb5' '--without-gss' '--disable-ipf-transparent' '--enable-ipfw-transparent' '--disable-pf-transparent' '--without-nat-devpf' '--enable-auth-basic=DB SMB_LM MSNT-multi-domain NCSA PAM POP3 RADIUS fake getpwnam NIS' '--enable-auth-digest=file' '--enable-external-acl-helpers=file_userip time_quota unix_group' '--enable-auth-negotiate=none' '--enable-auth-ntlm=fake smb_lm' '--enable-storeio=aufs diskd rock ufs' '--enable-disk-io=DiskThreads DiskDaemon AIO Blocking IpcIo Mmapped' '--enable-log-daemon-helpers=file' '--enable-url-rewrite-helpers=fake' '--enable-storeid-rewrite-helpers=file' '--prefix=/usr/local' '--mandir=/usr/local/man' '--disable-silent-rules' '--infodir=/usr/local/info/' '--build=amd64-portbld-freebsd11.0' 'build_alias=amd64-portbld-freebsd11.0' 'CC=cc' 'CFLAGS=-O2 -pipe -fstack-protector -fno-strict-aliasing' 'LDFLAGS= -pthread -Wl,-rpath,/usr/local/lib -fstack-protector' 'LIBS=' 'CPPFLAGS=' 'CXX=c++' 'CXXFLAGS=-O2 -pipe -fstack-protector -fno-strict-aliasing -Wno-unknown-warning-option -Wno-undefined-bool-conversion -Wno-tautological-undefined-compare -Wno-dynamic-class-memaccess' 'CPP=cpp' --enable-ltdl-convenience
squid.conf:
cache_effective_user squid
cache_effective_group squid
visible_hostname reestr-proxy
#debug_options ALL,5
http_port 192.168.2.230:9090
http_port 192.168.2.230:3128 intercept
https_port 192.168.2.230:3129 intercept ssl-bump generate-host-certificates=on dynamic_cert_mem_cache_size=4MB cert=/usr/local/etc/squid/squ
always_direct allow all
acl ssl_sites ssl::server_name "/var/db/zapret-info/denied_https.conf"
acl step1 at_step SslBump1
acl step2 at_step SslBump2
ssl_bump peek step1
ssl_bump bump ssl_sites
ssl_bump splice all
sslproxy_cert_error allow all
sslproxy_options NO_SSLv2,NO_SSLv3,SINGLE_ECDH_USE
sslproxy_options ALL
sslproxy_cipher ALL
sslcrtd_program /usr/local/libexec/squid/ssl_crtd -s /var/squid/ssl_db -M 4MB
icp_port 0
#hierarchy_stoplist cgi-bin ?
acl QUERY urlpath_regex cgi-bin \?
cache deny QUERY
acl apache rep_header Server ^Apache
cache_mem 1 MB
cache_swap_low 90
cache_swap_high 95
maximum_object_size 1 KB
maximum_object_size_in_memory 50 KB
cache_replacement_policy heap LFUDA
cache_dir ufs /var/squid/cache 1 1 1 no-store
logfile_rotate 7
dns_nameservers 8.8.8.8
hosts_file /etc/hosts
refresh_pattern ^ftp: 1440 20% 10080
refresh_pattern ^gopher: 1440 0% 1440
refresh_pattern . 0 20% 4320
quick_abort_min 0 KB
quick_abort_max 0 KB
half_closed_clients off
acl purge method PURGE
acl CONNECT method CONNECT
acl SSL_ports port 16869
acl SSL_ports port 443
acl Safe_ports port 80
acl Safe_ports port 8001
acl Safe_ports port 81
acl Safe_ports port 888
http_access allow manager localhost
http_access deny manager
http_access allow purge localhost
http_access deny purge
http_access deny !Safe_ports
http_access deny CONNECT !SSL_ports
http_access allow localhost
http_access allow all
http_reply_access allow all
icp_access allow all
cache_mgr root@server.ru
memory_pools off
log_icp_queries off
cachemgr_passwd q1w2e3r4 all
client_db off
buffered_logs on
wccp2_router 192.168.2.229
wccp2_rebuild_wait on
wccp2_forwarding_method 2
wccp2_return_method 2
wccp2_assignment_method 1
wccp2_service dynamic 0
wccp2_service_info 0 protocol=tcp flags=dst_ip_hash,dst_port_hash priority=240 ports=80,8001,8080,8081,81,888
wccp2_service dynamic 70
wccp2_service_info 70 protocol=tcp flags=dst_ip_hash,dst_port_hash priority=240 ports=16869,443
wccp2_address 192.168.2.230
redirect_program /usr/local/etc/squid/redirector.pl
url_rewrite_children 60 startup=10 idle=1 concurrency=0
/var/log/squid/cache.log:
2017/06/22 23:54:18 kid1| Current Directory is /var/squid
2017/06/22 23:54:18 kid1| Starting Squid Cache version 3.5.23 for amd64-portbld-freebsd11.0...
2017/06/22 23:54:18 kid1| Service Name: squid
2017/06/22 23:54:18 kid1| Process ID 16050
2017/06/22 23:54:18 kid1| Process Roles: worker
2017/06/22 23:54:18 kid1| With 1171206 file descriptors available
2017/06/22 23:54:18 kid1| Initializing IP Cache...
2017/06/22 23:54:18 kid1| DNS Socket created at [::], FD 6
2017/06/22 23:54:18 kid1| DNS Socket created at 0.0.0.0, FD 7
2017/06/22 23:54:18 kid1| Adding nameserver 8.8.8.8 from squid.conf
2017/06/22 23:54:18 kid1| helperOpenServers: Starting 5/32 'ssl_crtd' processes
2017/06/22 23:54:18 kid1| helperOpenServers: Starting 10/60 'redirector.pl' processes
2017/06/22 23:54:18 kid1| Logfile: opening log daemon:/var/log/squid/access.log
2017/06/22 23:54:18 kid1| Logfile Daemon: opening log /var/log/squid/access.log
2017/06/22 23:54:18 kid1| Unlinkd pipe opened on FD 45
2017/06/22 23:54:18 kid1| Local cache digest enabled; rebuild/rewrite every 3600/3600 sec
2017/06/22 23:54:18 kid1| Store logging disabled
2017/06/22 23:54:18 kid1| Swap maxSize 1024 + 1024 KB, estimated 157 objects
2017/06/22 23:54:18 kid1| Target number of buckets: 7
2017/06/22 23:54:18 kid1| Using 8192 Store buckets
2017/06/22 23:54:18 kid1| Max Mem size: 1024 KB
2017/06/22 23:54:18 kid1| Max Swap size: 1024 KB
2017/06/22 23:54:18 kid1| Rebuilding storage in /var/squid/cache (clean log)
2017/06/22 23:54:18 kid1| Using Least Load store dir selection
2017/06/22 23:54:18 kid1| Current Directory is /var/squid
2017/06/22 23:54:18 kid1| Finished loading MIME types and icons.
2017/06/22 23:54:18 kid1| Accepting WCCPv2 messages on port 2048, FD 48.
2017/06/22 23:54:18 kid1| Initialising all WCCPv2 lists
2017/06/22 23:54:18 kid1| HTCP Disabled.
2017/06/22 23:54:18 kid1| Pinger socket opened on FD 53
2017/06/22 23:54:18 kid1| Squid plugin modules loaded: 0
2017/06/22 23:54:18 kid1| Adaptation support is off.
2017/06/22 23:54:18 kid1| Accepting HTTP Socket connections at local=192.168.2.230:9090 remote=[::] FD 49 flags=9
2017/06/22 23:54:18 kid1| Accepting NAT intercepted HTTP Socket connections at local=192.168.2.230:3128 remote=[::] FD 50 flags=41
2017/06/22 23:54:18 kid1| Accepting NAT intercepted SSL bumped HTTPS Socket connections at local=192.168.2.230:3129 remote=[::] FD 51 flags=
2017/06/22 23:54:18 kid1| Done reading /var/squid/cache swaplog (0 entries)
2017/06/22 23:54:18 kid1| Store rebuilding is 0.00% complete
2017/06/22 23:54:18 kid1| Finished rebuilding storage from disk.
2017/06/22 23:54:18 kid1| 0 Entries scanned
2017/06/22 23:54:18 kid1| 0 Invalid entries.
2017/06/22 23:54:18 kid1| 0 With invalid flags.
2017/06/22 23:54:18 kid1| 0 Objects loaded.
2017/06/22 23:54:18 kid1| 0 Objects expired.
2017/06/22 23:54:18 kid1| 0 Objects cancelled.
2017/06/22 23:54:18 kid1| 0 Duplicate URLs purged.
2017/06/22 23:54:18 kid1| 0 Swapfile clashes avoided.
2017/06/22 23:54:18 kid1| Took 0.03 seconds ( 0.00 objects/sec).
2017/06/22 23:54:18 kid1| Beginning Validation Procedure
2017/06/22 23:54:18 kid1| Completed Validation Procedure
2017/06/22 23:54:18 kid1| Validated 0 Entries
2017/06/22 23:54:18 kid1| store_swap_size = 0.00 KB
2017/06/22 23:54:18| pinger: Initialising ICMP pinger ...
2017/06/22 23:54:18| pinger: ICMP socket opened.
2017/06/22 23:54:18| pinger: ICMPv6 socket opened
2017/06/22 23:54:19 kid1| storeLateRelease: released 0 objects
При этом /var/squid/ssl_db:
index.txt 0
size 1
каталог certs - пустой
#sockstat -4 | grep 3129
squid squid 16050 51 tcp4 192.168.2.230:3129 *:*
#sockstat -4 | grep 3128
squid squid 16050 50 tcp4 192.168.2.230:3128 *:*
# ipfw list
00050 fwd 192.168.2.230,3128 tcp from not me to any dst-port 80 via igb1 in
00050 fwd 192.168.2.230,3128 tcp from not me to any dst-port 8001 via igb1 in
00050 fwd 192.168.2.230,3128 tcp from not me to any dst-port 8080 via igb1 in
00050 fwd 192.168.2.230,3128 tcp from not me to any dst-port 8081 via igb1 in
00050 fwd 192.168.2.230,3128 tcp from not me to any dst-port 81 via igb1 in
00050 fwd 192.168.2.230,3128 tcp from not me to any dst-port 888 via igb1 in
00050 fwd 192.168.2.230,3129 tcp from not me to any dst-port 16869 via igb1 in
00050 fwd 192.168.2.230,3129 tcp from not me to any dst-port 443 via igb1 in
Технические аспекты реализации "закона Яровой"
в У нага
Опубликовано · Изменено пользователем snik_1900 · Жалоба на ответ
"Страусиная технология" до добра не доведет. Проблемы надо решать, а не складировать.
У HP есть вполне "нормальные" (в этом мире всё относительно) сетевые хранилища с оптическим линком 10Гб с ценой не от новой "летающей тарелки".