From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (qmail 60013 invoked by alias); 14 Aug 2015 13:49:54 -0000 Mailing-List: contact cygwin-help@cygwin.com; run by ezmlm Precedence: bulk List-Id: List-Subscribe: List-Archive: List-Post: List-Help: , Sender: cygwin-owner@cygwin.com Mail-Followup-To: cygwin@cygwin.com Received: (qmail 60002 invoked by uid 89); 14 Aug 2015 13:49:53 -0000 Authentication-Results: sourceware.org; auth=none X-Virus-Found: No X-Spam-SWARE-Status: No, score=-4.1 required=5.0 tests=AWL,BAYES_50,KAM_LAZY_DOMAIN_SECURITY autolearn=no version=3.3.2 X-HELO: calimero.vinschen.de Received: from aquarius.hirmke.de (HELO calimero.vinschen.de) (217.91.18.234) by sourceware.org (qpsmtpd/0.93/v0.84-503-g423c35a) with ESMTP; Fri, 14 Aug 2015 13:49:53 +0000 Received: by calimero.vinschen.de (Postfix, from userid 500) id 969A2A808BB; Fri, 14 Aug 2015 15:49:50 +0200 (CEST) Date: Fri, 14 Aug 2015 13:49:00 -0000 From: Corinna Vinschen To: cygwin@cygwin.com Subject: Re: commands spends time in cygheap_user Message-ID: <20150814134950.GH28349@calimero.vinschen.de> Reply-To: cygwin@cygwin.com Mail-Followup-To: cygwin@cygwin.com References: <1437114390582-119766.post@n5.nabble.com> <1438937297149-120365.post@n5.nabble.com> <20150807090646.GB12475@calimero.vinschen.de> <1438977081850-120377.post@n5.nabble.com> <20150810084019.GB1559@calimero.vinschen.de> <1439208020687-120403.post@n5.nabble.com> <20150810160150.GD13029@calimero.vinschen.de> MIME-Version: 1.0 Content-Type: multipart/signed; micalg=pgp-sha256; protocol="application/pgp-signature"; boundary="2NLGdgz3UMHa/lqP" Content-Disposition: inline In-Reply-To: <20150810160150.GD13029@calimero.vinschen.de> User-Agent: Mutt/1.5.23 (2014-03-12) X-SW-Source: 2015-08/txt/msg00211.txt.bz2 --2NLGdgz3UMHa/lqP Content-Type: text/plain; charset=utf-8 Content-Disposition: inline Content-Transfer-Encoding: quoted-printable Content-length: 3309 On Aug 10 18:01, Corinna Vinschen wrote: > On Aug 10 05:00, mku wrote: > > Oh, sorry. I did a fresh 2.2 install to avoid side effects from my prev= ious > > updated one and forgot to modify the nsswitch.conf. After correcting it= , the > > result now is the same from the time aspect but loading of the net dlls= have > > now disappeared as expected. > > +++ 8< ----- > > 280 44318 [main] ls 181944 client_request::make_request: cygserver > > un-availa > > --- Process 181944 loaded C:\Windows\SysWOW64\advapi32.dll at 75400000 > > --- Process 181944 loaded C:\Windows\SysWOW64\msvcrt.dll at 76CB0000 > > --- Process 181944 loaded C:\Windows\SysWOW64\sechost.dll at 76A00000 > > --- Process 181944 loaded C:\Windows\SysWOW64\rpcrt4.dll at 75300000 > > --- Process 181944 loaded C:\Windows\SysWOW64\sspicli.dll at 750C0000 > > --- Process 181944 loaded C:\Windows\SysWOW64\cryptbase.dll at 750B0000 > > --- Process 181944 thread 186236 created > > 4525052 4569370 [main] ls 181944 cygheap_user::ontherange: what 2, pw > > 0x612FFCF0 > > 302 4569672 [main] ls 181944 cygheap_user::ontherange: HOME is alread= y in > > the > > --- 8< ----- > > I don't know how the delta time is calculated. As the time lag is always > > printed after loading the dlls, it may be that the log message is print= ed > > after the logged step has been completed. If that is true, the time lag= may > > occur while loading one of the dlls or creating the thread. > >=20 > > As I mentioned before the same command repeated one second again shows > > normal behaviour (0.406s). > > +++ 8< ----- > > 106 20307 [main] ls 201132 client_request::make_request: cygserver > > un-availa > > --- Process 201132 loaded C:\Windows\SysWOW64\advapi32.dll at 75400000 > > --- Process 201132 loaded C:\Windows\SysWOW64\msvcrt.dll at 76CB0000 > > --- Process 201132 loaded C:\Windows\SysWOW64\sechost.dll at 76A00000 > > --- Process 201132 loaded C:\Windows\SysWOW64\rpcrt4.dll at 75300000 > > --- Process 201132 loaded C:\Windows\SysWOW64\sspicli.dll at 750C0000 > > --- Process 201132 loaded C:\Windows\SysWOW64\cryptbase.dll at 750B0000 > > --- Process 201132 thread 201240 created > > 9195 29502 [main] ls 201132 cygheap_user::ontherange: what 2, pw > > 0x612FFCF0 > > 95 29597 [main] ls 201132 cygheap_user::ontherange: HOME is alread= y in > > the > > --- 8< ----- > > If you wait a longer period (e.g. 5 minutes), the first (longer) behavi= our > > is shown. > > As this time lag is not seen with Version 1.7.28 on the same machine, I > > assume that it has come with Version 2.2. >=20 > Well, it might have been introduced by *any* version later than 1.7.28. > It's very unlikley that this has been introduced by 2.2.0. From the > above output it's not clear where it hangs. I'll try to reproduce it > later this week. Except, I can't. Under the same circumstances, with nsswitch.conf passwd: files group: files and the network connection to my AD disabled, I don't encounter any noticable lag, not even after waiting a long time to get rid of potentially cached info. Any chance this behaviour is triggered by some virus scanner or something like that? Corinna --=20 Corinna Vinschen Please, send mails regarding Cygwin to Cygwin Maintainer cygwin AT cygwin DOT com Red Hat --2NLGdgz3UMHa/lqP Content-Type: application/pgp-signature Content-length: 819 -----BEGIN PGP SIGNATURE----- Version: GnuPG v2 iQIcBAEBCAAGBQJVzfH+AAoJEPU2Bp2uRE+gWAMQAKH/kSdonCOhvdGvYGQ9S1cA zfhTE/Jlq8/CKWk1H//HKl5Q1Y5D0lFZJdQVU82tbrXqBsCB5CcwJOqRD2taimfy 5QC0ww8CZUx4rZPmIT7O6P8Xf2lHBzBnW1Ct0GrafI0c9wZJ1B1/tzN4SmWm6fNZ q4SeF+hjtxjSvYtzq+qm/gBadEDKZuZmkEXHyr8gekS/kZY5c5wCHODT26/9mFhe qJulzH4ZQXtbxl+1UDltiIs9WclcvKqXF15nI+4f7pD28rHCmvAyD5QDhIadt46l tP+8TeLRAqUg1iwcivEhjAJsuKRM8alvveRcjTdHxLfHGELVoanqw7blhxJp2d5i Dd53XWpBwkuODxv69cUWx84/uLSBGzdQmHyPe1spTgA6EPXZuNmfNJ/m1nlK8nLU f692x/th53wbk3sjdSvVpMQwTPKXOR/3Sqfu4upsB5I/VbSg4mjOquoTeyJ6Zext 56p6CC0RZanbM5NqfQsBFfo7lxdp8UKuU97ZwoO4MBL7VsfjqYLoBTGBuUTphI79 HUZ3K+y39dOU8lQxjX4CMSo23IFLYcrx61oN4PZorzYqyEyB6FwGtdqaOaPeD1tW MCG6cb9fZMmIbPbPgcW/8l42YVG5iHSIQatJG9ojJ6x5kH35SLk/4yYrXOMHG4Ff 4Iu718XMl7ua64xzqs+F =OyRH -----END PGP SIGNATURE----- --2NLGdgz3UMHa/lqP--