Return-Path: X-Original-To: apmail-httpd-users-archive@www.apache.org Delivered-To: apmail-httpd-users-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id AC86818C44 for ; Fri, 18 Dec 2015 17:30:07 +0000 (UTC) Received: (qmail 42416 invoked by uid 500); 18 Dec 2015 17:30:04 -0000 Delivered-To: apmail-httpd-users-archive@httpd.apache.org Received: (qmail 42377 invoked by uid 500); 18 Dec 2015 17:30:04 -0000 Mailing-List: contact users-help@httpd.apache.org; run by ezmlm Precedence: bulk Reply-To: users@httpd.apache.org list-help: list-unsubscribe: List-Post: List-Id: Delivered-To: mailing list users@httpd.apache.org Received: (qmail 42367 invoked by uid 99); 18 Dec 2015 17:30:04 -0000 Received: from Unknown (HELO spamd2-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 18 Dec 2015 17:30:04 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd2-us-west.apache.org (ASF Mail Server at spamd2-us-west.apache.org) with ESMTP id EFBB11A1389 for ; Fri, 18 Dec 2015 17:30:03 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd2-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -0.102 X-Spam-Level: X-Spam-Status: No, score=-0.102 tagged_above=-999 required=6.31 tests=[DKIM_SIGNED=0.1, DKIM_VALID=-0.1, DKIM_VALID_AU=-0.1, RCVD_IN_MSPIKE_H2=-0.001, SPF_PASS=-0.001] autolearn=disabled Authentication-Results: spamd2-us-west.apache.org (amavisd-new); dkim=pass (1024-bit key) header.d=sevenval.com Received: from mx1-us-east.apache.org ([10.40.0.8]) by localhost (spamd2-us-west.apache.org [10.40.0.9]) (amavisd-new, port 10024) with ESMTP id 1DRvKoQBkEC2 for ; Fri, 18 Dec 2015 17:30:02 +0000 (UTC) Received: from mail-io0-f173.google.com (mail-io0-f173.google.com [209.85.223.173]) by mx1-us-east.apache.org (ASF Mail Server at mx1-us-east.apache.org) with ESMTPS id E2A88439FB for ; Fri, 18 Dec 2015 17:30:01 +0000 (UTC) Received: by mail-io0-f173.google.com with SMTP id e126so96719149ioa.1 for ; Fri, 18 Dec 2015 09:30:01 -0800 (PST) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=sevenval.com; s=google; h=mime-version:date:message-id:subject:from:to:content-type; bh=FyO/PbBi7YwOojYh+FeUHr38JDZ4z2dEGUCrihyTWRk=; b=cjpAVgUXSKIn0xQB5WfGv6/eja4pZ1PoMr8SobZI6flJryvlNwks3hEK4KQgo1idmP eogcbjFjTfAlFWLlpK9dP6vSI6NeJ4NnQcTBTqz6dbsQxeZSl6aOcRd5DeMzTiVKawa1 G9jUbv2H9X77OuePyG24C6lyb3kVNYNgapONw= X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20130820; h=x-gm-message-state:mime-version:date:message-id:subject:from:to :content-type; bh=FyO/PbBi7YwOojYh+FeUHr38JDZ4z2dEGUCrihyTWRk=; b=Ab48mko1LxFk2cTMu+WxVgCCxA/Lnz5ehUOvOBiOKPO+BaqgwwzQ0Gn9FPgA3CD5CY To1ujeUCL9Uzs/l1pKK6CCJ40fNlWexAXZhyB2bQkBfJAQlidUo0I0kJo+f3kvEj80QK 9wyKJtCNMOrkN6ZGye/lyNCOI6253RcrPEBUa9jDivkyZPMWrsaYAOux3Cy7KzMl4GbD xBTKwnogfgq96DFNxJhYtxkew+Z/fvZz9cionJ99oW+saAoLZgo3vZwGzll1Xv/Lkdz2 linepbIT0CrvEsh7HcLFK/QYk0W8rjB1IRlgPF2ElkqbYPrJSsgX5Pf3oemb1aOQ+84R +/NA== X-Gm-Message-State: ALoCoQkWgaZmxhi9Ha9y5JmzlvAdMeZVPyKEYAn1F74ehjgBxIguKqxWXaQOhG3t5xHPEGGGPSPkv1JCPU8K0Y12O5eUKM1VWWmhsmtPzVtMcLD+3kQWtcw= MIME-Version: 1.0 X-Received: by 10.107.8.20 with SMTP id 20mr6772825ioi.34.1450459801324; Fri, 18 Dec 2015 09:30:01 -0800 (PST) Received: by 10.50.220.194 with HTTP; Fri, 18 Dec 2015 09:30:01 -0800 (PST) Date: Fri, 18 Dec 2015 18:30:01 +0100 Message-ID: From: Rainer Canavan To: users@httpd.apache.org Content-Type: text/plain; charset=UTF-8 Subject: [users@httpd] AH00273: apr_proc_mutex_lock failed, possibly caused by cron, systemd or su Hi, we've got an obscure problem with the apache httpd that was shipped with CentOS 7.2. We perform automatic builds and updates via cron, and, since the update to CentOS 7.2. The update script is triggered by cron and stops, yum updates and starts the httpd. When the next cron job that is run as the same user as the httpd (not the update job) terminates, the httpd frequently fails, starting with a AH00273: apr_proc_mutex_lock failed message, and then a never ending loop of AH00272 messages, one from each httpd process that is forked, until the listener process is stopped. [Thu Dec 17 08:30:04.895455 2015] [mpm_worker:notice] [pid 12021:tid 140011320178752] - AH00295: caught SIGTERM, shutting down [Thu Dec 17 08:30:52.783949 2015] [mpm_worker:notice] [pid 23947:tid 140703866935360] - AH00292: Apache/2.4.6 (CentOS) OpenSSL/1.0.1e-fips configured -- resuming normal operations [Thu Dec 17 08:30:52.784091 2015] [core:notice] [pid 23947:tid 140703866935360] - AH00094: Command line: '/usr/sbin/httpd -d /opt/sevenval/fit14/ -f /opt/sevenval/fit14/conf/httpd.conf' [Thu Dec 17 08:40:54.959482 2015] [mpm_worker:emerg] [pid 23949:tid 140703388559104] (43)Identifier removed: - AH00273: apr_proc_mutex_lock failed. Attempting to shutdown process gracefully. [Thu Dec 17 08:40:55.669532 2015] [mpm_worker:emerg] [pid 25505:tid 140703388559104] (22)Invalid argument: - AH00272: apr_proc_mutex_lock failed before this child process served any requests. [Thu Dec 17 08:40:55.991490 2015] [mpm_worker:emerg] [pid 23950:tid 140703388559104] (22)Invalid argument: - AH00273: apr_proc_mutex_unlock failed. Attempting to shutdown process gracefully. [Thu Dec 17 08:40:57.672519 2015] [mpm_worker:emerg] [pid 25540:tid 140703388559104] (22)Invalid argument: - AH00272: apr_proc_mutex_lock failed before this child process served any requests. [Thu Dec 17 08:40:59.676573 2015] [mpm_worker:emerg] [pid 25575:tid 140703388559104] (22)Invalid argument: - AH00272: apr_proc_mutex_lock failed before this child process served any requests. The httpd version is the old version from RHEL (httpd-2.4.6-40.el7). The backtrace is rather uninteresting: (gdb) bt full #0 accept_mutex_error (func=0x7f3fcac5d299 "unlock", rv=22, process_slot=0) at worker.c:659 level = 0 #1 0x00007f3fcac5b46a in listener_thread (thd=0x7f3fcdc405c8, dummy=) at worker.c:849 ti = process_slot = 0 tpool = csd = 0x7f3fc00444c0 ptrans = 0x7f3fc0044438 pollset = 0x7f3fc003fa30 rv = lr = 0x7f3fcdbd5a98 have_idle_worker = 1 last_poll_idx = 1 #2 0x00007f3fcc26cdc5 in start_thread (arg=0x7f3fb0fe1700) at pthread_create.c:308 __res = pd = 0x7f3fb0fe1700 now = unwind_buf = {cancel_jmp_buf = {{jmp_buf = {139911529109248, 4832434510689815290, 0, 139911529109952, 139911529109248, 0, -4796579440261772550, -4796375556103124230}, mask_was_saved = 0}}, priv = {pad = {0x0, 0x0, 0x0, 0x0}, data = {prev = 0x0, cleanup = 0x0, canceltype = 0}}} not_first_call = pagesize_m1 = sp = freesize = #3 0x00007f3fcbd9621d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:113 No locals. Does anyone habe any ideas what may cause these mutex errors? thanks, rainer --------------------------------------------------------------------- To unsubscribe, e-mail: users-unsubscribe@httpd.apache.org For additional commands, e-mail: users-help@httpd.apache.org