1437404 - Intermittent valgrind-test | 72 bytes in 1 blocks are definitely lost at calloc / error_get_my_stack / nss_ClearErrorStack / NSSArena_Create

Status: RESOLVED INCOMPLETE

(NSS :: Build, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ncsoregi [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=161576548&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/Nq43q8FmQzygdLmEYr5JEA/runs/0/artifacts/public/logs/live_backing.log

[task 2018-02-11T13:13:31.924Z] 13:13:31     INFO -  14:02.14 --31165-- Archiving syms at 0x200dd010-0x200eafdb in /builds/worker/workspace/build/src/obj-firefox/security/nss/lib/ckfw/builtins/builtins_nssckbi/libnssckbi.so due to munmap()
[task 2018-02-11T13:13:32.482Z] 13:13:32     INFO -  14:02.69 1518354812462	addons.xpi	ERROR	XPI_verifySignature: Error: Attempt to use XPI database when it is not initialized
[task 2018-02-11T13:13:42.468Z] 13:13:42     INFO -  14:12.68 --31165-- memcheck GC: 81115 nodes, 6158 survivors (7.6%)
[task 2018-02-11T13:13:46.762Z] 13:13:46     INFO -  14:16.98 ==31165== Warning: set address range perms: large range [0x1f1678fe5000, 0x1f16b8fe5000) (noaccess)
[task 2018-02-11T13:13:50.353Z] 13:13:50     INFO -  14:20.57 --31165-- Archiving syms at 0x1ee3f000-0x1ee9a7a6 in /builds/worker/workspace/build/src/obj-firefox/security/nss/lib/freebl/freebl_freeblpriv3/libfreeblpriv3.so due to munmap()
[task 2018-02-11T13:13:50.369Z] 13:13:50     INFO -  14:20.58 --31165-- Archiving syms at 0x1ec045e0-0x1ec2e60c in /builds/worker/workspace/build/src/obj-firefox/security/nss/lib/softoken/softoken_softokn3/libsoftokn3.so due to munmap()
[task 2018-02-11T13:13:50.637Z] 13:13:50     INFO -  14:20.85 --31165-- Archiving syms at 0x252bd4d0-0x252be168 in /usr/lib/x86_64-linux-gnu/gconv/MACINTOSH.so due to munmap()
[task 2018-02-11T13:13:50.801Z] 13:13:50     INFO -  14:21.01 --31165-- Archiving syms at 0x1b406260-0x1b40aaf8 in /lib/x86_64-linux-gnu/libnss_compat-2.13.so due to munmap()
[task 2018-02-11T13:13:50.816Z] 13:13:50     INFO -  14:21.03 --31165-- Archiving syms at 0x1b827070-0x1b82d208 in /lib/x86_64-linux-gnu/libnss_nis-2.13.so due to munmap()
[task 2018-02-11T13:13:50.831Z] 13:13:50     INFO -  14:21.04 --31165-- Archiving syms at 0x151ce180-0x151d5148 in /lib/x86_64-linux-gnu/libnss_files-2.13.so due to munmap()
[task 2018-02-11T13:13:50.839Z] 13:13:50     INFO -  14:21.05 --31165-- Archiving syms at 0x374a8000-0x374ab0b8 in /lib/x86_64-linux-gnu/libnss_dns-2.13.so due to munmap()
[task 2018-02-11T13:13:50.854Z] 13:13:50     INFO -  14:21.07 ==31165==
[task 2018-02-11T13:13:50.854Z] 13:13:50     INFO -  14:21.07 ==31165== HEAP SUMMARY:
[task 2018-02-11T13:13:50.854Z] 13:13:50     INFO -  14:21.07 ==31165==     in use at exit: 1,765,986 bytes in 11,850 blocks
[task 2018-02-11T13:13:50.854Z] 13:13:50     INFO -  14:21.07 ==31165==   total heap usage: 2,418,939 allocs, 2,407,089 frees, 1,667,620,552 bytes allocated
[task 2018-02-11T13:13:50.854Z] 13:13:50     INFO -  14:21.07 ==31165==
[task 2018-02-11T13:13:50.854Z] 13:13:50     INFO -  14:21.07 ==31165== Searching for pointers to 11,850 not-freed blocks
[task 2018-02-11T13:13:50.890Z] 13:13:50     INFO -  14:21.10 ==31165== Checked 15,277,192 bytes
[task 2018-02-11T13:13:50.890Z] 13:13:50     INFO -  14:21.10 ==31165==
[task 2018-02-11T13:13:51.266Z] 13:13:51     INFO -  14:21.48 TEST-UNEXPECTED-FAIL | valgrind-test | 72 bytes in 1 blocks are definitely lost at calloc / error_get_my_stack / nss_ClearErrorStack / NSSArena_Create
[task 2018-02-11T13:13:51.267Z] 13:13:51     INFO -  14:21.48 ==31165== 72 bytes in 1 blocks are definitely lost in loss record 4,941 of 7,740

Comment 1

[Intermittent Failures Robot]

1 failures in 702 pushes (0.001 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1437404&startday=2018-02-05&endday=2018-02-11&tree=all

Comment 2

[Intermittent Failures Robot]

1 failures in 685 pushes (0.001 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1437404&startday=2018-02-12&endday=2018-02-18&tree=all

Comment 3

[Intermittent Failures Robot]

1 failures in 675 pushes (0.001 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1437404&startday=2018-02-19&endday=2018-02-25&tree=all

Comment 4

[Intermittent Failures Robot]

1 failures in 831 pushes (0.001 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1437404&startday=2018-02-26&endday=2018-03-04&tree=all

Comment 5

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 6

[Andreea Pavel [:apavel]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=170459064&repo=mozilla-inbound&lineNumber=33625

[task 2018-03-27T05:02:23.787Z] 05:02:23     INFO -  13:53.38 receiveMessage()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///tmp/tmpJyW6LY/extensions/[e-mail]!/bootstrap.js:28
[task 2018-03-27T05:02:23.787Z] 05:02:23     INFO -  13:53.38 quit()@contentscript.js:16
[task 2018-03-27T05:02:24.419Z] 05:02:24     INFO -  13:54.01 --32530-- memcheck GC: 81118 nodes, 14219 survivors (17.5%)
[task 2018-03-27T05:02:27.100Z] 05:02:27     INFO -  13:56.69 --32530-- Archiving syms at 0x207df010-0x207ecfdb in /builds/worker/workspace/build/src/obj-firefox/security/nss/lib/ckfw/builtins/builtins_nssckbi/libnssckbi.so due to munmap()
[task 2018-03-27T05:02:27.684Z] 05:02:27     INFO -  13:57.28 1522126947672	addons.xpi	ERROR	XPI_verifySignature: Error: Attempt to use XPI database when it is not initialized
[task 2018-03-27T05:02:37.152Z] 05:02:37     INFO -  14:06.74 --32530-- memcheck GC: 81118 nodes, 9544 survivors (11.8%)
[task 2018-03-27T05:02:43.044Z] 05:02:43     INFO -  14:12.64 ==32530== Warning: set address range perms: large range [0x317c47dfb000, 0x317c87dfb000) (noaccess)
[task 2018-03-27T05:02:46.564Z] 05:02:46     INFO -  14:16.16 --32530-- Archiving syms at 0x1f140000-0x1f19c176 in /builds/worker/workspace/build/src/obj-firefox/security/nss/lib/freebl/freebl_freeblpriv3/libfreeblpriv3.so due to munmap()
[task 2018-03-27T05:02:46.580Z] 05:02:46     INFO -  14:16.17 --32530-- Archiving syms at 0x1ef045f0-0x1ef2e96c in /builds/worker/workspace/build/src/obj-firefox/security/nss/lib/softoken/softoken_softokn3/libsoftokn3.so due to munmap()
[task 2018-03-27T05:02:46.862Z] 05:02:46     INFO -  14:16.46 --32530-- Archiving syms at 0x249bb4d0-0x249bc168 in /usr/lib/x86_64-linux-gnu/gconv/MACINTOSH.so due to munmap()
[task 2018-03-27T05:02:47.050Z] 05:02:47     INFO -  14:16.64 --32530-- Archiving syms at 0x1bb07260-0x1bb0baf8 in /lib/x86_64-linux-gnu/libnss_compat-2.13.so due to munmap()
[task 2018-03-27T05:02:47.066Z] 05:02:47     INFO -  14:16.66 --32530-- Archiving syms at 0x1bf28070-0x1bf2e208 in /lib/x86_64-linux-gnu/libnss_nis-2.13.so due to munmap()
[task 2018-03-27T05:02:47.082Z] 05:02:47     INFO -  14:16.68 --32530-- Archiving syms at 0x15bc8180-0x15bcf148 in /lib/x86_64-linux-gnu/libnss_files-2.13.so due to munmap()
[task 2018-03-27T05:02:47.098Z] 05:02:47     INFO -  14:16.69 --32530-- Archiving syms at 0x3659d000-0x365a00b8 in /lib/x86_64-linux-gnu/libnss_dns-2.13.so due to munmap()
[task 2018-03-27T05:02:47.099Z] 05:02:47     INFO -  14:16.69 ==32530==
[task 2018-03-27T05:02:47.100Z] 05:02:47     INFO -  14:16.70 ==32530== HEAP SUMMARY:
[task 2018-03-27T05:02:47.100Z] 05:02:47     INFO -  14:16.70 ==32530==     in use at exit: 1,749,844 bytes in 11,966 blocks
[task 2018-03-27T05:02:47.100Z] 05:02:47     INFO -  14:16.70 ==32530==   total heap usage: 2,248,236 allocs, 2,236,270 frees, 1,500,505,376 bytes allocated
[task 2018-03-27T05:02:47.100Z] 05:02:47     INFO -  14:16.70 ==32530==
[task 2018-03-27T05:02:47.104Z] 05:02:47     INFO -  14:16.70 ==32530== Searching for pointers to 11,966 not-freed blocks
[task 2018-03-27T05:02:47.144Z] 05:02:47     INFO -  14:16.74 ==32530== Checked 15,180,008 bytes
[task 2018-03-27T05:02:47.144Z] 05:02:47     INFO -  14:16.74 ==32530==
[task 2018-03-27T05:02:47.643Z] 05:02:47     INFO -  14:17.24 TEST-UNEXPECTED-FAIL | valgrind-test | 72 bytes in 1 blocks are definitely lost at calloc / error_get_my_stack / nss_ClearErrorStack / NSSArena_Create
[task 2018-03-27T05:02:47.643Z] 05:02:47     INFO -  14:17.24 ==32530== 72 bytes in 1 blocks are definitely lost in loss record 5,046 of 7,868
[task 2018-03-27T05:02:47.643Z] 05:02:47     INFO -  14:17.24 ==32530==    at 0x4C28960: calloc (vg_replace_malloc.c:710)
[task 2018-03-27T05:02:47.644Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0x207E08A6: error_get_my_stack (error.c:110)
[task 2018-03-27T05:02:47.644Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0x207E08F8: nss_ClearErrorStack (error.c:246)
[task 2018-03-27T05:02:47.644Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0x207E0528: NSSArena_Create (arena.c:329)
[task 2018-03-27T05:02:47.644Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0x207DFAE3: nss_builtins_FindObjectsInit (bfind.c:188)
[task 2018-03-27T05:02:47.644Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0x207E4ECF: nssCKFWSession_FindObjectsInit (session.c:1594)
[task 2018-03-27T05:02:47.644Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0x207E9CE0: NSSCKFWC_FindObjectsInit (wrap.c:2476)
[task 2018-03-27T05:02:47.644Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0x716B2BF: nssToken_TraverseCertificates (devtoken.c:1487)
[task 2018-03-27T05:02:47.645Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0x7173ACF: NSSTrustDomain_TraverseCertificates (trustdomain.c:1019)
[task 2018-03-27T05:02:47.645Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0x71557E0: CERT_GetCertNicknames (certhigh.c:485)
[task 2018-03-27T05:02:47.645Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0x71558C8: CERT_FindUserCertsByUsage (certhigh.c:86)
[task 2018-03-27T05:02:47.645Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0x112542B5: nsNSSComponent::HasUserCertsInstalled(bool&) (nsNSSComponent.cpp:1109)
[task 2018-03-27T05:02:47.645Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE4B93D9: mozilla::net::CanEnableSpeculativeConnect() (nsHttpHandler.cpp:2480)
[task 2018-03-27T05:02:47.646Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE4C1C86: mozilla::net::nsHttpHandler::SpeculativeConnectInternal(nsIURI*, nsIPrincipal*, nsIInterfaceRequestor*, bool) (nsHttpHandler.cpp:2581)
[task 2018-03-27T05:02:47.646Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE282492: mozilla::net::IOServiceProxyCallback::OnProxyAvailable(nsICancelable*, nsIChannel*, nsIProxyInfo*, nsresult) (nsIOService.cpp:1829)
[task 2018-03-27T05:02:47.646Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE2C1667: mozilla::net::nsAsyncResolveRequest::DoCallback() (nsProtocolProxyService.cpp:422)
[task 2018-03-27T05:02:47.646Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE2C1AA6: Run (nsProtocolProxyService.cpp:272)
[task 2018-03-27T05:02:47.647Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE2C1AA6: operator() (nsProtocolProxyService.cpp:251)
[task 2018-03-27T05:02:47.647Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE2C1AA6: std::_Function_handler<nsresult (mozilla::net::nsAsyncResolveRequest*, nsIProxyInfo*, bool), mozilla::net::nsAsyncResolveRequest::ProcessLocally(mozilla::net::nsProtocolInfo&, nsIProxyInfo*, bool)::{lambda(mozilla::net::nsAsyncResolveRequest*, nsIProxyInfo*, bool)#1}>::_M_invoke(std::_Any_data const&, mozilla::net::nsAsyncResolveRequest*&&, nsIProxyInfo*&&, bool&&) (functional:1717)
[task 2018-03-27T05:02:47.647Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE2C0F18: operator() (functional:2127)
[task 2018-03-27T05:02:47.648Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE2C0F18: mozilla::net::nsAsyncResolveRequest::AsyncApplyFilters::Finish() (nsProtocolProxyService.cpp:623)
[task 2018-03-27T05:02:47.649Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE2C11DB: mozilla::net::nsAsyncResolveRequest::AsyncApplyFilters::ProcessNextFilter() (nsProtocolProxyService.cpp:534)
[task 2018-03-27T05:02:47.649Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE2C13CE: mozilla::net::nsAsyncResolveRequest::AsyncApplyFilters::AsyncProcess(mozilla::net::nsAsyncResolveRequest*) (nsProtocolProxyService.cpp:509)
[task 2018-03-27T05:02:47.649Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE2C1E55: ProcessLocally (nsProtocolProxyService.cpp:260)
[task 2018-03-27T05:02:47.649Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE2C1E55: mozilla::net::nsProtocolProxyService::AsyncResolveInternal(nsIChannel*, unsigned int, nsIProtocolProxyCallback*, nsICancelable**, bool, nsIEventTarget*) (nsProtocolProxyService.cpp:1688)
[task 2018-03-27T05:02:47.650Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE2C1FBA: mozilla::net::nsProtocolProxyService::AsyncResolve2(nsIChannel*, unsigned int, nsIProtocolProxyCallback*, nsIEventTarget*, nsICancelable**) (nsProtocolProxyService.cpp:1711)
[task 2018-03-27T05:02:47.650Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE2955B9: mozilla::net::nsIOService::SpeculativeConnectInternal(nsIURI*, nsIPrincipal*, nsIInterfaceRequestor*, bool) (nsIOService.cpp:1909)
[task 2018-03-27T05:02:47.650Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE256453: mozilla::net::Predictor::PredictForLink(nsIURI*, nsIURI*, mozilla::OriginAttributes const&, nsINetworkPredictorVerifier*) (Predictor.cpp:1047)
[task 2018-03-27T05:02:47.650Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE256EE2: mozilla::net::Predictor::PredictNative(nsIURI*, nsIURI*, unsigned int, mozilla::OriginAttributes const&, nsINetworkPredictorVerifier*) (Predictor.cpp:899)
[task 2018-03-27T05:02:47.650Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xE2583F3: mozilla::net::PredictorPredict(nsIURI*, nsIURI*, unsigned int, mozilla::OriginAttributes const&, nsINetworkPredictorVerifier*) (Predictor.cpp:2338)
[task 2018-03-27T05:02:47.651Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0x110D4315: nsDocShell::OnOverLink(nsIContent*, nsIURI*, char16_t const*) [clone .part.312] (nsDocShell.cpp:13826)
[task 2018-03-27T05:02:47.651Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xEF27D6A: nsContentUtils::TriggerLink(nsIContent*, nsPresContext*, nsIURI*, nsTString<char16_t> const&, bool, bool, bool) (nsContentUtils.cpp:5654)
[task 2018-03-27T05:02:47.651Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xEFC5410: mozilla::dom::Element::GetEventTargetParentForLinks(mozilla::EventChainPreVisitor&) (Element.cpp:3272)
[task 2018-03-27T05:02:47.651Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xFA81E81: mozilla::EventTargetChainItem::GetEventTargetParent(mozilla::EventChainPreVisitor&) (EventDispatcher.cpp:425)
[task 2018-03-27T05:02:47.652Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xFA92243: mozilla::EventDispatcher::Dispatch(nsISupports*, nsPresContext*, mozilla::WidgetEvent*, nsIDOMEvent*, nsEventStatus*, mozilla::EventDispatchingCallback*, nsTArray<mozilla::dom::EventTarget*>*) (EventDispatcher.cpp:832)
[task 2018-03-27T05:02:47.652Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xFA5DA35: mozilla::EventStateManager::DispatchMouseOrPointerEvent(mozilla::WidgetMouseEvent*, mozilla::EventMessage, nsIContent*, nsIContent*) (EventStateManager.cpp:4150)
[task 2018-03-27T05:02:47.652Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xFA5E057: mozilla::EventStateManager::NotifyMouseOver(mozilla::WidgetMouseEvent*, nsIContent*) (EventStateManager.cpp:4367)
[task 2018-03-27T05:02:47.652Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xFA5E415: mozilla::EventStateManager::GenerateMouseEnterExit(mozilla::WidgetMouseEvent*) (EventStateManager.cpp:4516)
[task 2018-03-27T05:02:47.653Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0xFA5F492: mozilla::EventStateManager::PreHandleEvent(nsPresContext*, mozilla::WidgetEvent*, nsIFrame*, nsIContent*, nsEventStatus*) (EventStateManager.cpp:732)
[task 2018-03-27T05:02:47.653Z] 05:02:47     INFO -  14:17.24 ==32530==    by 0x10464B5A: mozilla::PresShell::HandleEventInternal(mozilla::WidgetEvent*, nsEventStatus*, bool) (PresShell.cpp:7614)
[task 2018-03-27T05:02:47.653Z] 05:02:47     INFO -  14:17.24 ==32530==

Comment 7

[Intermittent Failures Robot]

1 failures in 822 pushes (0.001 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1437404&startday=2018-03-20&endday=2018-03-26&tree=trunk

Comment 8

[Intermittent Failures Robot]

4 failures in 718 pushes (0.006 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 3
* autoland: 1

Platform breakdown:
* linux64: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1437404&startday=2018-03-26&endday=2018-04-01&tree=trunk

Comment 9

[Intermittent Failures Robot]

1 failures in 765 pushes (0.001 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* try: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1437404&startday=2018-04-02&endday=2018-04-08&tree=trunk

Comment 10

[Intermittent Failures Robot]

1 failures in 792 pushes (0.001 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1437404&startday=2018-04-09&endday=2018-04-15&tree=trunk

Comment 11

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup