Received: from malur.postgresql.org ([217.196.149.56]) by arkaria.postgresql.org with esmtps (TLS1.3:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.92) (envelope-from ) id 1mAuBY-0004HE-6W for pgsql-hackers@arkaria.postgresql.org; Tue, 03 Aug 2021 13:10:20 +0000 Received: from localhost ([127.0.0.1] helo=malur.postgresql.org) by malur.postgresql.org with esmtp (Exim 4.92) (envelope-from ) id 1mAuBW-0002Ek-5S for pgsql-hackers@arkaria.postgresql.org; Tue, 03 Aug 2021 13:10:18 +0000 Received: from magus.postgresql.org ([2a02:c0:301:0:ffff::29]) by malur.postgresql.org with esmtps (TLS1.3:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.92) (envelope-from ) id 1mAuBV-0002Ec-QR for pgsql-hackers@lists.postgresql.org; Tue, 03 Aug 2021 13:10:17 +0000 Received: from mail-io1-xd31.google.com ([2607:f8b0:4864:20::d31]) by magus.postgresql.org with esmtps (TLS1.3:ECDHE_RSA_AES_128_GCM_SHA256:128) (Exim 4.92) (envelope-from ) id 1mAuBQ-00035l-Gq for pgsql-hackers@postgresql.org; Tue, 03 Aug 2021 13:10:17 +0000 Received: by mail-io1-xd31.google.com with SMTP id h1so24180358iol.9 for ; Tue, 03 Aug 2021 06:10:12 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=telsasoft-com.20150623.gappssmtp.com; s=20150623; h=date:from:to:cc:subject:message-id:references:mime-version :content-disposition:in-reply-to:user-agent; bh=XNgB4NuTrmrLgJIMQgfl1YKoeAtm5gTokxNWiiVv39s=; b=xQ26wn+XITINaRop9OzIeJhEao8YT4qckAzDRvxNJz0GKcysMekn1/CP3osG2ri6TF o0JKNhXopNXpniFtDXHR2AP2FlS19yPBWgoWDSL/tiGnvXL46tiiahL+etDgqu87Eqsy RlSToX2eh3VJYnmmQruQkdxDYvwERUZduQfjdv9XHrsFNcKj1N4WZDp7Pch8jbr215e4 RMFMj4wCW2UldE7zqbhM0lN72mYu+gP+9xAqOgjsffMwv+azTuTktdPy+Dlz7sjr63OI ELwmIec0cs1V/t+RcRscZF4tTUCJPdI3Oa+xc7824ByR4AnuWqlC5mT9L9Z6Z9mc9/r0 nbEA== X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20161025; h=x-gm-message-state:date:from:to:cc:subject:message-id:references :mime-version:content-disposition:in-reply-to:user-agent; bh=XNgB4NuTrmrLgJIMQgfl1YKoeAtm5gTokxNWiiVv39s=; b=BqLrG2yllyHkqkVuWhN3ues8uJYLlTFnY4VpESABTvk4NkTgqVSra4WBVtGxS1FT5D NHnsYHRo1TvnHXSiwb3hTBQIO2C5ACqyMoWp/VUFfnlxqIvnRl4e4xNvyFtwpEcin/cH D5oVj2wJukGdCit5HFOJbn2LqlHAr4jsqSxmMEwvcnnWamHkfjHvwPd5GffifhoG2vhe 0zy8rPLSI54KtUA2Rs2U0bDtCdPvol9ugkH9r5g7Ca945LraNTXsJ0ZzA71OMCIpkX1Y qUC7+JDQNBn7S9oQpK+LSQ0dGKAHFQSAa+ESCZK1ErCkX4frhD5k4mHZTTwYcNj9/Uau 0mxQ== X-Gm-Message-State: AOAM531xi0iC/TXKhmVFpiTCjVLIEsh9zULzCHsOzoWmU8cTL2BLd8Pv HIlq0pKZu+b9nzo2pBlLXsPvrA== X-Google-Smtp-Source: ABdhPJwP6VHuatiX/rNi1OTbPcIGx5zPnjX5uCZQBr+53jtsjSymTBIwBvMchrvlnuULMNq7CVcVTw== X-Received: by 2002:a02:7f12:: with SMTP id r18mr19189274jac.50.1627996210561; Tue, 03 Aug 2021 06:10:10 -0700 (PDT) Received: from pryzbyj.telsasoft (charmander.telsasoft.com. [50.244.222.1]) by smtp.gmail.com with ESMTPSA id c1sm7500795ili.35.2021.08.03.06.10.09 (version=TLS1_2 cipher=ECDHE-ECDSA-AES128-GCM-SHA256 bits=128/128); Tue, 03 Aug 2021 06:10:10 -0700 (PDT) Received: by pryzbyj.telsasoft (Postfix, from userid 1000) id 36CED800C45; Tue, 3 Aug 2021 08:10:09 -0500 (CDT) Date: Tue, 3 Aug 2021 08:10:09 -0500 From: Justin Pryzby To: Nitin Jadhav Cc: Robert Haas , Bharath Rupireddy , Tom Lane , Andres Freund , Stephen Frost , Magnus Hagander , Thomas Munro , pgsql-hackers@postgresql.org Subject: Re: when the startup process doesn't (logging startup delays) Message-ID: <20210803131008.GU12533@telsasoft.com> References: <20210726153023.GC23997@telsasoft.com> MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline In-Reply-To: User-Agent: Mutt/1.9.4 (2018-02-28) List-Id: List-Help: List-Subscribe: List-Post: List-Owner: List-Archive: Archived-At: Precedence: bulk Two issues that I saw: The first syncfs message is not output, because it's before InitStartupProgress() is called: 2021-08-03 07:53:02.176 CDT startup[9717] LOG: database system was interrupted; last known up at 2021-08-03 07:52:15 CDT 2021-08-03 07:53:02.733 CDT startup[9717] LOG: data directory sync (syncfs) complete after 0.55 s 2021-08-03 07:53:02.734 CDT startup[9717] LOG: database system was not properly shut down; automatic recovery in progress FP exception when the GUC is set to 0: 2021-08-03 07:53:02.877 CDT postmaster[9715] LOG: startup process (PID 9717) was terminated by signal 8: Floating point exception Probably due to mod zero operation. This prevents the process from starting. + enable_timeout_after(LOG_STARTUP_PROGRESS_TIMEOUT, + (interval_in_ms - (elapsed_ms % interval_in_ms))); -- Justin