Return-Path: X-Original-To: apmail-zookeeper-user-archive@www.apache.org Delivered-To: apmail-zookeeper-user-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id B3133107E1 for ; Fri, 19 Jul 2013 23:50:53 +0000 (UTC) Received: (qmail 59422 invoked by uid 500); 19 Jul 2013 23:50:52 -0000 Delivered-To: apmail-zookeeper-user-archive@zookeeper.apache.org Received: (qmail 59395 invoked by uid 500); 19 Jul 2013 23:50:52 -0000 Mailing-List: contact user-help@zookeeper.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: user@zookeeper.apache.org Delivered-To: mailing list user@zookeeper.apache.org Received: (qmail 59387 invoked by uid 99); 19 Jul 2013 23:50:52 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 19 Jul 2013 23:50:52 +0000 X-ASF-Spam-Status: No, hits=1.5 required=5.0 tests=HTML_MESSAGE,RCVD_IN_DNSWL_LOW,SPF_PASS X-Spam-Check-By: apache.org Received-SPF: pass (nike.apache.org: domain of bitanarch@gmail.com designates 209.85.223.169 as permitted sender) Received: from [209.85.223.169] (HELO mail-ie0-f169.google.com) (209.85.223.169) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 19 Jul 2013 23:50:45 +0000 Received: by mail-ie0-f169.google.com with SMTP id 10so10953841ied.28 for ; Fri, 19 Jul 2013 16:50:25 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20120113; h=mime-version:in-reply-to:references:date:message-id:subject:from:to :content-type; bh=W8+H9PRUspi1PqhkT5l2XBlAlVfMNrdfCt4KikKEeiM=; b=HUI7vS7+4C170aKk5zbiMcV/ZHzIZrq0HhUmaiEqEo72YLShOOdX9JPPYTPoQGSUat REM6/kie1MuIEnpllaQ7u0IkMAvyUpbg0W0orj2mvSkj65LZhKIUbOKScTYG80dN+dLR usX21ZxWJBNqsBroSXQ91lyDVkLemkSA9iIftPhZLUYyqWSzZcGEG/FsHQu9txpIpBPJ hPnOSq/oGwVoglNJp6w5vXxAO5I+/gRh9cy9Wo93WcpQPHKas7ellKAn8ZkBWQchnu6v urzN9DnawMq4mjIdGECOQDy9BRLULhMNxL+mdLVOgxJ9lisgzdAwhxIf5105eYLVY6x+ uh/A== MIME-Version: 1.0 X-Received: by 10.50.28.111 with SMTP id a15mr13594839igh.43.1374277824983; Fri, 19 Jul 2013 16:50:24 -0700 (PDT) Received: by 10.50.16.162 with HTTP; Fri, 19 Jul 2013 16:50:24 -0700 (PDT) In-Reply-To: References: Date: Fri, 19 Jul 2013 16:50:24 -0700 Message-ID: Subject: Re: "Client session timed out, have not heard from server in 143198ms", etc. From: Martin Kou To: user@zookeeper.apache.org Content-Type: multipart/alternative; boundary=089e0158b2c494b9b404e1e5fd7a X-Virus-Checked: Checked by ClamAV on apache.org --089e0158b2c494b9b404e1e5fd7a Content-Type: text/plain; charset=ISO-8859-1 Anyone? Best Regards, Martin Kou On Mon, Jun 17, 2013 at 12:23 AM, Martin Kou wrote: > Hi folks, > > I've been seeing a lot of client session timed out messages from my server > logs recently - normally those wouldn't concern me too much since there're > occasion network outages. However, some of the session time out values are > abnormally large. > > I've been using 40s as the session timeout value for my ZooKeeper > sessions, and those are confirmed as the negotiated timeouts in the client > establishment logs as well. However, I'd sometimes see timeout logs stating > times far longer than 40s - e.g. the one in the title. > > Reading from ZooKeeper's source code (I'm using v3.4.4) - it seems there's > no way the clientCnxnSocket.getIdleRecv() call would cause session time out > delays of more than 2/3 * sessionTimeout (which is 26.66s in my case). The > theory I have is.. let's say the ZooKeeper client receives a ping from > server at time t, and the ClientCnxn.SendThread schedules the next > doTransport() at t + 26.66s - then the worst thing that could happen is > there's nothing from the server for 26.66s and so I'd get a session timed > out mesage with sth like 26667ms - which is quite common during network > outage. However, sometimes I'm getting these >40s and even >100s time outs > - and I just can't understand them. > > Any clues on how these can happen? > > Best Regards, > Martin Kou > --089e0158b2c494b9b404e1e5fd7a--