Closed Bug 1501197 Opened Last year Closed 8 months ago

Intermittent services/sync/tests/unit/test_load_modules.js | xpcshell return code: 1

Categories

(Firefox :: Sync, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Assigned: rhunt)

References

Details

(Keywords: intermittent-failure, Whiteboard: [retriggered][stockwell disabled])

Attachments

(2 files)

Filed by: btara [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=207198355&repo=autoland

https://queue.taskcluster.net/v1/task/Y-3U4dd9SLG5KpXDQymi4w/runs/0/artifacts/public/logs/live_backing.log

07:15:54     INFO -  TEST-START | toolkit/components/extensions/test/xpcshell/test_ext_native_messaging_perf.js
07:15:58     INFO -  TEST-PASS | toolkit/components/extensions/test/xpcshell/test_ext_native_messaging_perf.js | took 3689ms
07:15:58     INFO -  TEST-START | services/sync/tests/unit/test_load_modules.js
07:16:58  WARNING -  TEST-UNEXPECTED-FAIL | services/sync/tests/unit/test_load_modules.js | xpcshell return code: 1
07:16:58     INFO -  TEST-INFO took 60378ms
07:16:59     INFO -  >>>>>>>
07:16:59     INFO -  PID 16108 | JavaScript strict warning: Z:/task_1540276540/build/tests/xpcshell/tests/services/sync/tests/unit/head_helpers.js -> resource://testing-common/sinon-2.3.2.js, line 8941: ReferenceError: reference to undefined property "iso-8859-8-i"
07:16:59     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
...
07:16:59     INFO -  PID 16108 | _execute_test@Z:\\task_1540276540\\build\\tests\\xpcshell\\head.js:527:7
07:16:59     INFO -  PID 16108 | @-e:1:1
07:16:59     INFO -  PID 16108 | JavaScript strict warning: resource://services-sync/engines.js, line 571: ReferenceError: reference to undefined property "Prefs"
07:16:59     INFO -  <<<<<<<
07:16:59     INFO -  mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/eFdJZz4_SMyGKHUXkSnt0w/artifacts/public/build/target.crashreporter-symbols.zip
07:17:02     INFO -  mozcrash Copy/paste: Z:\task_1540276540\build\win32-minidump_stackwalk.exe c:\users\task_1540276540\appdata\local\temp\xpc-other-k6xbri\1868e54f-d746-48d7-b806-08746a97764f.dmp c:\users\task_1540276540\appdata\local\temp\tmpritsxb
07:17:07     INFO -  mozcrash Saved minidump as Z:\task_1540276540\build\blobber_upload_dir\1868e54f-d746-48d7-b806-08746a97764f.dmp
07:17:07     INFO -  mozcrash Saved app info as Z:\task_1540276540\build\blobber_upload_dir\1868e54f-d746-48d7-b806-08746a97764f.extra
07:17:07  WARNING -  PROCESS-CRASH | services/sync/tests/unit/test_load_modules.js | application crashed [@ xul.dll + 0x34c7f4d]
07:17:07     INFO -  Crash dump filename: c:\users\task_1540276540\appdata\local\temp\xpc-other-k6xbri\1868e54f-d746-48d7-b806-08746a97764f.dmp
07:17:07     INFO -  Operating system: Windows NT
07:17:07     INFO -                    10.0.15063
07:17:07     INFO -  CPU: amd64
07:17:07     INFO -       family 6 model 63 stepping 2
07:17:07     INFO -       8 CPUs
07:17:07     INFO -  GPU: UNKNOWN
07:17:07     INFO -  Crash reason:  EXCEPTION_ACCESS_VIOLATION_READ
07:17:07     INFO -  Crash address: 0x0
07:17:07     INFO -  Process uptime: 60 seconds
07:17:07     INFO -  Thread 0 (crashed)
07:17:07     INFO -   0  xul.dll + 0x34c7f4d
07:17:07     INFO -      rax = 0x0000000000000001   rdx = 0x0000000000000000
07:17:07     INFO -      rcx = 0x0000000000000000   rbx = 0x000002432e807608
07:17:07     INFO -      rsi = 0x000002432e807600   rdi = 0x0000024338e7c0d8
07:17:07     INFO -      rbp = 0x0000000000000000   rsp = 0x000000aee47fee50
07:17:07     INFO -       r8 = 0x0000000000000000    r9 = 0x0000000000000020
07:17:07     INFO -      r10 = 0x0000000000000000   r11 = 0x000002432e700100
07:17:07     INFO -      r12 = 0x00007fff01456387   r13 = 0x00000000ffffffff
07:17:07     INFO -      r14 = 0x000002433a7641a0   r15 = 0x0000000000000001
07:17:07     INFO -      rip = 0x00007ffeffb27f4d
07:17:07     INFO -      Found by: given as instruction pointer in context
07:17:07     INFO -  Thread 1
07:17:07     INFO -   0  ntdll.dll!NtRemoveIoCompletion + 0x14
07:17:07     INFO -      rax = 0xfffffffffffffffe   rdx = 0x000002432e803100
07:17:07     INFO -      rcx = 0xfffffffffffffffe   rbx = 0x000000aee4fff680
07:17:07     INFO -      rsi = 0x000002432e83b100   rdi = 0x000000aee4fff694
07:17:07     INFO -      rbp = 0x000000aee4fff694   rsp = 0x000000aee4fff5c8
07:17:07     INFO -       r8 = 0x0000000000000074    r9 = 0x000000000000000f
07:17:07     INFO -      r10 = 0x000000aee4fff5e8   r11 = 0x000002432e803100
07:17:07     INFO -      r12 = 0x00000000000000bc   r13 = 0x0000000000000000
07:17:07     INFO -      r14 = 0x00007fff2f685530   r15 = 0x000002432e83b120
07:17:07     INFO -      rip = 0x00007fff2f9354d4
07:17:07     INFO -      Found by: given as instruction pointer in context
07:17:07     INFO -   1  KERNELBASE.dll!LdrResGetRCConfig + 0x3ff
07:17:07     INFO -      rbx = 0x000000aee4fff680   rbp = 0x000000aee4fff694
07:17:07     INFO -      rsp = 0x000000aee4fff5d0   r12 = 0x00000000000000bc
07:17:07     INFO -      r13 = 0x0000000000000000   r14 = 0x00007fff2f685530
07:17:07     INFO -      r15 = 0x000002432e83b120   rip = 0x00007fff2c3a9aff
There are 52 failures in the last 24h.
All of them on windows10-64.
Perry, could you please take a look?
Flags: needinfo?(perry)
Duplicate of this bug: 1501556
rhunt: Hi, could this type of failure https://treeherder.mozilla.org/logviewer.html#?job_id=207414103&repo=autoland&lineNumber=10335 be caused by the landing of https://bugzilla.mozilla.org/show_bug.cgi?id=1499554 as pointed out in Comment 1. Thank you!
Flags: needinfo?(rhunt)
There is no activity on this bug so I created the attached patch to disable the test.
Attachment #9021159 - Flags: review?(jmaher)
Attachment #9021159 - Flags: review?(jmaher) → review+
Whiteboard: [retriggered][stockwell disable-recommended] → [retriggered][stockwell disabled]
Pushed by ebalazs@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/60ba45fa266e
Disable test_load_modules.js on win10 for frequent failures. r=jmaher
Keywords: checkin-needed
I've got a patch that should fix this in bug 1501196. I'll make sure this gets re-enabled when that lands.
Flags: needinfo?(rhunt)
Flags: needinfo?(perry)
Assignee: nobody → rhunt
Here's a try run with the test enabled on m-c with the patches from bug 1501196. Retriggered it a bunch, and I don't see the intermittent anymore. [1]

[1] https://treeherder.mozilla.org/#/jobs?repo=try&selectedJob=209279301&revision=8a9a2d12a9ea4e8c2656da5d72a9142ec09d3149
Pushed by rhunt@eqrion.net:
https://hg.mozilla.org/integration/mozilla-inbound/rev/4453d0a8dc33
Re-enable test_load_modules.js xpcshell test. r=jmaher
This should be resolved now that the test is re-enabled.
Status: NEW → RESOLVED
Closed: Last year
Keywords: leave-open
Resolution: --- → FIXED

This reoccurred here: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=221098534&repo=try&lineNumber=13159

15:17:37 INFO - TEST-START | services/sync/tests/unit/test_load_modules.js
15:20:38 WARNING - TEST-UNEXPECTED-FAIL | services/sync/tests/unit/test_load_modules.js | xpcshell return code: 1
15:20:38 INFO - TEST-INFO took 180901ms
15:20:38 INFO - >>>>>>>

15:20:38 INFO - PID 10888 | _registerEngines@service.js:409:17
15:20:38 INFO - PID 10888 | JavaScript strict warning: resource://services-sync/engines.js, line 571: ReferenceError: reference to undefined property "Prefs"
15:20:38 INFO - PID 10888 | JavaScript error: resource://services-sync/engines.js, line 571: TypeError: Svc.Prefs is undefined
15:20:38 INFO - PID 10888 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 675: Error: Phase "profile-change-teardown" is finished, it is too late to register completion condition "Places Clients shutdown"
15:20:38 INFO - PID 10888 | Assertion failure: ((bool)(__builtin_expect(!!(!NS_FAILED_impl(rv)), 1))), at z:/build/build/src/toolkit/components/places/Database.cpp:491
15:20:56 INFO - PID 10888 | #01: mozilla::places::Database::GetSingleton() [toolkit/components/places/Database.cpp:356]
15:20:56 INFO - PID 10888 | #02: nsNavHistory::Init() [toolkit/components/places/nsNavHistory.cpp:435]

15:21:06 WARNING - PROCESS-CRASH | services/sync/tests/unit/test_load_modules.js | application crashed [@ mozilla::places::Database::Init()]
15:21:06 INFO - Crash dump filename: c:\users\task_1547130375\appdata\local\temp\xpc-other-ilfmm7\f99fb55d-5712-4a50-9577-af2eb950334b.dmp
15:21:06 INFO - Operating system: Windows NT
15:21:06 INFO - 6.1.7601 Service Pack 1
15:21:06 INFO - CPU: x86
15:21:06 INFO - GenuineIntel family 6 model 63 stepping 2
15:21:06 INFO - 8 CPUs
15:21:06 INFO - GPU: UNKNOWN
15:21:06 INFO - Crash reason: EXCEPTION_BREAKPOINT
15:21:06 INFO - Crash address: 0x59c013a1
15:21:06 INFO - Process uptime: 181 seconds
15:21:06 INFO - Thread 0 (crashed)
15:21:06 INFO - 0 xul.dll!mozilla::places::Database::Init() [Database.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 491 + 0x0]
15:21:06 INFO - eip = 0x59c013a1 esp = 0x002ff374 ebp = 0x002ff3a0 ebx = 0x002ff380
15:21:06 INFO - esi = 0x0de96040 edi = 0x002ff38c eax = 0x6b14c6a4 ecx = 0x000001eb
15:21:06 INFO - edx = 0x00000000 efl = 0x00000206
15:21:06 INFO - Found by: given as instruction pointer in context
15:21:06 INFO - 1 xul.dll!mozilla::places::Database::GetSingleton() [Database.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 356 + 0xf]
15:21:06 INFO - eip = 0x59c00d0e esp = 0x002ff3a8 ebp = 0x002ff3ac
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 2 xul.dll!nsNavHistory::Init() [nsNavHistory.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 435 + 0x1d]
15:21:06 INFO - eip = 0x59c47be6 esp = 0x002ff3b4 ebp = 0x002ff3cc
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 3 xul.dll!nsNavHistory::GetSingleton() [nsNavHistory.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 381 + 0xf]
15:21:06 INFO - eip = 0x59c479d2 esp = 0x002ff3d4 ebp = 0x002ff3dc
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 4 xul.dll!static nsresult nsNavHistoryConstructor(class nsISupports *, const struct nsID & const, void * *) [nsPlacesModule.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 18 + 0x30]
15:21:06 INFO - eip = 0x59c740b0 esp = 0x002ff3e4 ebp = 0x002ff3fc
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 5 xul.dll!mozilla::GenericFactory::CreateInstance(nsISupports *,nsID const &,void * *) [GenericFactory.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 16 + 0xc]
15:21:06 INFO - eip = 0x553b4262 esp = 0x002ff404 ebp = 0x002ff410
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 6 xul.dll!nsComponentManagerImpl::CreateInstanceByContractID(char const *,nsISupports *,nsID const &,void * *) [nsComponentManager.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 1082 + 0xd]
15:21:06 INFO - eip = 0x553bd0ab esp = 0x002ff418 ebp = 0x002ff444
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 7 xul.dll!nsComponentManagerImpl::GetServiceByContractID(char const *,nsID const &,void * *) [nsComponentManager.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 1435 + 0x12]
15:21:06 INFO - eip = 0x553b9d6a esp = 0x002ff44c ebp = 0x002ff4a8
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 8 xul.dll!nsGetServiceByContractID::operator()(nsID const &,void * *) [nsComponentManagerUtils.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 243 + 0x19]
15:21:06 INFO - eip = 0x553bf50d esp = 0x002ff4b0 ebp = 0x002ff4c4
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 9 xul.dll!nsCOMPtr_base::assign_from_gs_contractid(nsGetServiceByContractID,nsID const &) [nsCOMPtr.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 82 + 0x6]
15:21:06 INFO - eip = 0x55333570 esp = 0x002ff4cc ebp = 0x002ff4e0
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 10 xul.dll!nsCategoryObserver::nsCategoryObserver(nsTSubstring<char> const &) [nsCategoryCache.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 43 + 0x1a]
15:21:06 INFO - eip = 0x553b6363 esp = 0x002ff4e8 ebp = 0x002ff5c4
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 11 xul.dll!nsCategoryCache<mozIStorageVacuumParticipant>::LazyInit() [nsCategoryCache.h:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 98 + 0x8]
15:21:06 INFO - eip = 0x5606adb2 esp = 0x002ff5cc ebp = 0x002ff5d8
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 12 xul.dll!mozilla::storage::VacuumManager::Observe(nsISupports *,char const *,char16_t const *) [VacuumManager.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 332 + 0x1a]
15:21:06 INFO - eip = 0x5605e0ea esp = 0x002ff5e0 ebp = 0x002ff768
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 13 xul.dll!nsIdleServiceDaily::Observe(nsISupports *,char const *,char16_t const *) [nsIdleService.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 106 + 0xf]
15:21:06 INFO - eip = 0x5875dfdb esp = 0x002ff770 ebp = 0x002ff798
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 14 xul.dll!nsIdleService::IdleTimerCallback() [nsIdleService.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 732 + 0x10]
15:21:06 INFO - eip = 0x5875fc4a esp = 0x002ff7a0 ebp = 0x002ff8c0
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 15 xul.dll!nsTimerImpl::Fire(int) [nsTimerImpl.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 559 + 0xf]
15:21:06 INFO - eip = 0x553f64f3 esp = 0x002ff8c8 ebp = 0x002ff9ac
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 16 xul.dll!nsTimerEvent::Run() [TimerThread.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 260 + 0x8]
15:21:06 INFO - eip = 0x553d9fba esp = 0x002ff9b4 ebp = 0x002ff9f4
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 17 xul.dll!nsThread::ProcessNextEvent(bool,bool *) [nsThread.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 1157 + 0x6]
15:21:06 INFO - eip = 0x553e2853 esp = 0x002ff9fc ebp = 0x002ffae0
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 18 xul.dll!NS_ProcessNextEvent(nsIThread *,bool) [nsThreadUtils.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 468 + 0x11]
15:21:06 INFO - eip = 0x553e5ac4 esp = 0x002ffae8 ebp = 0x002ffb04
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 19 xul.dll!nsresult `anonymous namespace'::ParentImpl::ShutdownObserver::Observe(class nsISupports *, const char *, const char16_t *) [BackgroundImpl.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 1065 + 0x23a]
15:21:06 INFO - eip = 0x55a20088 esp = 0x002ffb0c ebp = 0x002ffb38
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 20 xul.dll!nsObserverList::NotifyObservers(nsISupports *,char const *,char16_t const *) [nsObserverList.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 66 + 0xb]
15:21:06 INFO - eip = 0x55366f47 esp = 0x002ffb40 ebp = 0x002ffb64
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 21 xul.dll!nsObserverService::NotifyObservers(nsISupports *,char const *,char16_t const *) [nsObserverService.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 286 + 0xe]
15:21:06 INFO - eip = 0x553683fe esp = 0x002ffb6c ebp = 0x002ffbe0
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 22 xul.dll!mozilla::ShutdownXPCOM(nsIServiceManager *) [XPCOMInit.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 824 + 0xf]
15:21:06 INFO - eip = 0x5541a069 esp = 0x002ffbe8 ebp = 0x002ffc24
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 23 xul.dll!XRE_XPCShellMain(int,char * *,char * *,XREShellData const *) [XPCShellImpl.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 1411 + 0x7]
15:21:06 INFO - eip = 0x56002979 esp = 0x002ffc2c ebp = 0x002ffe8c
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 24 xul.dll!mozilla::BootstrapImpl::XRE_XPCShellMain(int,char * *,char * *,XREShellData const *) [Bootstrap.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 48 + 0x11]
15:21:06 INFO - eip = 0x59e2a874 esp = 0x002ffe94 ebp = 0x002ffea4
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 25 xpcshell.exe!NS_internal_main(int,char * *,char * *) [xpcshell.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 65 + 0x12]
15:21:06 INFO - eip = 0x01341287 esp = 0x002ffeac ebp = 0x002ffecc
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 26 xpcshell.exe!wmain [nsWindowsWMain.cpp:aa6760d280fb38a53f28eac8e1c395267c7d69d7 : 129 + 0xf]
15:21:06 INFO - eip = 0x01341190 esp = 0x002ffed4 ebp = 0x002ffef8
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 27 xpcshell.exe!static int __scrt_common_main_seh() [exe_common.inl : 288 + 0x1c]
15:21:06 INFO - eip = 0x013965f4 esp = 0x002fff00 ebp = 0x002fff40
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 28 kernel32.dll!BaseThreadInitThunk + 0x12
15:21:06 INFO - eip = 0x76233c45 esp = 0x002fff48 ebp = 0x002fff4c
15:21:06 INFO - Found by: previous frame's frame pointer
15:21:06 INFO - 29 ntdll.dll!CreatePipe + 0x231
15:21:06 INFO - eip = 0x77b337f5 esp = 0x002fff54 ebp = 0x002fff8c
15:21:06 INFO - Found by: call frame info
15:21:06 INFO - 30 ntdll.dll!CreatePipe + 0x204
15:21:06 INFO - eip = 0x77b337c8 esp = 0x002fff94 ebp = 0x002fffa4
15:21:06 INFO - Found by: call frame info

Status: RESOLVED → REOPENED
Resolution: FIXED → ---
Status: REOPENED → RESOLVED
Closed: Last year8 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.