1075025 - Use Android's Choreographer to coordinate vsync intervals

Status: RESOLVED FIXED

(Firefox for Android Graveyard :: Toolbar, defect)

Description

[James Willcox (:snorp) ([e-mail]) (he/him)]

As I understand it, this would allow us to accomplish something similar to what Project Silk is attempting for Firefox OS, by syncing our composition to the vsync.

http://developer.android.com/reference/android/view/Choreographer.html

Comment 1

[John Lin [:jhlin][:jolin]]

The plan is to develop an AndroidVsyncSource class and create one in gfxAndroidPlatform::CreateHardwareVsyncSource().

Comment 2

[John Lin [:jhlin][:jolin]]

Attachment: Bug 1075025 - part 1: receive vsync events through Choreographer.

Review commit: https://reviewboard.mozilla.org/r/46677/diff/#index_header
See other reviews: https://reviewboard.mozilla.org/r/46677/

Comment 3

[John Lin [:jhlin][:jolin]]

Attachment: MozReview Request: Bug 1075025 - part 2: implement AndroidVsyncSource. r?snorp

Review commit: https://reviewboard.mozilla.org/r/46679/diff/#index_header
See other reviews: https://reviewboard.mozilla.org/r/46679/

Comment 4

[Kartikaya Gupta (email:[e-mail])]

https://reviewboard.mozilla.org/r/46679/#review43299

Some drive-by comments

::: gfx/thebes/gfxAndroidPlatform.cpp:445
(Diff revision 1)
> +        void EnableVsync() final
> +        {
> +            MOZ_ASSERT(NS_IsMainThread());
> +            if (!mObservingVsync) {
> +                mozilla::widget::GeckoAppShell::ObserveVsync(true);
> +                mObservingVsync = true;

this could get out of sync with the java-side state, if ObserverSync takes the early-exit path. You might want to return true/false from that function as to what the current java state is, and use that to set mObservingVsync.

::: gfx/thebes/gfxAndroidPlatform.cpp:496
(Diff revision 1)
>          NS_WARNING("Error enabling gonk vsync. Falling back to software vsync");
>          return gfxPlatform::CreateHardwareVsyncSource();
>      }
>      display.DisableVsync();
>      return vsyncSource.forget();
>  #else

This should probably be an #elif defined(MOZ_WIDGET_ANDROID)

Comment 5

[John Lin [:jhlin][:jolin]]

https://reviewboard.mozilla.org/r/46679/#review43299

Thanks for the comments. Will update the patches to address them.

Comment 6

[James Willcox (:snorp) ([e-mail]) (he/him)]

Comment on attachment 8741656 [details]
Bug 1075025 - part 1: receive vsync events through Choreographer.

https://reviewboard.mozilla.org/r/46677/#review43667

Needs some iteration, but thanks for working on this. What do you expect the main benefits to be? When I've brought this up before, it was assumed that touch events and compositions are basically already aligned.

::: mobile/android/base/java/org/mozilla/gecko/GeckoAppShell.java:272
(Diff revision 1)
>      private static LayerView sLayerView;
>      private static Rect sScreenSize;
>  
> +    // Don't need dedicate Thread/Looper/Choreographer: View.postOnAnimation()
> +    // uses an existing Choreographer in the Android UI thread.
> +    public static final boolean SUPPORT_VSYNC =

We're trying to not add more junk to GeckoAppShell. Please put all of this in some new object (VSyncSupport, maybe?) or possibly just in GeckoView

::: mobile/android/base/java/org/mozilla/gecko/GeckoAppShell.java:313
(Diff revision 1)
> +    }
> +
> +    @WrapForJNI
> +    public static float getRefreshRate() {
> +        if (sLayerView != null && sLayerView.isAttachedToWindow()) {
> +            return sLayerView.getDisplay().getRefreshRate();

What's the reason for having this path at all? Maybe it's running on a different display? I would prefer this go through GeckoView somehow if that's the case.

Comment 7

[James Willcox (:snorp) ([e-mail]) (he/him)]

Comment on attachment 8741657 [details]
MozReview Request: Bug 1075025 - part 2: implement AndroidVsyncSource. r?snorp

https://reviewboard.mozilla.org/r/46679/#review43671

This seems mostly ok to me, but I think Vlad has been rewriting the vsync stuff to be widget-centric. You should probably talk to him if you haven't already, and he should review these patches.

::: gfx/thebes/gfxAndroidPlatform.cpp:497
(Diff revision 1)
>          return gfxPlatform::CreateHardwareVsyncSource();
>      }
>      display.DisableVsync();
>      return vsyncSource.forget();
>  #else
> +    if (mozilla::widget::GeckoAppShell::IsVsyncSupported()) {

The comment above for b2g says hw vsync is inaccurate on JB, but your prior patch enables it for >= JB. Should probably do the same, no?

Comment 8

[John Lin [:jhlin][:jolin]]

Comment on attachment 8741656 [details]
Bug 1075025 - part 1: receive vsync events through Choreographer.

Review request updated; see interdiff: https://reviewboard.mozilla.org/r/46677/diff/1-2/

Comment 9

[John Lin [:jhlin][:jolin]]

Comment on attachment 8741657 [details]
MozReview Request: Bug 1075025 - part 2: implement AndroidVsyncSource. r?snorp

Review request updated; see interdiff: https://reviewboard.mozilla.org/r/46679/diff/1-2/

Comment 10

[John Lin [:jhlin][:jolin]]

Cancel review requests that didn't address James' comments.

Comment 11

[John Lin [:jhlin][:jolin]]

The interface & implementation of vsync source will be changed in bug 1184283. Will hold until it's done.

Comment 12

[John Lin [:jhlin][:jolin]]

Comment on attachment 8741656 [details]
Bug 1075025 - part 1: receive vsync events through Choreographer.

Review request updated; see interdiff: https://reviewboard.mozilla.org/r/46677/diff/2-3/

Comment 13

[John Lin [:jhlin][:jolin]]

Looks like bug 1184283 will take some more time. The 1st part patch doesn't depend on it so upload the revision to move forward.

Comment 14

[James Willcox (:snorp) ([e-mail]) (he/him)]

Comment on attachment 8741656 [details]
Bug 1075025 - part 1: receive vsync events through Choreographer.

https://reviewboard.mozilla.org/r/46677/#review48773

I think this will be fine if we stop using LayerView

::: mobile/android/base/java/org/mozilla/gecko/gfx/VsyncSource.java:37
(Diff revision 3)
> +        if (!SUPPORT_VSYNC) {
> +            Log.w(LOGTAG, "HW vsync avaiable only for KK and later.");
> +            return null;
> +        }
> +
> +        View v = GeckoAppShell.getLayerView();

Hopefully in the short/medium future we will not have a singleton LayerView, so please don't add new code that assumes we do. I think we should just use Choreographer directly and not involve any View stuff at all.

Comment 15

[John Lin [:jhlin][:jolin]]

Comment on attachment 8741656 [details]
Bug 1075025 - part 1: receive vsync events through Choreographer.

Review request updated; see interdiff: https://reviewboard.mozilla.org/r/46677/diff/3-4/

Comment 16

[James Willcox (:snorp) ([e-mail]) (he/him)]

Comment on attachment 8741656 [details]
Bug 1075025 - part 1: receive vsync events through Choreographer.

https://reviewboard.mozilla.org/r/46677/#review49431

::: mobile/android/base/java/org/mozilla/gecko/gfx/VsyncSource.java:35
(Diff revision 4)
> +    private static CountDownLatch sInitLatch = new CountDownLatch(1);
> +
> +    private volatile boolean mObservingVsync;
> +
> +    @WrapForJNI
> +    public static synchronized VsyncSource createInstance() {

I think I would expect a 'getInstance()' here that returns a cached singleton instead, but maybe you have a good reason for not doing that. I don't think you would want multiple instances calling nativeNotifyVsync() though.

Comment 17

[John Lin [:jhlin][:jolin]]

https://reviewboard.mozilla.org/r/46677/#review49431

> I think I would expect a 'getInstance()' here that returns a cached singleton instead, but maybe you have a good reason for not doing that. I don't think you would want multiple instances calling nativeNotifyVsync() though.

It's meant for supporting mulitple vsync sources in the future, but I agree that the risk of misuse is not worth it. Will change it to getInstance() as suggested. Thanks a lot.

Comment 18

[John Lin [:jhlin][:jolin]]

Comment on attachment 8741656 [details]
Bug 1075025 - part 1: receive vsync events through Choreographer.

Review request updated; see interdiff: https://reviewboard.mozilla.org/r/46677/diff/4-5/

Comment 19

[John Lin [:jhlin][:jolin]]

Looks like bug 1184283 is not going anywhere anytime soon. Will upload my patch again and start another round of review later today.

Comment 20

[John Lin [:jhlin][:jolin]]

Attachment: Bug 1075025 - part 2: implement AndroidVsyncSource.

Review commit: https://reviewboard.mozilla.org/r/99708/diff/#index_header
See other reviews: https://reviewboard.mozilla.org/r/99708/

Comment 21

[John Lin [:jhlin][:jolin]]

Comment on attachment 8741656 [details]
Bug 1075025 - part 1: receive vsync events through Choreographer.

Review request updated; see interdiff: https://reviewboard.mozilla.org/r/46677/diff/5-6/

Comment 22

[John Lin [:jhlin][:jolin]]

Comment on attachment 8820173 [details]
Bug 1075025 - part 2: implement AndroidVsyncSource.

Review request updated; see interdiff: https://reviewboard.mozilla.org/r/99708/diff/1-2/

Comment 23

[James Willcox (:snorp) ([e-mail]) (he/him)]

Comment on attachment 8820173 [details]
Bug 1075025 - part 2: implement AndroidVsyncSource.

https://reviewboard.mozilla.org/r/99708/#review100262

Comment 24

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/b9d69795947d
part 1: receive vsync events through Choreographer. r=snorp
https://hg.mozilla.org/integration/autoland/rev/14fcdf7cc920
part 2: implement AndroidVsyncSource. r=snorp

Comment 25

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

Backed out for crashing in xpcshell test test_setUsePrivateBrowsing.js and test_ext_alarms.js on Android:

https://hg.mozilla.org/integration/autoland/rev/2a87677a4113644cdb38866af9c92611ce0e7052
https://hg.mozilla.org/integration/autoland/rev/2e4489f166fd162139cf4c0745a37a6d7969233a

Push with failures: https://treeherder.mozilla.org/#/jobs?repo=autoland&revision=14fcdf7cc92060c1b1c9fd2b581b4857252c1fd6
Failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=8231706&repo=autoland

[task 2016-12-21T06:52:51.449350Z] 06:52:51     INFO -  TEST-START | docshell/test/unit/test_setUsePrivateBrowsing.js
[task 2016-12-21T06:52:56.338767Z] 06:52:56  WARNING -  TEST-UNEXPECTED-FAIL | docshell/test/unit/test_setUsePrivateBrowsing.js | xpcshell return code: 139
[task 2016-12-21T06:52:56.339372Z] 06:52:56     INFO -  TEST-INFO took 4890ms
[task 2016-12-21T06:52:56.339538Z] 06:52:56     INFO -  >>>>>>>
[task 2016-12-21T06:52:56.340090Z] 06:52:56     INFO -  PROCESS | docshell/test/unit/test_setUsePrivateBrowsing.js | xpcw: cd /storage/sdcard/tests/xpc/docshell/test/unit
[task 2016-12-21T06:52:56.343675Z] 06:52:56     INFO -  PROCESS | docshell/test/unit/test_setUsePrivateBrowsing.js | xpcw: xpcshell -r /storage/sdcard/tests/xpc/c/httpd.manifest --greomni /data/local/xpcb/target.apk -m -s -e const _HEAD_JS_PATH = "/storage/sdcard/tests/xpc/head.js"; -e const _MOZINFO_JS_PATH = "/storage/sdcard/tests/xpc/p/mozinfo.json"; -e const _TESTING_MODULES_DIR = "/storage/sdcard/tests/xpc/m"; -f /storage/sdcard/tests/xpc/head.js -e const _SERVER_ADDR = "localhost" -e const _HEAD_FILES = ["/storage/sdcard/tests/xpc/docshell/test/unit/head_docshell.js"]; -e const _TAIL_FILES = []; -e const _JSDEBUGGER_PORT = 0; -e const _TEST_FILE = ["test_setUsePrivateBrowsing.js"]; -e const _TEST_NAME = "docshell/test/unit/test_setUsePrivateBrowsing.js" -e _execute_test(); quit(0);
[task 2016-12-21T06:52:56.343792Z] 06:52:56     INFO -  PROCESS | docshell/test/unit/test_setUsePrivateBrowsing.js | [2322] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /home/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 172
[task 2016-12-21T06:52:56.343870Z] 06:52:56     INFO -  PROCESS | docshell/test/unit/test_setUsePrivateBrowsing.js | Warning: MOZILLA_FIVE_HOME not set.
[task 2016-12-21T06:52:56.343989Z] 06:52:56     INFO -  PROCESS | docshell/test/unit/test_setUsePrivateBrowsing.js | [2322] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /home/worker/workspace/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 2861
[task 2016-12-21T06:52:56.344043Z] 06:52:56     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2016-12-21T06:52:56.344101Z] 06:52:56     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2016-12-21T06:52:56.344157Z] 06:52:56     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2016-12-21T06:52:56.344197Z] 06:52:56     INFO -  running event loop
[task 2016-12-21T06:52:56.344242Z] 06:52:56     INFO -  docshell/test/unit/test_setUsePrivateBrowsing.js | Starting
[task 2016-12-21T06:52:56.344288Z] 06:52:56     INFO -  (xpcshell/head.js) | test pending (2)
[task 2016-12-21T06:52:56.344349Z] 06:52:56     INFO -  PROCESS | docshell/test/unit/test_setUsePrivateBrowsing.js | Segmentation fault
[task 2016-12-21T06:52:56.344406Z] 06:52:56     INFO -  PROCESS | docshell/test/unit/test_setUsePrivateBrowsing.js | 13
[task 2016-12-21T06:52:56.344541Z] 06:52:56     INFO -  <<<<<<<
[task 2016-12-21T06:52:56.829176Z] 06:52:56     INFO -  mozcrash Copy/paste: /usr/local/bin/linux64-minidump_stackwalk /tmp/tmpkv_rjx/5078281f-cc54-85d8-4ff03910-6934d51a.dmp /home/worker/workspace/build/symbols
[task 2016-12-21T06:53:02.540414Z] 06:53:02     INFO -  mozcrash Saved minidump as /home/worker/workspace/build/blobber_upload_dir/5078281f-cc54-85d8-4ff03910-6934d51a.dmp
[task 2016-12-21T06:53:02.542659Z] 06:53:02     INFO -  mozcrash Saved app info as /home/worker/workspace/build/blobber_upload_dir/5078281f-cc54-85d8-4ff03910-6934d51a.extra
[task 2016-12-21T06:53:02.542748Z] 06:53:02  WARNING -  PROCESS-CRASH | docshell/test/unit/test_setUsePrivateBrowsing.js | application crashed [@ mozilla::jni::GetEnvForThread]
[task 2016-12-21T06:53:02.542809Z] 06:53:02     INFO -  Crash dump filename: /tmp/tmpkv_rjx/5078281f-cc54-85d8-4ff03910-6934d51a.dmp
[task 2016-12-21T06:53:02.542851Z] 06:53:02     INFO -  Operating system: Android
[task 2016-12-21T06:53:02.542906Z] 06:53:02     INFO -                    0.0.0 Linux 2.6.29-gea477bb #1 Wed Sep 26 11:04:45 PDT 2012 armv7l
[task 2016-12-21T06:53:02.542943Z] 06:53:02     INFO -  CPU: arm
[task 2016-12-21T06:53:02.542997Z] 06:53:02     INFO -       ARMv7 ARM Cortex-A8 features: swp,half,thumb,fastmult,vfpv2,edsp,neon,vfpv3
[task 2016-12-21T06:53:02.543033Z] 06:53:02     INFO -       1 CPU
[task 2016-12-21T06:53:02.543068Z] 06:53:02     INFO -  GPU: UNKNOWN
[task 2016-12-21T06:53:02.543114Z] 06:53:02     INFO -  Crash reason:  SIGSEGV
[task 2016-12-21T06:53:02.543286Z] 06:53:02     INFO -  Crash address: 0x0
[task 2016-12-21T06:53:02.543586Z] 06:53:02     INFO -  Process uptime: not available
[task 2016-12-21T06:53:02.547385Z] 06:53:02     INFO -  Thread 0 (crashed)
[task 2016-12-21T06:53:02.547452Z] 06:53:02     INFO -   0  libxul.so!mozilla::jni::GetEnvForThread [Utils.cpp:14fcdf7cc920 : 146 + 0x0]
[task 2016-12-21T06:53:02.547515Z] 06:53:02     INFO -       r0 = 0x00000076    r1 = 0xb1583a7b    r2 = 0x43130ccc    r3 = 0x4024710c
[task 2016-12-21T06:53:02.547597Z] 06:53:02     INFO -       r4 = 0x00000000    r5 = 0x4397be64    r6 = 0x450b6f60    r7 = 0x02051e42
[task 2016-12-21T06:53:02.547691Z] 06:53:02     INFO -       r8 = 0x00000000    r9 = 0xbe809468   r10 = 0xbe80945c   r12 = 0x00000003
[task 2016-12-21T06:53:02.547753Z] 06:53:02     INFO -       fp = 0xbe809460    sp = 0xbe8093d0    lr = 0x4199cf07    pc = 0x4199cfca
[task 2016-12-21T06:53:02.547811Z] 06:53:02     INFO -      Found by: given as instruction pointer in context
[task 2016-12-21T06:53:02.547882Z] 06:53:02     INFO -   1  libxul.so!mozilla::java::VsyncSource::IsVsyncSupported [Refs.h:14fcdf7cc920 : 69 + 0x3]
[task 2016-12-21T06:53:02.547945Z] 06:53:02     INFO -       r4 = 0x450b6f60    r5 = 0xbe80946c    r6 = 0x450b6f60    r7 = 0x02051e42
[task 2016-12-21T06:53:02.548026Z] 06:53:02     INFO -       r8 = 0x00000000    r9 = 0xbe809468   r10 = 0xbe80945c    fp = 0xbe809460
[task 2016-12-21T06:53:02.548084Z] 06:53:02     INFO -       sp = 0xbe8093f8    pc = 0x41973bff
[task 2016-12-21T06:53:02.548135Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.548203Z] 06:53:02     INFO -   2  libxul.so!gfxAndroidPlatform::CreateHardwareVsyncSource [gfxAndroidPlatform.cpp:14fcdf7cc920 : 424 + 0x3]
[task 2016-12-21T06:53:02.548269Z] 06:53:02     INFO -       r4 = 0x450b6f60    r5 = 0xbe80946c    r6 = 0x450b6f60    r7 = 0x00000000
[task 2016-12-21T06:53:02.548332Z] 06:53:02     INFO -       r8 = 0x00000000    r9 = 0xbe809468   r10 = 0xbe80945c    fp = 0xbe809460
[task 2016-12-21T06:53:02.548386Z] 06:53:02     INFO -       sp = 0xbe809420    pc = 0x40d5991b
[task 2016-12-21T06:53:02.548439Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.548515Z] 06:53:02     INFO -   3  libxul.so!gfxPlatform::Init [gfxPlatform.cpp:14fcdf7cc920 : 783 + 0x11]
[task 2016-12-21T06:53:02.548617Z] 06:53:02     INFO -       r4 = 0x4397be64    r5 = 0xbe80946c    r6 = 0x450b6f60    r7 = 0x00000000
[task 2016-12-21T06:53:02.548683Z] 06:53:02     INFO -       r8 = 0x00000000    r9 = 0xbe809468   r10 = 0xbe80945c    fp = 0xbe809460
[task 2016-12-21T06:53:02.548732Z] 06:53:02     INFO -       sp = 0xbe809438    pc = 0x40d64eeb
[task 2016-12-21T06:53:02.548782Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.548849Z] 06:53:02     INFO -   4  libxul.so!gfxPlatform::GetPlatform [gfxPlatform.cpp:14fcdf7cc920 : 534 + 0x3]
[task 2016-12-21T06:53:02.548914Z] 06:53:02     INFO -       r4 = 0x4803a800    r5 = 0x00000000    r6 = 0x00000001    r7 = 0x00000000
[task 2016-12-21T06:53:02.549082Z] 06:53:02     INFO -       r8 = 0xbe809518    r9 = 0x00000000   r10 = 0xbe809588    fp = 0x4506887c
[task 2016-12-21T06:53:02.549311Z] 06:53:02     INFO -       sp = 0xbe8094e8    pc = 0x40d6518b
[task 2016-12-21T06:53:02.549651Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.549990Z] 06:53:02     INFO -   5  libxul.so!mozilla::widget::PuppetWidget::InfallibleCreate [PuppetWidget.cpp:14fcdf7cc920 : 115 + 0x3]
[task 2016-12-21T06:53:02.550190Z] 06:53:02     INFO -       r3 = 0x00000000    r4 = 0x4803a800    r5 = 0x00000000    r6 = 0x00000001
[task 2016-12-21T06:53:02.550525Z] 06:53:02     INFO -       r7 = 0x00000000    r8 = 0xbe809518    r9 = 0x00000000   r10 = 0xbe809588
[task 2016-12-21T06:53:02.550860Z] 06:53:02     INFO -       fp = 0x4506887c    sp = 0xbe8094f0    pc = 0x41959cc9
[task 2016-12-21T06:53:02.551167Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.551536Z] 06:53:02     INFO -   6  libxul.so!mozilla::widget::PuppetWidget::Create [PuppetWidget.cpp:14fcdf7cc920 : 141 + 0x7]
[task 2016-12-21T06:53:02.551869Z] 06:53:02     INFO -       r4 = 0x00000000    r5 = 0x48042980    r6 = 0xbe809584    r7 = 0x4397be64
[task 2016-12-21T06:53:02.552201Z] 06:53:02     INFO -       r8 = 0xbe809574    r9 = 0x00000000   r10 = 0xbe809588    fp = 0x4506887c
[task 2016-12-21T06:53:02.552528Z] 06:53:02     INFO -       sp = 0xbe809540    pc = 0x41959e03
[task 2016-12-21T06:53:02.552854Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.553770Z] 06:53:02     INFO -   7  libxul.so!nsAppShellService::CreateWindowlessBrowser [nsAppShellService.cpp:14fcdf7cc920 : 531 + 0x5]
[task 2016-12-21T06:53:02.553847Z] 06:53:02     INFO -       r4 = 0x41959df9    r5 = 0x48042980    r6 = 0xbe809584    r7 = 0x4397be64
[task 2016-12-21T06:53:02.553915Z] 06:53:02     INFO -       r8 = 0xbe809574    r9 = 0x00000000   r10 = 0xbe809588    fp = 0x4506887c
[task 2016-12-21T06:53:02.553966Z] 06:53:02     INFO -       sp = 0xbe809550    pc = 0x41dda557
[task 2016-12-21T06:53:02.554015Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.554255Z] 06:53:02     INFO -   8  libxul.so!NS_InvokeByIndex [xptcinvoke_arm.cpp:14fcdf7cc920 : 169 + 0x13]
[task 2016-12-21T06:53:02.554461Z] 06:53:02     INFO -       r4 = 0xbe8095d0    r5 = 0x41dda391    r6 = 0x00000004    r7 = 0xbe8095d8
[task 2016-12-21T06:53:02.554703Z] 06:53:02     INFO -       r8 = 0x00000000    r9 = 0x00000002   r10 = 0xbe809714    fp = 0x47d92558
[task 2016-12-21T06:53:02.555098Z] 06:53:02     INFO -       sp = 0xbe8095c0    pc = 0x406e4867
[task 2016-12-21T06:53:02.555200Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.555433Z] 06:53:02     INFO -   9  libxul.so!CallMethodHelper::Call [XPCWrappedNative.cpp:14fcdf7cc920 : 2058 + 0xd]
[task 2016-12-21T06:53:02.555679Z] 06:53:02     INFO -       r3 = 0xbe809650    r4 = 0xbe809620    r5 = 0x00000002    r6 = 0xbe8095f8
[task 2016-12-21T06:53:02.555878Z] 06:53:02     INFO -       r7 = 0x00000002    r8 = 0x00000000    r9 = 0x00000002   r10 = 0xbe809714
[task 2016-12-21T06:53:02.556095Z] 06:53:02     INFO -       fp = 0x47d92558    sp = 0xbe8095f0    pc = 0x40b11443
[task 2016-12-21T06:53:02.556348Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.556679Z] 06:53:02     INFO -  10  libxul.so!XPCWrappedNative::CallMethod [XPCWrappedNative.cpp:14fcdf7cc920 : 1344 + 0x5]
[task 2016-12-21T06:53:02.556802Z] 06:53:02     INFO -       r4 = 0xbe809740    r5 = 0x00000000    r6 = 0xbe809710    r7 = 0xbe809648
[task 2016-12-21T06:53:02.557030Z] 06:53:02     INFO -       r8 = 0xbe809640    r9 = 0xbe809724   r10 = 0xbe809714    fp = 0x47d92558
[task 2016-12-21T06:53:02.557267Z] 06:53:02     INFO -       sp = 0xbe809620    pc = 0x40b11539
[task 2016-12-21T06:53:02.557461Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.557724Z] 06:53:02     INFO -  11  libxul.so!XPC_WN_CallMethod [XPCWrappedNativeJSOps.cpp:14fcdf7cc920 : 999 + 0x7]
[task 2016-12-21T06:53:02.557943Z] 06:53:02     INFO -       r4 = 0x00000001    r5 = 0xbe809740    r6 = 0xbe809710    r7 = 0x00000000
[task 2016-12-21T06:53:02.558189Z] 06:53:02     INFO -       r8 = 0xbe809718    r9 = 0xbe809724   r10 = 0xbe809714    fp = 0x47d92558
[task 2016-12-21T06:53:02.558412Z] 06:53:02     INFO -       sp = 0xbe8096f8    pc = 0x40b15d6f
[task 2016-12-21T06:53:02.558594Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.558817Z] 06:53:02     INFO -  12  libxul.so!js::CallJSNative [jscntxtinlines.h:14fcdf7cc920 : 239 + 0x3]
[task 2016-12-21T06:53:02.559006Z] 06:53:02     INFO -       r4 = 0x47d92560    r5 = 0x4742b000    r6 = 0xbe809a38    r7 = 0x00000000
[task 2016-12-21T06:53:02.559253Z] 06:53:02     INFO -       r8 = 0x4397be64    r9 = 0xbe8097c4   r10 = 0x40b15c69    fp = 0x47d92558
[task 2016-12-21T06:53:02.559399Z] 06:53:02     INFO -       sp = 0xbe8097b8    pc = 0x427d5f07
[task 2016-12-21T06:53:02.559691Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.559954Z] 06:53:02     INFO -  13  libxul.so!js::InternalCallOrConstruct [Interpreter.cpp:14fcdf7cc920 : 457 + 0xb]
[task 2016-12-21T06:53:02.560205Z] 06:53:02     INFO -       r4 = 0xbe809a38    r5 = 0x4742b000    r6 = 0x4397be64    r7 = 0x00000000
[task 2016-12-21T06:53:02.560459Z] 06:53:02     INFO -       r8 = 0x00000000    r9 = 0xbe8097fc   r10 = 0xbe809970    fp = 0x4397be64
[task 2016-12-21T06:53:02.560661Z] 06:53:02     INFO -       sp = 0xbe8097f0    pc = 0x42805b13
[task 2016-12-21T06:53:02.560916Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.561156Z] 06:53:02     INFO -  14  libxul.so!Interpret [Interpreter.cpp:14fcdf7cc920 : 508 + 0x7]
[task 2016-12-21T06:53:02.561400Z] 06:53:02     INFO -       r4 = 0x4742b000    r5 = 0x00000000    r6 = 0xbe809b70    r7 = 0x47d92548
[task 2016-12-21T06:53:02.561598Z] 06:53:02     INFO -       r8 = 0x00000001    r9 = 0xbe809a38   r10 = 0xbe809970    fp = 0x4397be64
[task 2016-12-21T06:53:02.561827Z] 06:53:02     INFO -       sp = 0xbe809848    pc = 0x427fafcd
[task 2016-12-21T06:53:02.562064Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.562319Z] 06:53:02     INFO -  15  libxul.so!js::RunScript [Interpreter.cpp:14fcdf7cc920 : 403 + 0x7]
[task 2016-12-21T06:53:02.562524Z] 06:53:02     INFO -       r4 = 0x4742b000    r5 = 0xbe809b70    r6 = 0xbe809b2c    r7 = 0xc0000000
[task 2016-12-21T06:53:02.562778Z] 06:53:02     INFO -       r8 = 0x47f571c0    r9 = 0xbe809b64   r10 = 0xbe809be0    fp = 0x438e23c4
[task 2016-12-21T06:53:02.563002Z] 06:53:02     INFO -       sp = 0xbe809b20    pc = 0x42805913
[task 2016-12-21T06:53:02.563184Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.563464Z] 06:53:02     INFO -  16  libxul.so!js::InternalCallOrConstruct [Interpreter.cpp:14fcdf7cc920 : 475 + 0x7]
[task 2016-12-21T06:53:02.563669Z] 06:53:02     INFO -       r4 = 0xbe809c40    r5 = 0x4742b000    r6 = 0x4397be64    r7 = 0x00000000
[task 2016-12-21T06:53:02.563915Z] 06:53:02     INFO -       r8 = 0xbe809b70    r9 = 0xbe809b64   r10 = 0xbe809be0    fp = 0x438e23c4
[task 2016-12-21T06:53:02.564135Z] 06:53:02     INFO -       sp = 0xbe809b58    pc = 0x42805c2f
[task 2016-12-21T06:53:02.564315Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.564547Z] 06:53:02     INFO -  17  libxul.so!js::Call [Interpreter.cpp:14fcdf7cc920 : 521 + 0x3]
[task 2016-12-21T06:53:02.564844Z] 06:53:02     INFO -       r4 = 0xbe809c40    r5 = 0xbe809d88    r6 = 0x44ec9680    r7 = 0xffffff8c
[task 2016-12-21T06:53:02.565037Z] 06:53:02     INFO -       r8 = 0xbe809c50    r9 = 0x00000001   r10 = 0xbe809be0    fp = 0x438e23c4
[task 2016-12-21T06:53:02.565296Z] 06:53:02     INFO -       sp = 0xbe809bb0    pc = 0x42805eaf
[task 2016-12-21T06:53:02.565453Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.565679Z] 06:53:02     INFO -  18  libxul.so!js::Wrapper::call [Wrapper.cpp:14fcdf7cc920 : 165 + 0x3]
[task 2016-12-21T06:53:02.566182Z] 06:53:02     INFO -       r4 = 0x00000001    r5 = 0xbe809d88    r6 = 0x4397be64    r7 = 0xbe809bd0
[task 2016-12-21T06:53:02.566239Z] 06:53:02     INFO -       r8 = 0xbe809c50    r9 = 0x00000001   r10 = 0xbe809be0    fp = 0x438e23c4
[task 2016-12-21T06:53:02.566309Z] 06:53:02     INFO -       sp = 0xbe809bc0    pc = 0x42760d2f
[task 2016-12-21T06:53:02.566798Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.566854Z] 06:53:02     INFO -  19  libxul.so!js::CrossCompartmentWrapper::call [CrossCompartmentWrapper.cpp:14fcdf7cc920 : 333 + 0xb]
[task 2016-12-21T06:53:02.566918Z] 06:53:02     INFO -       r4 = 0x4742b000    r5 = 0x00000001    r6 = 0xbe809d88    r7 = 0x00000000
[task 2016-12-21T06:53:02.566975Z] 06:53:02     INFO -       r8 = 0xbe809ce4    r9 = 0xbe809d84   r10 = 0x47b5c800    fp = 0x438e23c4
[task 2016-12-21T06:53:02.567054Z] 06:53:02     INFO -       sp = 0xbe809ce0    pc = 0x42749c3d
[task 2016-12-21T06:53:02.567221Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.567636Z] 06:53:02     INFO -  20  libxul.so!js::Proxy::call [Proxy.cpp:14fcdf7cc920 : 400 + 0x11]
[task 2016-12-21T06:53:02.567774Z] 06:53:02     INFO -       r4 = 0x42749b35    r5 = 0xbe809d20    r6 = 0xbe809d84    r7 = 0xbe809d88
[task 2016-12-21T06:53:02.567958Z] 06:53:02     INFO -       r8 = 0x438e23c4    r9 = 0x00000000   r10 = 0x439e2a94    fp = 0x47d92470
[task 2016-12-21T06:53:02.568321Z] 06:53:02     INFO -       sp = 0xbe809d18    pc = 0x42742c19
[task 2016-12-21T06:53:02.568438Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.568635Z] 06:53:02     INFO -  21  libxul.so!js::proxy_Call [Proxy.cpp:14fcdf7cc920 : 689 + 0x9]
[task 2016-12-21T06:53:02.568885Z] 06:53:02     INFO -       r4 = 0x00100000    r5 = 0x4397be64    r6 = 0x4742b000    r7 = 0xbe809d88
[task 2016-12-21T06:53:02.569199Z] 06:53:02     INFO -       r8 = 0xbe809d7c    r9 = 0xbe809dbc   r10 = 0x42743769    fp = 0x47d92470
[task 2016-12-21T06:53:02.569486Z] 06:53:02     INFO -       sp = 0xbe809d78    pc = 0x427437db
[task 2016-12-21T06:53:02.569797Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.570071Z] 06:53:02     INFO -  22  libxul.so!js::CallJSNative [jscntxtinlines.h:14fcdf7cc920 : 239 + 0x3]
[task 2016-12-21T06:53:02.570351Z] 06:53:02     INFO -       r4 = 0x47d92478    r5 = 0x4742b000    r6 = 0xbe80a030    r7 = 0x00000000
[task 2016-12-21T06:53:02.570728Z] 06:53:02     INFO -       r8 = 0x4397be64    r9 = 0xbe809dbc   r10 = 0x42743769    fp = 0x47d92470
[task 2016-12-21T06:53:02.570816Z] 06:53:02     INFO -       sp = 0xbe809db0    pc = 0x427d5f07
[task 2016-12-21T06:53:02.571048Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.571268Z] 06:53:02     INFO -  23  libxul.so!js::InternalCallOrConstruct [Interpreter.cpp:14fcdf7cc920 : 445 + 0x9]
[task 2016-12-21T06:53:02.571537Z] 06:53:02     INFO -       r4 = 0xbe80a030    r5 = 0x4742b000    r6 = 0x4397be64    r7 = 0x00000000
[task 2016-12-21T06:53:02.571745Z] 06:53:02     INFO -       r8 = 0x00000000    r9 = 0x438e0090   r10 = 0xbe809f68    fp = 0x4397be64
[task 2016-12-21T06:53:02.572049Z] 06:53:02     INFO -       sp = 0xbe809de8    pc = 0x42805c8b
[task 2016-12-21T06:53:02.572304Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.572497Z] 06:53:02     INFO -  24  libxul.so!Interpret [Interpreter.cpp:14fcdf7cc920 : 508 + 0x7]
[task 2016-12-21T06:53:02.572727Z] 06:53:02     INFO -       r4 = 0x4742b000    r5 = 0x00000000    r6 = 0xbe80a168    r7 = 0x00000000
[task 2016-12-21T06:53:02.573047Z] 06:53:02     INFO -       r8 = 0x00000001    r9 = 0xbe80a030   r10 = 0xbe809f68    fp = 0x4397be64
[task 2016-12-21T06:53:02.573522Z] 06:53:02     INFO -       sp = 0xbe809e40    pc = 0x427fafcd
[task 2016-12-21T06:53:02.573799Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.573858Z] 06:53:02     INFO -  25  libxul.so!js::RunScript [Interpreter.cpp:14fcdf7cc920 : 403 + 0x7]
[task 2016-12-21T06:53:02.573925Z] 06:53:02     INFO -       r4 = 0x4742b000    r5 = 0xbe80a168    r6 = 0xbe80a124    r7 = 0xc0000000
[task 2016-12-21T06:53:02.573976Z] 06:53:02     INFO -       r8 = 0x47f52220    r9 = 0xbe80a15c   r10 = 0x00000001    fp = 0x47d92300
[task 2016-12-21T06:53:02.574127Z] 06:53:02     INFO -       sp = 0xbe80a118    pc = 0x42805913
[task 2016-12-21T06:53:02.574322Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.574721Z] 06:53:02     INFO -  26  libxul.so!js::InternalCallOrConstruct [Interpreter.cpp:14fcdf7cc920 : 475 + 0x7]
[task 2016-12-21T06:53:02.574846Z] 06:53:02     INFO -       r4 = 0xbe80a230    r5 = 0x4742b000    r6 = 0x4397be64    r7 = 0x00000000
[task 2016-12-21T06:53:02.575050Z] 06:53:02     INFO -       r8 = 0xbe80a168    r9 = 0xbe80a15c   r10 = 0x00000001    fp = 0x47d92300
[task 2016-12-21T06:53:02.575272Z] 06:53:02     INFO -       sp = 0xbe80a150    pc = 0x42805c2f
[task 2016-12-21T06:53:02.575621Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.575766Z] 06:53:02     INFO -  27  libxul.so!js::Call [Interpreter.cpp:14fcdf7cc920 : 521 + 0x3]
[task 2016-12-21T06:53:02.576015Z] 06:53:02     INFO -       r4 = 0xbe80a230    r5 = 0x00000000    r6 = 0x00000000    r7 = 0xffffff82
[task 2016-12-21T06:53:02.576215Z] 06:53:02     INFO -       r8 = 0xbe80a240    r9 = 0x47d922f8   r10 = 0x00000001    fp = 0x47d92300
[task 2016-12-21T06:53:02.576439Z] 06:53:02     INFO -       sp = 0xbe80a1a8    pc = 0x42805eaf
[task 2016-12-21T06:53:02.576625Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.576876Z] 06:53:02     INFO -  28  libxul.so!js::fun_call [jsfun.cpp:14fcdf7cc920 : 1157 + 0x3]
[task 2016-12-21T06:53:02.577080Z] 06:53:02     INFO -       r4 = 0x00000001    r5 = 0x00000000    r6 = 0x4397be64    r7 = 0xbe80a230
[task 2016-12-21T06:53:02.577321Z] 06:53:02     INFO -       r8 = 0xbe80a240    r9 = 0x47d922f8   r10 = 0x00000001    fp = 0x47d92300
[task 2016-12-21T06:53:02.577509Z] 06:53:02     INFO -       sp = 0xbe80a1b8    pc = 0x426c68eb
[task 2016-12-21T06:53:02.577707Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.577953Z] 06:53:02     INFO -  29  libxul.so!js::CallJSNative [jscntxtinlines.h:14fcdf7cc920 : 239 + 0x3]
[task 2016-12-21T06:53:02.578198Z] 06:53:02     INFO -       r4 = 0x47d92308    r5 = 0x4742b000    r6 = 0xbe80a558    r7 = 0x00000000
[task 2016-12-21T06:53:02.578438Z] 06:53:02     INFO -       r8 = 0x4397be64    r9 = 0xbe80a2e4   r10 = 0x426c679d    fp = 0x47d92300
[task 2016-12-21T06:53:02.578619Z] 06:53:02     INFO -       sp = 0xbe80a2d8    pc = 0x427d5f07
[task 2016-12-21T06:53:02.578799Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.579017Z] 06:53:02     INFO -  30  libxul.so!js::InternalCallOrConstruct [Interpreter.cpp:14fcdf7cc920 : 457 + 0xb]
[task 2016-12-21T06:53:02.579309Z] 06:53:02     INFO -       r4 = 0xbe80a558    r5 = 0x4742b000    r6 = 0x4397be64    r7 = 0x00000000
[task 2016-12-21T06:53:02.579569Z] 06:53:02     INFO -       r8 = 0x00000000    r9 = 0xbe80a31c   r10 = 0xbe80a490    fp = 0x4397be64
[task 2016-12-21T06:53:02.579788Z] 06:53:02     INFO -       sp = 0xbe80a310    pc = 0x42805b13
[task 2016-12-21T06:53:02.579967Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.580251Z] 06:53:02     INFO -  31  libxul.so!Interpret [Interpreter.cpp:14fcdf7cc920 : 508 + 0x7]
[task 2016-12-21T06:53:02.580419Z] 06:53:02     INFO -       r4 = 0x4742b000    r5 = 0x00000000    r6 = 0xbe80a690    r7 = 0xbe80a590
[task 2016-12-21T06:53:02.580730Z] 06:53:02     INFO -       r8 = 0x00000001    r9 = 0xbe80a558   r10 = 0xbe80a490    fp = 0x4397be64
[task 2016-12-21T06:53:02.580896Z] 06:53:02     INFO -       sp = 0xbe80a368    pc = 0x427fafcd
[task 2016-12-21T06:53:02.581187Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.581519Z] 06:53:02     INFO -  32  libxul.so!js::RunScript [Interpreter.cpp:14fcdf7cc920 : 403 + 0x7]
[task 2016-12-21T06:53:02.581770Z] 06:53:02     INFO -       r4 = 0x4742b000    r5 = 0xbe80a690    r6 = 0xbe80a64c    r7 = 0xc0000000
[task 2016-12-21T06:53:02.581983Z] 06:53:02     INFO -       r8 = 0x47f520a0    r9 = 0xbe80a684   r10 = 0xbe80a700    fp = 0x438e23c4
[task 2016-12-21T06:53:02.582161Z] 06:53:02     INFO -       sp = 0xbe80a640    pc = 0x42805913
[task 2016-12-21T06:53:02.582401Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.582568Z] 06:53:02     INFO -  33  libxul.so!js::InternalCallOrConstruct [Interpreter.cpp:14fcdf7cc920 : 475 + 0x7]
[task 2016-12-21T06:53:02.582882Z] 06:53:02     INFO -       r4 = 0xbe80a760    r5 = 0x4742b000    r6 = 0x4397be64    r7 = 0x00000000
[task 2016-12-21T06:53:02.583148Z] 06:53:02     INFO -       r8 = 0xbe80a690    r9 = 0xbe80a684   r10 = 0xbe80a700    fp = 0x438e23c4
[task 2016-12-21T06:53:02.583362Z] 06:53:02     INFO -       sp = 0xbe80a678    pc = 0x42805c2f
[task 2016-12-21T06:53:02.583605Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.583935Z] 06:53:02     INFO -  34  libxul.so!js::Call [Interpreter.cpp:14fcdf7cc920 : 521 + 0x3]
[task 2016-12-21T06:53:02.584162Z] 06:53:02     INFO -       r4 = 0xbe80a760    r5 = 0xbe80a8a8    r6 = 0x47e02970    r7 = 0xffffff8c
[task 2016-12-21T06:53:02.584468Z] 06:53:02     INFO -       r8 = 0xbe80a770    r9 = 0x00000001   r10 = 0xbe80a700    fp = 0x438e23c4
[task 2016-12-21T06:53:02.584812Z] 06:53:02     INFO -       sp = 0xbe80a6d0    pc = 0x42805eaf
[task 2016-12-21T06:53:02.585119Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.585248Z] 06:53:02     INFO -  35  libxul.so!js::Wrapper::call [Wrapper.cpp:14fcdf7cc920 : 165 + 0x3]
[task 2016-12-21T06:53:02.585583Z] 06:53:02     INFO -       r4 = 0x00000001    r5 = 0xbe80a8a8    r6 = 0x4397be64    r7 = 0xbe80a6f0
[task 2016-12-21T06:53:02.585780Z] 06:53:02     INFO -       r8 = 0xbe80a770    r9 = 0x00000001   r10 = 0xbe80a700    fp = 0x438e23c4
[task 2016-12-21T06:53:02.585963Z] 06:53:02     INFO -       sp = 0xbe80a6e0    pc = 0x42760d2f
[task 2016-12-21T06:53:02.586146Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.586370Z] 06:53:02     INFO -  36  libxul.so!js::CrossCompartmentWrapper::call [CrossCompartmentWrapper.cpp:14fcdf7cc920 : 333 + 0xb]
[task 2016-12-21T06:53:02.586679Z] 06:53:02     INFO -       r4 = 0x4742b000    r5 = 0x00000001    r6 = 0xbe80a8a8    r7 = 0x00000000
[task 2016-12-21T06:53:02.586874Z] 06:53:02     INFO -       r8 = 0xbe80a804    r9 = 0xbe80a8a4   r10 = 0x47b54000    fp = 0x438e23c4
[task 2016-12-21T06:53:02.587048Z] 06:53:02     INFO -       sp = 0xbe80a800    pc = 0x42749c3d
[task 2016-12-21T06:53:02.587260Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.587538Z] 06:53:02     INFO -  37  libxul.so!js::Proxy::call [Proxy.cpp:14fcdf7cc920 : 400 + 0x11]
[task 2016-12-21T06:53:02.587850Z] 06:53:02     INFO -       r4 = 0x42749b35    r5 = 0xbe80a840    r6 = 0xbe80a8a4    r7 = 0xbe80a8a8
[task 2016-12-21T06:53:02.587991Z] 06:53:02     INFO -       r8 = 0x438e23c4    r9 = 0x00000000   r10 = 0x439e2a94    fp = 0x47d92288
[task 2016-12-21T06:53:02.588186Z] 06:53:02     INFO -       sp = 0xbe80a838    pc = 0x42742c19
[task 2016-12-21T06:53:02.588492Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.588793Z] 06:53:02     INFO -  38  libxul.so!js::proxy_Call [Proxy.cpp:14fcdf7cc920 : 689 + 0x9]
[task 2016-12-21T06:53:02.588936Z] 06:53:02     INFO -       r4 = 0x00100000    r5 = 0x4397be64    r6 = 0x4742b000    r7 = 0xbe80a8a8
[task 2016-12-21T06:53:02.589159Z] 06:53:02     INFO -       r8 = 0xbe80a89c    r9 = 0xbe80a8dc   r10 = 0x42743769    fp = 0x47d92288
[task 2016-12-21T06:53:02.589400Z] 06:53:02     INFO -       sp = 0xbe80a898    pc = 0x427437db
[task 2016-12-21T06:53:02.589643Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.589903Z] 06:53:02     INFO -  39  libxul.so!js::CallJSNative [jscntxtinlines.h:14fcdf7cc920 : 239 + 0x3]
[task 2016-12-21T06:53:02.590115Z] 06:53:02     INFO -       r4 = 0x47d92290    r5 = 0x4742b000    r6 = 0xbe80ab50    r7 = 0x00000000
[task 2016-12-21T06:53:02.590398Z] 06:53:02     INFO -       r8 = 0x4397be64    r9 = 0xbe80a8dc   r10 = 0x42743769    fp = 0x47d92288
[task 2016-12-21T06:53:02.590741Z] 06:53:02     INFO -       sp = 0xbe80a8d0    pc = 0x427d5f07
[task 2016-12-21T06:53:02.590857Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.591171Z] 06:53:02     INFO -  40  libxul.so!js::InternalCallOrConstruct [Interpreter.cpp:14fcdf7cc920 : 445 + 0x9]
[task 2016-12-21T06:53:02.591447Z] 06:53:02     INFO -       r4 = 0xbe80ab50    r5 = 0x4742b000    r6 = 0x4397be64    r7 = 0x00000000
[task 2016-12-21T06:53:02.591786Z] 06:53:02     INFO -       r8 = 0x00000000    r9 = 0x438e0090   r10 = 0xbe80aa88    fp = 0x4397be64
[task 2016-12-21T06:53:02.592029Z] 06:53:02     INFO -       sp = 0xbe80a908    pc = 0x42805c8b
[task 2016-12-21T06:53:02.592196Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.592498Z] 06:53:02     INFO -  41  libxul.so!Interpret [Interpreter.cpp:14fcdf7cc920 : 508 + 0x7]
[task 2016-12-21T06:53:02.592819Z] 06:53:02     INFO -       r4 = 0x4742b000    r5 = 0x00000000    r6 = 0xbe80ac88    r7 = 0x47e16020
[task 2016-12-21T06:53:02.592951Z] 06:53:02     INFO -       r8 = 0x00000001    r9 = 0xbe80ab50   r10 = 0xbe80aa88    fp = 0x4397be64
[task 2016-12-21T06:53:02.593254Z] 06:53:02     INFO -       sp = 0xbe80a960    pc = 0x427fafcd
[task 2016-12-21T06:53:02.593367Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.593619Z] 06:53:02     INFO -  42  libxul.so!js::RunScript [Interpreter.cpp:14fcdf7cc920 : 403 + 0x7]
[task 2016-12-21T06:53:02.593828Z] 06:53:02     INFO -       r4 = 0x4742b000    r5 = 0xbe80ac88    r6 = 0xbe80ac44    r7 = 0xc0000000
[task 2016-12-21T06:53:02.594237Z] 06:53:02     INFO -       r8 = 0x47e2b100    r9 = 0xbe80ac7c   r10 = 0xbe80acf0    fp = 0x00000000
[task 2016-12-21T06:53:02.594323Z] 06:53:02     INFO -       sp = 0xbe80ac38    pc = 0x42805913
[task 2016-12-21T06:53:02.594509Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.594802Z] 06:53:02     INFO -  43  libxul.so!js::InternalCallOrConstruct [Interpreter.cpp:14fcdf7cc920 : 475 + 0x7]
[task 2016-12-21T06:53:02.595081Z] 06:53:02     INFO -       r4 = 0xbe80ad50    r5 = 0x4742b000    r6 = 0x4397be64    r7 = 0x00000000
[task 2016-12-21T06:53:02.595371Z] 06:53:02     INFO -       r8 = 0xbe80ac88    r9 = 0xbe80ac7c   r10 = 0xbe80acf0    fp = 0x00000000
[task 2016-12-21T06:53:02.595644Z] 06:53:02     INFO -       sp = 0xbe80ac70    pc = 0x42805c2f
[task 2016-12-21T06:53:02.595893Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.596150Z] 06:53:02     INFO -  44  libxul.so!js::Call [Interpreter.cpp:14fcdf7cc920 : 521 + 0x3]
[task 2016-12-21T06:53:02.596463Z] 06:53:02     INFO -       r4 = 0xbe80ad50    r5 = 0xbe80aeb0    r6 = 0x47e16460    r7 = 0xffffff8c
[task 2016-12-21T06:53:02.596599Z] 06:53:02     INFO -       r8 = 0xbe80acec    r9 = 0xbe80ae84   r10 = 0xbe80acf0    fp = 0x00000000
[task 2016-12-21T06:53:02.596785Z] 06:53:02     INFO -       sp = 0xbe80acc8    pc = 0x42805eaf
[task 2016-12-21T06:53:02.596991Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.597358Z] 06:53:02     INFO -  45  libxul.so!JS_CallFunctionValue [jsapi.cpp:14fcdf7cc920 : 2784 + 0x5]
[task 2016-12-21T06:53:02.597505Z] 06:53:02     INFO -       r4 = 0xbe80aeb8    r5 = 0xbe80aeb0    r6 = 0xbe80ad50    r7 = 0x4397be64
[task 2016-12-21T06:53:02.597730Z] 06:53:02     INFO -       r8 = 0xbe80acec    r9 = 0xbe80ae84   r10 = 0xbe80acf0    fp = 0x00000000
[task 2016-12-21T06:53:02.598099Z] 06:53:02     INFO -       sp = 0xbe80acd8    pc = 0x42685afd
[task 2016-12-21T06:53:02.598193Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.598466Z] 06:53:02     INFO -  46  libxul.so!nsXPCWrappedJSClass::CallMethod [XPCWrappedJSClass.cpp:14fcdf7cc920 : 1213 + 0x7]
[task 2016-12-21T06:53:02.598721Z] 06:53:02     INFO -       r4 = 0x4802be50    r5 = 0xbe80ae58    r6 = 0x00000000    r7 = 0xbe80aec0
[task 2016-12-21T06:53:02.599132Z] 06:53:02     INFO -       r8 = 0xbe80ae4c    r9 = 0xbe80b040   r10 = 0x80004005    fp = 0x00000000
[task 2016-12-21T06:53:02.599220Z] 06:53:02     INFO -       sp = 0xbe80adf8    pc = 0x40b14e2d
[task 2016-12-21T06:53:02.599538Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.599696Z] 06:53:02     INFO -  47  libxul.so!nsXPCWrappedJS::CallMethod [XPCWrappedJS.cpp:14fcdf7cc920 : 613 + 0xf]
[task 2016-12-21T06:53:02.600494Z] 06:53:02     INFO -       r4 = 0x47bc4d00    r5 = 0x00000001    r6 = 0x4749d058    r7 = 0x00000003
[task 2016-12-21T06:53:02.600585Z] 06:53:02     INFO -       r8 = 0xbe80b0f8    r9 = 0xbe80b0f8   r10 = 0x4749d058    fp = 0xbe80b2b8
[task 2016-12-21T06:53:02.600641Z] 06:53:02     INFO -       sp = 0xbe80b0a8    pc = 0x40b154a3
[task 2016-12-21T06:53:02.600690Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.600837Z] 06:53:02     INFO -  48  libxul.so!PrepareAndDispatch [xptcstubs_arm.cpp:14fcdf7cc920 : 103 + 0xf]
[task 2016-12-21T06:53:02.601093Z] 06:53:02     INFO -       r4 = 0x40b15455    r5 = 0xbe80b1a4    r6 = 0x00000001    r7 = 0x00000000
[task 2016-12-21T06:53:02.601290Z] 06:53:02     INFO -       r8 = 0xbe80b1c8    r9 = 0xbe80b0f8   r10 = 0x4749d058    fp = 0xbe80b2b8
[task 2016-12-21T06:53:02.601529Z] 06:53:02     INFO -       sp = 0xbe80b0c8    pc = 0x406e51cd
[task 2016-12-21T06:53:02.601706Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.601970Z] 06:53:02     INFO -  49  libxul.so!SharedStub + 0xe
[task 2016-12-21T06:53:02.602229Z] 06:53:02     INFO -       r4 = 0x450670e0    r5 = 0x00000000    r6 = 0x00000001    r7 = 0xbe80b1cc
[task 2016-12-21T06:53:02.602439Z] 06:53:02     INFO -       r8 = 0xbe80b1c8    r9 = 0xbe80b1c4   r10 = 0x00000001    fp = 0xbe80b2b8
[task 2016-12-21T06:53:02.602665Z] 06:53:02     INFO -       sp = 0xbe80b1a0    pc = 0x406e487d
[task 2016-12-21T06:53:02.602870Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.603147Z] 06:53:02     INFO -  50  libxul.so!nsThread::ProcessNextEvent [nsThread.cpp:14fcdf7cc920 : 1213 + 0x3]
[task 2016-12-21T06:53:02.603336Z] 06:53:02     INFO -       r1 = 0xbe80b190    r2 = 0x0000021e    r3 = 0x406e4881    r4 = 0x450670e0
[task 2016-12-21T06:53:02.603688Z] 06:53:02     INFO -       r5 = 0x00000000    r6 = 0x00000001    r7 = 0xbe80b1cc    r8 = 0xbe80b1c8
[task 2016-12-21T06:53:02.603976Z] 06:53:02     INFO -       r9 = 0xbe80b1c4   r10 = 0x00000001    fp = 0xbe80b2b8    sp = 0xbe80b1b0
[task 2016-12-21T06:53:02.604265Z] 06:53:02     INFO -       pc = 0x406dceaf
[task 2016-12-21T06:53:02.604518Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.604753Z] 06:53:02     INFO -  51  libxul.so!NS_InvokeByIndex [xptcinvoke_arm.cpp:14fcdf7cc920 : 169 + 0x13]
[task 2016-12-21T06:53:02.605036Z] 06:53:02     INFO -       r4 = 0xbe80b228    r5 = 0x406dccd9    r6 = 0x0000000c    r7 = 0xbe80b230
[task 2016-12-21T06:53:02.605355Z] 06:53:02     INFO -       r8 = 0x00000000    r9 = 0x00000002   r10 = 0xbe80b36c    fp = 0x47d92180
[task 2016-12-21T06:53:02.605632Z] 06:53:02     INFO -       sp = 0xbe80b218    pc = 0x406e4867
[task 2016-12-21T06:53:02.605919Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.606192Z] 06:53:02     INFO -  52  libxul.so!CallMethodHelper::Call [XPCWrappedNative.cpp:14fcdf7cc920 : 2058 + 0xd]
[task 2016-12-21T06:53:02.606476Z] 06:53:02     INFO -       r3 = 0xbe80b2a8    r4 = 0xbe80b278    r5 = 0x00000002    r6 = 0xbe80b250
[task 2016-12-21T06:53:02.606701Z] 06:53:02     INFO -       r7 = 0x00000002    r8 = 0x00000000    r9 = 0x00000002   r10 = 0xbe80b36c
[task 2016-12-21T06:53:02.606986Z] 06:53:02     INFO -       fp = 0x47d92180    sp = 0xbe80b248    pc = 0x40b11443
[task 2016-12-21T06:53:02.607413Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.607548Z] 06:53:02     INFO -  53  libxul.so!XPCWrappedNative::CallMethod [XPCWrappedNative.cpp:14fcdf7cc920 : 1344 + 0x5]
[task 2016-12-21T06:53:02.607789Z] 06:53:02     INFO -       r4 = 0xbe80b398    r5 = 0x00000000    r6 = 0xbe80b368    r7 = 0xbe80b2a0
[task 2016-12-21T06:53:02.608249Z] 06:53:02     INFO -       r8 = 0xbe80b298    r9 = 0xbe80b37c   r10 = 0xbe80b36c    fp = 0x47d92180
[task 2016-12-21T06:53:02.608361Z] 06:53:02     INFO -       sp = 0xbe80b278    pc = 0x40b11539
[task 2016-12-21T06:53:02.608593Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.608793Z] 06:53:02     INFO -  54  libxul.so!XPC_WN_CallMethod [XPCWrappedNativeJSOps.cpp:14fcdf7cc920 : 999 + 0x7]
[task 2016-12-21T06:53:02.609228Z] 06:53:02     INFO -       r4 = 0x00000001    r5 = 0xbe80b398    r6 = 0xbe80b368    r7 = 0x00000000
[task 2016-12-21T06:53:02.609494Z] 06:53:02     INFO -       r8 = 0xbe80b370    r9 = 0xbe80b37c   r10 = 0xbe80b36c    fp = 0x47d92180
[task 2016-12-21T06:53:02.609591Z] 06:53:02     INFO -       sp = 0xbe80b350    pc = 0x40b15d6f
[task 2016-12-21T06:53:02.609802Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.610169Z] 06:53:02     INFO -  55  libxul.so!js::CallJSNative [jscntxtinlines.h:14fcdf7cc920 : 239 + 0x3]
[task 2016-12-21T06:53:02.610364Z] 06:53:02     INFO -       r4 = 0x47d92188    r5 = 0x4742b000    r6 = 0xbe80b690    r7 = 0x00000000
[task 2016-12-21T06:53:02.610599Z] 06:53:02     INFO -       r8 = 0x4397be64    r9 = 0xbe80b41c   r10 = 0x40b15c69    fp = 0x47d92180
[task 2016-12-21T06:53:02.610896Z] 06:53:02     INFO -       sp = 0xbe80b410    pc = 0x427d5f07
[task 2016-12-21T06:53:02.611074Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.611328Z] 06:53:02     INFO -  56  libxul.so!js::InternalCallOrConstruct [Interpreter.cpp:14fcdf7cc920 : 457 + 0xb]
[task 2016-12-21T06:53:02.611676Z] 06:53:02     INFO -       r4 = 0xbe80b690    r5 = 0x4742b000    r6 = 0x4397be64    r7 = 0x00000000
[task 2016-12-21T06:53:02.611922Z] 06:53:02     INFO -       r8 = 0x00000000    r9 = 0xbe80b454   r10 = 0xbe80b5c8    fp = 0x4397be64
[task 2016-12-21T06:53:02.612265Z] 06:53:02     INFO -       sp = 0xbe80b448    pc = 0x42805b13
[task 2016-12-21T06:53:02.612428Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.612740Z] 06:53:02     INFO -  57  libxul.so!Interpret [Interpreter.cpp:14fcdf7cc920 : 508 + 0x7]
[task 2016-12-21T06:53:02.612930Z] 06:53:02     INFO -       r4 = 0x4742b000    r5 = 0x00000000    r6 = 0xbe80b7c8    r7 = 0x47d92170
[task 2016-12-21T06:53:02.613217Z] 06:53:02     INFO -       r8 = 0x00000001    r9 = 0xbe80b690   r10 = 0xbe80b5c8    fp = 0x4397be64
[task 2016-12-21T06:53:02.613642Z] 06:53:02     INFO -       sp = 0xbe80b4a0    pc = 0x427fafcd
[task 2016-12-21T06:53:02.613718Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.614009Z] 06:53:02     INFO -  58  libxul.so!js::RunScript [Interpreter.cpp:14fcdf7cc920 : 403 + 0x7]
[task 2016-12-21T06:53:02.614290Z] 06:53:02     INFO -       r4 = 0x4742b000    r5 = 0xbe80b7c8    r6 = 0xbe80b784    r7 = 0xc0000000
[task 2016-12-21T06:53:02.614783Z] 06:53:02     INFO -       r8 = 0x47e1b520    r9 = 0xbe80b7bc   r10 = 0xbe80b8e0    fp = 0xbe80b88b
[task 2016-12-21T06:53:02.615044Z] 06:53:02     INFO -       sp = 0xbe80b778    pc = 0x42805913
[task 2016-12-21T06:53:02.615111Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.615189Z] 06:53:02     INFO -  59  libxul.so!js::InternalCallOrConstruct [Interpreter.cpp:14fcdf7cc920 : 475 + 0x7]
[task 2016-12-21T06:53:02.615492Z] 06:53:02     INFO -       r4 = 0xbe80b914    r5 = 0x4742b000    r6 = 0x4397be64    r7 = 0x00000000
[task 2016-12-21T06:53:02.615708Z] 06:53:02     INFO -       r8 = 0xbe80b7c8    r9 = 0xbe80b7bc   r10 = 0xbe80b8e0    fp = 0xbe80b88b
[task 2016-12-21T06:53:02.615966Z] 06:53:02     INFO -       sp = 0xbe80b7b0    pc = 0x42805c2f
[task 2016-12-21T06:53:02.616264Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.616432Z] 06:53:02     INFO -  60  libxul.so!js::jit::DoCallFallback [BaselineIC.cpp:14fcdf7cc920 : 4733 + 0x7]
[task 2016-12-21T06:53:02.616738Z] 06:53:02     INFO -       r4 = 0x00000000    r5 = 0x00000000    r6 = 0x4742b000    r7 = 0xbe80ba68
[task 2016-12-21T06:53:02.616985Z] 06:53:02     INFO -       r8 = 0x00000000    r9 = 0xbe80b904   r10 = 0xbe80b8e0    fp = 0xbe80b88b
[task 2016-12-21T06:53:02.617254Z] 06:53:02     INFO -       sp = 0xbe80b808    pc = 0x42ab96b3
[task 2016-12-21T06:53:02.617558Z] 06:53:02     INFO -      Found by: call frame info
[task 2016-12-21T06:53:02.617829Z] 06:53:02     INFO -  61  0x44e196ea
[task 2016-12-21T06:53:02.618119Z] 06:53:02     INFO -       r4 = 0xbe80b9d8    r5 = 0xbe80b9f0    r6 = 0x43724126    r7 = 0xffffff82
[task 2016-12-21T06:53:02.618406Z] 06:53:02     INFO -       r8 = 0x00001844    r9 = 0x48043fd0   r10 = 0x00000000    fp = 0xbe80ba10
[task 2016-12-21T06:53:02.618664Z] 06:53:02     INFO -       sp = 0xbe80b9c8    pc = 0x44e196ec
[task 2016-12-21T06:53:02.618912Z] 06:53:02     INFO -      Found by: call frame info

Comment 26

[John Lin [:jhlin][:jolin]]

Jerry, logcat shows gfx code was executed before Java bridge is ready. Does this even work?

12-20 22:52:54.757  2322  2322 I Gecko   : {"action":"log","time":1482303174760,"thread":null,"pid":null,"source":"xpcshell/head.js","level":"INFO","message":"docshell/test/unit/test_setUsePrivateBrowsing.js | Starting "}
12-20 22:52:54.767  2322  2322 I Gecko   : 
12-20 22:52:54.767  2322  2322 I Gecko   : {"action":"log","time":1482303174766,"thread":null,"pid":null,"source":"xpcshell/head.js","level":"INFO","message":"(xpcshell/head.js) | test pending (2)"}
12-20 22:52:54.817  2322  2322 I Gecko   : [GFX2-]: AndroidBridge missing during initialization
12-20 22:52:54.817  2322  2322 I Gecko   : Attempting load of libEGL.so
12-20 22:52:54.837  2322  2322 D libEGL  : loaded /system/lib/egl/libEGL_emulation.so
12-20 22:52:54.837  2322  2322 D         : HostConnection::get() New Host Connection established 0x1b618, tid 2322
12-20 22:52:54.837  2322  2322 D libEGL  : loaded /system/lib/egl/libGLESv1_CM_emulation.so
12-20 22:52:54.848  2322  2322 D libEGL  : loaded /system/lib/egl/libGLESv2_emulation.so
12-20 22:52:54.867  2322  2322 I Gecko   : Initializing context 0x20158 surface 0x1f420 on display 0x1
12-20 22:52:54.887  2322  2322 I Gecko   : Destroying context 0x20158 surface 0x1f420 on display 0x1
12-20 22:52:54.887  2322  2322 I Gecko   : [GFX2-]: AndroidBridge missing during initialization
12-20 22:52:54.887  2322  2322 I Gecko   : [GFX2-]: AndroidBridge missing during initialization
12-20 22:52:54.887  2322  2322 I Gecko   : [GFX2-]: AndroidBridge missing during initialization
12-20 22:52:54.897  2322  2322 I nsScreenManagerAndroid: nsScreenManagerAndroid: add PRIMARY screen
12-20 22:52:55.107  2322  2322 F MOZ_Assert: Assertion failure: sGeckoThreadEnv, at /home/worker/workspace/build/src/widget/android/jni/Utils.cpp:135

Comment 27

[John Lin [:jhlin][:jolin]]

Turns out xpcshell tests are run in its own process, which I suppose doesn't have the Java environment like Fennec app process.
Will add additional check (jni::IsAvailable()) when creating hardware vsync source to address this.

Comment 28

[John Lin [:jhlin][:jolin]]

Bug 1325072 was filed to handle missig Java env cases.

Comment 29

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/mozilla-inbound/rev/fb838c37b5be
part 1: receive vsync events through Choreographer. r=snorp
https://hg.mozilla.org/integration/mozilla-inbound/rev/8b358cc2fdd4
part 2: implement AndroidVsyncSource. r=snorp

Comment 30

[Wes Kocher (:KWierso) (Not reading bugmail; email directly if needed)]

https://hg.mozilla.org/mozilla-central/rev/fb838c37b5be
https://hg.mozilla.org/mozilla-central/rev/8b358cc2fdd4