When I run a xpcshell test on Android, I get tons of logspam that is a repetition of the following two lines: > D ServiceAllocator: org.mozilla.gecko.process.GeckoChildProcessServices$gpu updateBindings: FOREGROUND priority, 0 importance, 3 successful binds, 0 failed binds, 0 successful unbinds > W ServiceChildProcess: This process belongs to a different GeckoRuntime owner: a6e047f4-b59e-44e6-8fe7-2e77dfa27779 process: b4d03bfd-e47c-4c35-80d3-687a1b78ffce Here is an example of a 350 MB [logcat-emulator-5554.log](https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/aroo8mHAT5Ss-48kfPcvVg/runs/0/artifacts/public/test_info/logcat-emulator-5554.log) from a [try push](https://treeherder.mozilla.org/jobs?repo=try&revision=59f09a1ce98810875441761dd2a02c42fa8ddbbf&selectedTaskRun=aroo8mHAT5Ss-48kfPcvVg.0): - 1 963 881 lines - 1 527 627 lines with the above two messages. To get anything useful out of adb logcat locally, I currently have to filter logcat as follows: `adb logcat | grep -vE ServiceChildProcess: This process belongs to a different GeckoRuntime owner|ServiceAllocator: org.mozilla.gecko.process.GeckoChildProcessServices'` If you want to reproduce, create a `.mozconfig` to develop for Android (artifact builds are OK): ``` ./mach android-emulator ./mach test toolkit/components/extensions/test/xpcshell/test_ext_alarms_does_not_fire.js ``` and in another terminal, `adb logcat` : ``` 07-21 19:14:47.812 5136 5152 I Gecko : {"action":"log","time":1689959687807,"thread":null,"pid":null,"source":"xpcshell/head.js","level":"INFO","message":"xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_alarms_does_not_fire.js | Starting test_cleared_alarm_does_not_fire"} 07-21 19:14:47.813 5136 5152 I Gecko : 07-21 19:14:47.813 5136 5152 I Gecko : {"action":"log","time":1689959687813,"thread":null,"pid":null,"source":"xpcshell/head.js","level":"INFO","message":"(xpcshell/head.js) | test test_cleared_alarm_does_not_fire pending (2)"} 07-21 19:14:47.848 5136 5152 I Gecko : 07-21 19:14:47.848 5136 5152 I Gecko : {"action":"log","time":1689959687848,"thread":null,"pid":null,"source":"xpcshell/head.js","level":"INFO","message":"\"Extension attached\""} 07-21 19:14:47.848 5136 5152 I Gecko : 07-21 19:14:47.848 5136 5152 I Gecko : {"action":"log","time":1689959687848,"thread":null,"pid":null,"source":"xpcshell/head.js","level":"INFO","message":"(xpcshell/head.js) | test run_next_test 1 finished (2)"} 07-21 19:14:47.962 5136 5152 D : HostConnection::get() New Host Connection established 0x7bdbf081f6a0, tid 5152 07-21 19:14:47.967 5136 5152 D EGL_emulation: eglCreateContext: 0x7bdbf08a7440: maj 3 min 0 rcv 3 07-21 19:14:47.968 5136 5152 D EGL_emulation: eglMakeCurrent: 0x7bdbf08a7440: ver 3 0 (tinfo 0x7bdbf08ba6e0) 07-21 19:14:47.972 5136 5152 E eglCodecCommon: glUtilsParamSize: unknow param 0x00008cdf 07-21 19:14:47.972 5136 5152 E eglCodecCommon: glUtilsParamSize: unknow param 0x00008824 07-21 19:14:47.989 5136 5168 D ServiceAllocator: org.mozilla.gecko.process.GeckoChildProcessServices$gpu updateBindings: FOREGROUND priority, 0 importance, 3 successful binds, 0 failed binds, 0 successful unbinds 07-21 19:14:47.997 5053 5070 W ServiceChildProcess: This process belongs to a different GeckoRuntime owner: a5be9210-1374-4549-bb9f-86271cd1137b process: dcc7ad4b-a327-40fe-8490-5baa279e0cda 07-21 19:14:48.011 5136 5168 D ServiceAllocator: org.mozilla.gecko.process.GeckoChildProcessServices$gpu updateBindings: FOREGROUND priority, 0 importance, 3 successful binds, 0 failed binds, 0 successful unbinds 07-21 19:14:48.013 5053 5070 W ServiceChildProcess: This process belongs to a different GeckoRuntime owner: a5be9210-1374-4549-bb9f-86271cd1137b process: dcc7ad4b-a327-40fe-8490-5baa279e0cda ``` The last two lines repeat 1237 times in this one test run, interleaved with other usual log output. Out of the 3.2k log lines, 2.5k was from this logspam...
Bug 1844829 Comment 0 Edit History
Note: The actual edited comment in the bug view page will always show the original commenter’s name and original timestamp.
When I run a xpcshell test on Android, I get tons of logspam that is a repetition of the following two lines: > D ServiceAllocator: org.mozilla.gecko.process.GeckoChildProcessServices$gpu updateBindings: FOREGROUND priority, 0 importance, 3 successful binds, 0 failed binds, 0 successful unbinds > W ServiceChildProcess: This process belongs to a different GeckoRuntime owner: a6e047f4-b59e-44e6-8fe7-2e77dfa27779 process: b4d03bfd-e47c-4c35-80d3-687a1b78ffce Here is an example of a 350 MB [logcat-emulator-5554.log](https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/aroo8mHAT5Ss-48kfPcvVg/runs/0/artifacts/public/test_info/logcat-emulator-5554.log) from a [try push](https://treeherder.mozilla.org/jobs?repo=try&revision=59f09a1ce98810875441761dd2a02c42fa8ddbbf&selectedTaskRun=aroo8mHAT5Ss-48kfPcvVg.0): - 1 963 881 lines - 1 527 627 lines with the above two messages. To get anything useful out of adb logcat locally, I currently have to filter logcat as follows: `adb logcat | grep -vE 'ServiceChildProcess: This process belongs to a different GeckoRuntime owner|ServiceAllocator: org.mozilla.gecko.process.GeckoChildProcessServices'` If you want to reproduce, create a `.mozconfig` to develop for Android (artifact builds are OK): ``` ./mach android-emulator ./mach test toolkit/components/extensions/test/xpcshell/test_ext_alarms_does_not_fire.js ``` and in another terminal, `adb logcat` : ``` 07-21 19:14:47.812 5136 5152 I Gecko : {"action":"log","time":1689959687807,"thread":null,"pid":null,"source":"xpcshell/head.js","level":"INFO","message":"xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_alarms_does_not_fire.js | Starting test_cleared_alarm_does_not_fire"} 07-21 19:14:47.813 5136 5152 I Gecko : 07-21 19:14:47.813 5136 5152 I Gecko : {"action":"log","time":1689959687813,"thread":null,"pid":null,"source":"xpcshell/head.js","level":"INFO","message":"(xpcshell/head.js) | test test_cleared_alarm_does_not_fire pending (2)"} 07-21 19:14:47.848 5136 5152 I Gecko : 07-21 19:14:47.848 5136 5152 I Gecko : {"action":"log","time":1689959687848,"thread":null,"pid":null,"source":"xpcshell/head.js","level":"INFO","message":"\"Extension attached\""} 07-21 19:14:47.848 5136 5152 I Gecko : 07-21 19:14:47.848 5136 5152 I Gecko : {"action":"log","time":1689959687848,"thread":null,"pid":null,"source":"xpcshell/head.js","level":"INFO","message":"(xpcshell/head.js) | test run_next_test 1 finished (2)"} 07-21 19:14:47.962 5136 5152 D : HostConnection::get() New Host Connection established 0x7bdbf081f6a0, tid 5152 07-21 19:14:47.967 5136 5152 D EGL_emulation: eglCreateContext: 0x7bdbf08a7440: maj 3 min 0 rcv 3 07-21 19:14:47.968 5136 5152 D EGL_emulation: eglMakeCurrent: 0x7bdbf08a7440: ver 3 0 (tinfo 0x7bdbf08ba6e0) 07-21 19:14:47.972 5136 5152 E eglCodecCommon: glUtilsParamSize: unknow param 0x00008cdf 07-21 19:14:47.972 5136 5152 E eglCodecCommon: glUtilsParamSize: unknow param 0x00008824 07-21 19:14:47.989 5136 5168 D ServiceAllocator: org.mozilla.gecko.process.GeckoChildProcessServices$gpu updateBindings: FOREGROUND priority, 0 importance, 3 successful binds, 0 failed binds, 0 successful unbinds 07-21 19:14:47.997 5053 5070 W ServiceChildProcess: This process belongs to a different GeckoRuntime owner: a5be9210-1374-4549-bb9f-86271cd1137b process: dcc7ad4b-a327-40fe-8490-5baa279e0cda 07-21 19:14:48.011 5136 5168 D ServiceAllocator: org.mozilla.gecko.process.GeckoChildProcessServices$gpu updateBindings: FOREGROUND priority, 0 importance, 3 successful binds, 0 failed binds, 0 successful unbinds 07-21 19:14:48.013 5053 5070 W ServiceChildProcess: This process belongs to a different GeckoRuntime owner: a5be9210-1374-4549-bb9f-86271cd1137b process: dcc7ad4b-a327-40fe-8490-5baa279e0cda ``` The last two lines repeat 1237 times in this one test run, interleaved with other usual log output. Out of the 3.2k log lines, 2.5k was from this logspam...