% time hg --profile --config experimental.obshashrange=false push --debug --verbose -t riastradh-test20251021 ssh://hg.test.netbsd.org/pkgsrc@draft pushing to ssh://hg.test.netbsd.org/pkgsrc%40draft running /home/riastradh/scripts/cvs-rsh/nbssh.sh hg.test.netbsd.org 'hg -R pkgsrc@draft serve --stdio' sending hello command sending between command remote: 635 remote: capabilities: _evoext_getbundle_obscommon _evoext_obshashrange_v1 _exttopics_heads batch branchmap bundle2=HG20%0Abookmarks%0Achangegroup%3D01%2C02%2C03%0Acheckheads%3Drelated%0Adigests%3Dmd5%2Csha1%2Csha512%0Aerror%3Dabort%2Cunsupportedcontent%2Cpushraced%2Cpushkey%0Ahgtagsfnodes%0Alistkeys%0Aobsmarkers%3DV0%2CV1%0Aphases%3Dheads%0Apushkey%0Aremote-changegroup%3Dhttp%2Chttps%0Astream%3Dv2 changegroupsubset ext-topics-publish=none ext-topics-tns-heads getbundle known lookup protocaps pushkey streamreqs=generaldelta,revlog-compression-zstd,revlogv1,sparserevlog topics topics-namespaces unbundle=HG10GZ,HG10BZ,HG10UN unbundlehash remote: 1 sending protocaps command query 1; heads sending batch command searching for changes taking initial sample query 2; still undecided: 1911, sample size is: 200 sending known command 2 total queries in 1.8614s preparing listkeys for "phases" sending listkeys command received listkey for "phases": 85 bytes preparing listkeys for "namespaces" sending listkeys command received listkey for "namespaces": 40 bytes preparing listkeys for "namespaces" sending listkeys command received listkey for "namespaces": 40 bytes checking for updated bookmarks preparing listkeys for "bookmarks" sending listkeys command received listkey for "bookmarks": 0 bytes preparing listkeys for "namespaces" sending listkeys command received listkey for "namespaces": 40 bytes preparing listkeys for "phases" sending listkeys command received listkey for "phases": 85 bytes sending branchmap command preparing listkeys for "bookmarks" sending listkeys command received listkey for "bookmarks": 0 bytes 1 changesets found list of changesets: 5c01f8a15a557f436fdd7fd93a8961f57c630124 sending unbundle command bundle2-output-bundle: "HG20", 4 parts total bundle2-output-part: "replycaps" 227 bytes payload bundle2-output-part: "check:phases" 72 bytes payload bundle2-output-part: "changegroup" (params: 1 mandatory) streamed payload bundle2-output-part: "obsmarkers" streamed payload remote: adding changesets remote: adding manifests remote: adding file changes remote: checking encoding/normalization/case-sensitivity... remote: 0.281482 sec to check encoding/normalization/case-sensitivity remote: no encoding/normalization/case-sensitivity trouble found remote: added 1 changesets with 1 changes to 1 files (+1 heads) remote: 1 new obsolescence markers remote: obsoleted 1 changesets bundle2-input-bundle: no-transaction bundle2-input-part: "reply:changegroup" (advisory) (params: 0 advisory) supported bundle2-input-part: "reply:obsmarkers" (params: 0 advisory) supported bundle2-input-bundle: 2 parts total preparing listkeys for "phases" sending listkeys command received listkey for "phases": 128 bytes remote: | 100.0% 63.34s hg: line 61: dispatch.run() remote: | 100.0% 63.34s dispatch.py: run line 143: status = dispatch(req) remote: | 100.0% 63.34s dispatch.py: dispatch line 232: status = _rundispatch(req) remote: | 100.0% 63.34s dispatch.py: _rundispatch line 276: ret = _runcatch(req) or 0 remote: | 100.0% 63.34s dispatch.py: _runcatch line 457: return _callcatch(ui, _runc... remote: | 100.0% 63.34s dispatch.py: _callcatch line 467: return scmutil.callcatch(ui... remote: | 100.0% 63.34s scmutil.py: callcatch line 177: return func() remote: | 100.0% 63.34s dispatch.py: _runcatchfunc line 447: return _dispatch(req) remote: | 99.6% 63.10s dispatch.py: _dispatch line 1272: return runcommand( remote: | 99.6% 63.10s dispatch.py: runcommand line 905: ret = _runcommand(ui, optio... remote: | 99.6% 63.10s dispatch.py: _runcommand line 1284: return cmdfunc() remote: | 99.6% 63.10s dispatch.py: line 1270: d = lambda: util.checksigna... remote: | 99.6% 63.10s util.py: check line 1957: return func(*args, **kwargs) remote: | 99.6% 63.10s commands.py: serve line 6706: s.serve_forever() remote: | 99.6% 63.10s wireprotoserver.py: serve_forever line 532: self.serveuntil(threading.E... remote: | 99.6% 63.10s wireprotoserver.py: serveuntil line 551: _runsshserver( remote: \ 84.5% 53.50s wireprotoserver.py: _runsshserver line 488: rsp = wireprotov1server.dis... remote: \ 78.2% 49.54s wireprotov1server.py: dispatch line 92: return func(repo, proto, *a... remote: | 76.5% 48.44s wireprotov1server.py: unbundle, line 722: r = exchange.unbundle( remote: | 71.9% 45.52s exchange.py: unbundle line 2823: lockandtr[2].close() remote: | 71.9% 45.52s transaction.py: _active line 41: return func(self, *args, **... remote: | 71.8% 45.50s transaction.py: close line 756: self._postclosecallback[cat... remote: | 71.8% 45.50s localrepo.py: updater line 2891: repo.updatecaches(tr) remote: | 71.8% 45.50s localrepo.py: wrapper line 246: return orig(repo.unfiltered... remote: | 71.8% 45.50s obscache.py: updatecaches line 519: super(obscacherepo, self).u... remote: | 71.8% 45.50s localrepo.py: wrapper line 246: return orig(repo.unfiltered... remote: | 70.3% 44.54s firstmergecache.py: updatecaches, line 64: super(firstmergecacherepo, ... remote: | 70.3% 44.54s localrepo.py: wrapper line 246: return orig(repo.unfiltered... remote: | 68.9% 43.62s depthcache.py: updatecaches line 91: super(depthcacherepo, self)... remote: | 68.9% 43.62s localrepo.py: wrapper line 246: return orig(repo.unfiltered... remote: | 67.9% 42.98s stablesort.py: updatecaches line 971: super(stablesortrepo, self)... remote: | 67.9% 42.98s localrepo.py: wrapper line 246: return orig(repo.unfiltered... remote: | 67.8% 42.92s stablerangecache.py: updatecaches, line 535: self.stablerange.update(self) remote: | 67.8% 42.92s localrepo.py: __get__ line 225: return super(unfilteredprop... remote: | 67.8% 42.92s util.py: __get__ line 1825: result = self.func(obj) remote: | 67.8% 42.92s stablerangecache.py: stablerange, line 522: cache.update(self) remote: | 67.8% 42.92s genericcaches.py: update line 122: self._updatefrom(repo, data) remote: | 67.8% 42.92s stablerangecache.py: _updatefrom, line 112: for sub in self.subranges(r... remote: | 67.8% 42.92s stablerange.py: subranges line 654: value = self._subranges(rep... remote: | 67.8% 42.92s stablerange.py: _subranges line 739: ret = self._slicesrangeat(r... remote: | 67.8% 42.92s stablerange.py: _slicesrangeat, line 745: self._warmcachefor(repo, ra... remote: | 66.1% 41.84s stablerange.py: _warmcachefor line 729: self.subranges(repo, current) remote: | 66.1% 41.84s stablerange.py: subranges line 651: cached = self._getsub(rangeid) remote: \ 33.6% 21.26s stablerangecache.py: _getsub, line 224: value = self._con.execute(_... remote: \ 32.4% 20.52s stablerangecache.py: _getsub, line 222: result = self._con.execute(... remote: \ 6.3% 3.96s wireprotov1server.py: dispatch line 90: args = proto.getargs(spec) remote: \ 15.1% 9.58s wireprotoserver.py: _runsshserver line 471: request = fin.readline()[:-1] remote: --- remote: Sample count: 3025 remote: Total time: 47.020000 seconds (63.360000 wall) | 100.0% 70.37s hg: line 59: dispatch.run() | 100.0% 70.37s dispatch.py: run line 142: status = dispatch(req) | 100.0% 70.37s dispatch.py: dispatch line 231: status = _rundispatch(req) | 100.0% 70.37s dispatch.py: _rundispatch line 275: ret = _runcatch(req) or 0 | 100.0% 70.37s dispatch.py: _runcatch line 456: return _callcatch(ui, _runc... | 100.0% 70.37s dispatch.py: _callcatch line 466: return scmutil.callcatch(ui... | 100.0% 70.37s scmutil.py: callcatch line 152: return func() | 100.0% 70.37s dispatch.py: _runcatchfunc line 446: return _dispatch(req) | 99.7% 70.18s dispatch.py: _dispatch line 1271: return runcommand( | 99.7% 70.18s __init__.py: runcommand line 577: return orig(lui, repo, *arg... | 99.7% 70.17s dispatch.py: runcommand line 904: ret = _runcommand(ui, optio... | 99.7% 70.17s dispatch.py: _runcommand line 1283: return cmdfunc() | 99.7% 70.17s dispatch.py: line 1269: d = lambda: util.checksigna... | 99.7% 70.17s util.py: check line 1898: return func(*args, **kwargs) | 99.7% 70.17s util.py: check line 1898: return func(*args, **kwargs) | 99.7% 70.12s __init__.py: pushoutgoingwrap line 1683: return orig(ui, repo, *args... | 99.7% 70.12s util.py: check line 1898: return func(*args, **kwargs) | 99.7% 70.12s util.py: check line 1898: return func(*args, **kwargs) | 99.7% 70.12s flow.py: wrappush line 118: return orig(repo, remote, *... | 99.7% 70.12s util.py: check line 1898: return func(*args, **kwargs) \ 88.0% 61.94s commands.py: push line 5873: pushop = exchange.push( | 88.0% 61.94s __init__.py: push line 581: return orig(repo, *args, **... \ 76.7% 53.95s exchange.py: push line 524: _pushbundle2(pushop) \ 70.9% 49.89s exchange.py: _pushbundle2, line 1224: reply = e.callcommand( | 70.9% 49.89s server.py: callcommand, line 148: return s.callcommand(comman... | 70.9% 49.89s wireprotov1peer.py: callcommand, line 175: self.sendcommands() | 70.9% 49.89s wireprotov1peer.py: sendcommands, line 208: result = fn(**pycompat.strk... | 69.9% 49.17s wireprotov1peer.py: unbundle, line 515: ret = bundle2.getunbundler(... | 69.9% 49.17s bundle2.py: getunbundler, line 811: magicstring = changegroup.r... | 69.9% 49.17s util.py: readexactly, line 3197: s = stream.read(n) | 69.9% 49.17s sshpeer.py: read line 97: r = self._call(b'read', size) | 69.9% 49.17s sshpeer.py: _call line 128: mainready, sideready = self... | 69.9% 49.17s sshpeer.py: _wait line 87: act = util.poll(fds) | 69.9% 49.17s posix.py: poll line 761: res = select.select(fds, fd... \ 5.8% 4.06s exchange.py: _pushbundle2, line 1214: ret = partgen(pushop, bundler) | 5.8% 4.05s exchange.py: _pushb2ctx, line 947: if not _pushcheckoutgoing(p... | 5.8% 4.05s exchange.py: _pushcheckoutgoing, line 825: discovery.checkheads(pushop) \ 10.4% 7.32s exchange.py: push line 520: _pushdiscovery(pushop) | 10.4% 7.32s exchange.py: _pushdiscovery, line 572: step(pushop) | 5.7% 4.00s obsdiscovery.py: newdisco line 817: _pushdiscoveryobsmarkers(ol... \ 8.3% 5.83s commands.py: push line 5828: other = hg.peer(repo, opts,... | 8.3% 5.83s discovery.py: wraphgpeer line 369: peer = orig(uiorrepo, opts,... | 8.3% 5.83s hg.py: peer line 264: peer = cls.make_peer( | 7.4% 5.19s sshpeer.py: make_peer line 694: peer = _make_peer( | 7.4% 5.18s sshpeer.py: _make_peer line 610: protoname, caps = _performh... | 7.4% 5.18s sshpeer.py: _performhandshake, line 323: l = stdout.readline() --- Sample count: 33422 Total time: 12.963000 seconds (70.370000 wall) hg --profile --config experimental.obshashrange=false push --debug --verbose 19.36s user 0.36s system 25% cpu 1:17.12 total