Closed Bug 996389 Opened 11 years ago Closed 11 years ago

[Tarako][Monkey test] Communication has not been killed after test is running for 4.5 hours

Categories

(Firefox OS Graveyard :: Runtime, defect)

Product:
Firefox OS Graveyard
Component:
Runtime
Platform:
ARM
Linux
Type:
defect
Priority:
Not set
Severity:
major

Tracking

(blocking-b2g:1.3T+)

Status:
RESOLVED WORKSFORME
Project Flags:
blocking-b2g 1.3T+

People

(Reporter: seinlin, Assigned: sinker)

Details

Attachments

(4 files)

b2g-info at the end of test
869 bytes, text/plain
Details
main.log
2.60 MB, application/x-compressed-tar
Details
Full log
3.76 MB, application/x-bzip
Details
mtlog-end-sp6821a_gonk-monkey-54-yingxuubt-1404190904.tar.bz2
4.57 MB, application/x-bzip
Details
Communication has not been killed after test is running for 4.5 hours and it oom_adj=1. This will result homescreen can't be loaded successfully, because its oom_adj is 2 which is lower than communication and it will be killed by LMK.
For checking communication's pid and oom_adj.
Thinker: can you help?
Flags: needinfo?(tlee)
Attached file main.log —
Is this monkey test run by us? If so please check if the comms app is still in a call. Normally oom_adj = 1 for the comms app means the phone is in a call, outgoing or incoming. Another question is why the phone is launching homescreen? Is it pushing the home button and trying to go back to the homescreen? When you observe this, do you try to intervene by stopping the monkey test and pushing the home button to see if it really can't go back to homescreen? Lastly, not least importantly, please be sure to attach dmesg for bugs regarding OOM killer or LMK in addition to adb logcat. There is hardly anything we can do without these important logs.
Yes, test is run in our lab. It can be observed that after communication was there, homescreen was killed many times even its adj is 2 during the test. At the end of the test when home button is pushed manually, homescreen can be launched successfully. As soon as it was launched it will be killed by LMK not OOM. --- main.log:09-19 05:37:44.020 23443 23443 I log : <4>0[66054.839267] lowmem_shrink select 23349 (Homescreen), adj 2, size 5409, to kill main.log:09-19 05:46:35.600 23815 23815 I log : <4>0[66586.371751] lowmem_shrink select 23572 (Homescreen), adj 2, size 5097, to kill main.log:09-19 05:47:50.260 23876 23876 I log : <4>0[66661.177611] lowmem_shrink select 23738 (Homescreen), adj 2, size 5519, to kill main.log:09-19 05:48:09.320 23894 23894 I log : <4>0[66680.079141] lowmem_shrink select 23823 (Homescreen), adj 2, size 5652, to kill main.log:09-19 05:48:49.980 23943 23943 I log : <4>0[66720.450743] lowmem_shrink select 23883 (Homescreen), adj 2, size 6147, to kill
Last week Evelyn has passed me a device with exactly the same symptom, I found free/cache were higher than notify_trigger but memory pressure kept happening. I was suspecting following checking but didn't confirm it: (si.freeswap << (PAGE_SHIFT - 10)) < lowmem_minfree_notif_trigger)) { lowmem_notify_killzone_approach(); }
(In reply to Kai-Zhen Li from comment #5) > At the end of the test when home button is pushed manually, homescreen can > be launched successfully. As soon as it was launched it will be killed by > LMK not OOM. > So what did you see? You push the home button and then the comms app doesn't switch to background?
(In reply to Cervantes Yu from comment #7) > So what did you see? You push the home button and then the comms app doesn't > switch to background? I saw nothing other than wallpaper. Press power key screen will be turned off and press again screen will be turned on. I can see the screen is locked. After unlock screen into home, there is only wallpaper. Try to push home button is also no home screen. At this time I can see "Homescreen is killed" in logcat.
James, Could you assign someone to check this error? From attached log we can see that communication (14651) was killed by LMK, but it is still alive. There could be some error in LMK. ---------- 305934 09-19 02:14:51.600 14651 14655 I GonkMemoryPressure: Memory pressure is over. 305935 09-19 02:14:51.660 13749 13753 I GonkMemoryPressure: Memory pressure is over. 305936 09-19 02:14:51.660 84 310 I GonkMemoryPressure: Memory pressure is over. 305937 09-19 02:14:51.680 13705 13709 I GonkMemoryPressure: Memory pressure is over. 305938 09-19 02:14:59.490 13808 13808 I log : <4>0[53890.296088] lowmem_shrink select 14651 (Communications), adj 1, size 8017, to kill 305939 09-19 02:15:03.260 84 84 I Gecko : 305940 09-19 02:15:03.260 84 84 I Gecko : ###!!! [Parent][MessageChannel] Error: Channel error: cannot send/recv 305941 09-19 02:15:03.260 84 84 I Gecko : 305942 09-19 02:15:04.210 14651 14655 I GonkMemoryPressure: Checking to see if memory pressure is over. 305943 09-19 02:15:04.210 14651 14655 I GonkMemoryPressure: Memory pressure is over. 305944 09-19 02:15:04.210 13749 13753 I GonkMemoryPressure: Checking to see if memory pressure is over. 305945 09-19 02:15:04.210 13749 13753 I GonkMemoryPressure: Memory pressure is over. 305946 09-19 02:15:04.210 84 310 I GonkMemoryPressure: Checking to see if memory pressure is over. 305947 09-19 02:15:04.210 84 310 I GonkMemoryPressure: Memory pressure is over.
Flags: needinfo?(tlee) → needinfo?(james.zhang)
Noming, because it sounds like we might be on call still and there might be an issue with the LMK
blocking-b2g: --- → 1.3T?
hi Thinker, can you please reassign to someone on your team? thanks triage: 1.3T+ as this is often seen in monkey test
Assignee: nobody → tlee
blocking-b2g: 1.3T? → 1.3T+
Ying, please help to check why communications cannot be killed successfully?
Flags: needinfo?(ying.xu)
Hi,kaizhen. Can you zip and upload the whole slog folder into the attachment?
Component: General → Runtime
Ying will handle LMK and OOM issue with mozilla team, and there are many android memory experts behind him.
Flags: needinfo?(james.zhang)
Attached file Full log —
Comment by partner: 1) Failed to waitpid for 14294, so 14924 became zombie since 15:44:34... 09-18 15:44:34.290 84 303 I Gecko : [Parent 84] WARNING: waitpid failed p id:14294 errno:10: file /home/kaizhen/Project/tarako/gecko/ipc/chromium/src/base /process_util_posix.cc, line 254 ... 09-18 15:44:36.290 84 303 I Gecko : [Parent 84] WARNING: waitpid failed p id:14294 errno:10: file /home/kaizhen/Project/tarako/gecko/ipc/chromium/src/base /process_util_posix.cc, line 254 09-18 15:44:36.290 84 303 I Gecko : [Parent 84] WARNING: Failed to delive r SIGKILL to 14294!(3).: file /home/kaizhen/Project/tarako/gecko/ipc/chromium/sr c/chrome/common/process_watcher_posix_sigchld.cc, line 118 2) Was 14651 a member thread in the group whose leader was 14294? If so, the die of 14294 might not lead to the dead of other member threads, when some exceptions happened. therefore, I suggest fixing the wrong of waitpid at first, thanks!
Flags: needinfo?(tlee)
For the implementation of Nuwa, no zombie should be there since SIGCHLD is ignored. Please check sigaction(2) of Linux manual, at NOTES section that explains how POSIX.1-2001 behave for SIGCHLD being ignored.
Flags: needinfo?(tlee)
1) We can see there is an errno 10 (ECHILD) int log, This is because Nuwa try to check the state of a process. When a process become zombie, it will be in ps with state=z. But there is no process with state=z in ps. So 14294 didn't become zombie. 2) 14651 is not a member of 14294. As I described in comment 9, 14651 was killed by LMK, but it keep printing log. This sign is unusual. May be something is wrong in kernel.
With full log. LMK and minfree param use default value.
(In reply to Kai-Zhen Li from comment #18) > 1) > We can see there is an errno 10 (ECHILD) int log, This is because Nuwa try > to check the state of a process. When a process become zombie, it will be in > ps with state=z. But there is no process with state=z in ps. So 14294 didn't > become zombie. > > 2) > 14651 is not a member of 14294. > > As I described in comment 9, 14651 was killed by LMK, but it keep printing > log. This sign is unusual. May be something is wrong in kernel. I will ask Horace to comment, he think it's ok.
Based on Ying's monkey test log. 1.Low memory killer is mainly caused by kswapd. 2.OOM killer is mainly caused by camera and compositor. 3.Sometimes LMK doesn't work and OOM killer works. kernel/kernel.log:04-18 21:13:20.724 <4>0[ 658.869870] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 21:13:51.059 <4>0[ 689.210674] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 21:30:52.826 <4>0[ 1710.973862] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 21:36:05.090 <4>0[ 2023.242218] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 21:36:37.025 <4>0[ 2055.177106] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 21:37:17.185 <4>0[ 2095.336291] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 21:44:54.072 <4>0[ 2552.185573] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 21:47:20.453 <4>0[ 2698.573776] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:01:24.669 <4>0[ 3542.821915] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:02:30.598 <4>0[ 3608.750731] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:02:35.959 <4>0[ 3614.110597] Gallery invoked lowmemorykiller: gfp_mask=0x200da, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 22:07:55.506 <4>0[ 3933.657617] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:18:22.850 <4>0[ 4561.001047] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:24:06.678 <4>0[ 4904.803464] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:30:34.284 <4>0[ 5292.433331] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:36:44.093 <4>0[ 5662.245496] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:37:52.051 <4>0[ 5730.201813] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:39:23.563 <4>0[ 5821.713515] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:43:39.183 <4>0[ 6077.330405] Compositor invoked lowmemorykiller: gfp_mask=0x286d2, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:44:18.164 <4>0[ 6116.315746] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:44:41.724 <4>0[ 6139.875826] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:46:23.477 <4>0[ 6241.628306] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:46:30.723 <4>0[ 6248.875088] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:53:06.944 <4>0[ 6645.096765] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:59:56.280 <4>0[ 7054.414936] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:01:51.054 <4>0[ 7169.206511] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:05:58.930 <4>0[ 7417.082336] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:08:32.311 <4>0[ 7570.460634] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:14:03.363 <4>0[ 7901.501461] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:14:10.371 <4>0[ 7908.484935] b2g invoked lowmemorykiller: gfp_mask=0x200da, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:14:34.946 <4>0[ 7933.097936] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:14:42.615 <4>0[ 7940.743522] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:14:50.960 <4>0[ 7949.112421] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:15:01.737 <4>0[ 7959.874149] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:16:36.797 <4>0[ 8054.948113] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:16:53.854 <4>0[ 8072.004369] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:17:03.640 <4>0[ 8081.791631] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:19:02.133 <4>0[ 8200.269487] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:19:53.045 <4>0[ 8251.193859] Compositor invoked lowmemorykiller: gfp_mask=0x286d2, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:20:12.253 <4>0[ 8270.393814] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:23:39.480 <4>0[ 8477.632620] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:37:29.743 <4>0[ 9307.844748] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:39:16.329 <4>0[ 9414.481458] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:39:48.738 <4>0[ 9446.891348] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:42:36.036 <4>0[ 9614.187995] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:47:10.318 <4>0[ 9888.469104] Compositor invoked lowmemorykiller: gfp_mask=0x286d2, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:47:14.496 <4>0[ 9892.646832] b2g invoked lowmemorykiller: gfp_mask=0x200da, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:51:01.120 <4>0[10119.271992] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:51:09.525 <4>0[10127.676479] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:54:22.492 <4>0[10320.623860] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 00:09:35.182 <4>0[11233.312093] Compositor invoked lowmemorykiller: gfp_mask=0x286d2, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 00:24:03.193 <4>0[12101.343217] Compositor invoked lowmemorykiller: gfp_mask=0x286d2, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 00:27:38.187 <4>0[12316.339275] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 00:29:56.419 <4>0[12454.571556] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 00:35:51.955 <4>0[12810.107749] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 00:43:04.534 <4>0[13242.685640] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 01:37:13.437 <4>0[16491.469500] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 02:49:29.686 <4>0[20827.835512] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 03:00:03.934 <4>0[21462.081330] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 03:20:00.205 <4>0[22658.356311] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 03:20:02.625 <4>0[22660.778448] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 03:23:42.060 <4>0[22880.212226] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 03:52:53.146 <4>0[24631.296481] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 04:16:07.984 <4>0[26026.118991] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 04:56:45.154 <4>0[28463.303190] StreamT~ns #149 invoked lowmemorykiller: gfp_mask=0x81250, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 05:00:25.389 <4>0[28683.538022] ImageDecoder #2 invoked lowmemorykiller: gfp_mask=0x200da, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 06:00:46.354 <4>0[32304.495885] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 06:00:47.606 <4>0[32305.758402] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 06:12:09.455 <4>0[32987.605396] Camera invoked lowmemorykiller: gfp_mask=0x200da, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 07:01:38.239 <4>0[35956.390569] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 07:01:39.533 <4>0[35957.685625] b2g invoked lowmemorykiller: gfp_mask=0x200da, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 07:30:57.866 <4>0[37716.016636] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 07:36:57.768 <4>0[38075.919608] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 07:49:11.619 <4>0[38809.772885] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 08:08:38.473 <4>0[39976.617250] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 08:08:52.380 <4>0[39990.503805] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 08:39:43.684 <4>0[41841.835924] kswapd0 invoked lowmemorykiller: gfp_mask=0xd0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 21:13:30.051 <4>0[ 668.183036] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 21:13:58.348 <4>0[ 696.499282] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 21:24:43.737 <4>0[ 1341.866837] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 21:24:48.447 <4>0[ 1346.598249] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 21:45:29.575 <4>0[ 2587.727196] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 21:45:30.185 <4>0[ 2588.314199] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 21:45:58.169 <4>0[ 2616.318447] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 21:47:30.969 <4>0[ 2709.117044] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 21:52:09.294 <4>0[ 2987.443606] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 22:00:23.072 <4>0[ 3481.179498] ImageDecoder #2 invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 22:00:30.028 <4>0[ 3488.179659] ImageDecoder #2 invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 22:00:47.216 <4>0[ 3505.367543] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:12:36.504 <4>0[ 4214.654220] b2g invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:23:54.384 <4>0[ 4892.525669] ImageDecoder #4 invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 22:24:14.111 <4>0[ 4912.248993] JS GC Helper invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 22:30:49.736 <4>0[ 5307.886941] ImageDecoder #2 invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 22:31:03.080 <4>0[ 5321.226640] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:31:49.936 <4>0[ 5368.084937] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:35:02.795 <4>0[ 5560.946673] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 22:49:10.445 <4>0[ 6408.596004] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 22:53:05.724 <4>0[ 6643.853698] E-Mail invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 23:06:06.917 <4>0[ 7425.068697] Homescreen invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 23:08:04.916 <4>0[ 7543.067261] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:13:02.685 <4>0[ 7840.837025] b2g invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:16:19.456 <4>0[ 8037.605267] Messages invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 23:23:53.996 <4>0[ 8492.148246] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:24:25.336 <4>0[ 8523.487125] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:27:16.997 <4>0[ 8695.148600] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:35:22.673 <4>0[ 9180.825033] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-18 23:50:36.918 <4>0[10095.069395] Messages invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=1, oom_score_adj=67 kernel/kernel.log:04-18 23:51:40.056 <4>0[10158.206584] Communications invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-18 23:58:26.465 <4>0[10564.615574] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 00:04:35.673 <4>0[10933.820112] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 00:04:46.634 <4>0[10944.785686] slog invoked oom-killer: gfp_mask=0xd0, order=2, oom_adj=-16, oom_score_adj=-941 kernel/kernel.log:04-19 00:25:42.545 <4>0[12200.696572] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 00:26:15.747 <4>0[12233.898447] ImageDecoder #1 invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 01:25:11.174 <4>0[15769.324370] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 02:12:53.483 <4>0[18631.594436] b2g invoked oom-killer: gfp_mask=0x201da, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 02:49:37.968 <4>0[20836.104304] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 03:00:10.770 <4>0[21468.926045] ImageDecoder #2 invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 03:03:05.744 <4>0[21643.895425] b2g invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 03:23:07.784 <4>0[22845.935006] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 03:24:02.034 <4>0[22900.155656] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 03:35:57.653 <4>0[23615.766847] ImageDecoder #2 invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 03:36:06.753 <4>0[23624.884083] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 03:47:13.616 <4>0[24291.767151] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 03:52:56.284 <4>0[24634.434579] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 04:16:41.398 <4>0[26059.549764] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 04:38:15.915 <4>0[27354.066807] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 04:46:42.440 <4>0[27860.591778] ImageDecoder #2 invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 04:57:17.254 <4>0[28495.387237] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 05:53:54.056 <4>0[31892.206598] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 06:01:11.708 <4>0[32329.859726] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 06:20:29.124 <4>0[33487.274871] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 06:30:16.889 <4>0[34075.037139] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 07:31:20.248 <4>0[37738.399411] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 07:37:03.027 <4>0[38081.177896] Camera invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel/kernel.log:04-19 07:57:47.940 <4>0[39326.091574] b2g invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=0, oom_score_adj=0 kernel/kernel.log:04-19 08:29:20.143 <4>0[41218.294573] Compositor invoked oom-killer: gfp_mask=0x286d2, order=0, oom_adj=0, oom_score_adj=0
From the log, I don't find the communication app could not be killed. kernel.log:04-18 21:36:05.090 <4>0[ 2023.242077] lowmem_shrink select 2153 (Communications), adj 10, size 5372, to kill kernel.log:04-18 21:36:05.090 <4>0[ 2023.242156] lowmem_shrink send sigkill to 2153 (Communications), adj 10, size 5372 kernel.log:04-18 21:36:37.064 <6>0[ 2055.190369] R (running) 2362 89332K 37544K 37544K 31429K 28808K 4436K Communications kernel.log:04-18 21:37:04.915 <6>0[ 2083.070176] R (running) 2362 93436K 32624K 32048K 26266K 22460K 7836K Communications kernel.log:04-18 21:37:04.915 <3>0[ 2083.071133] Out of memory: Kill process 2362 (Communications) score 375 or sacrifice child kernel.log:04-18 21:37:04.915 <3>0[ 2083.071205] Killed process 2362 (Communications) total-vm:93436kB, anon-rss:23084kB, file-rss:8964kB kernel.log:04-18 21:47:20.472 <6>0[ 2698.586221] R (running) 3135 103232K 36688K 32656K 26974K 24476K 11536K Communications kernel.log:04-18 23:16:53.880 <6>0[ 8072.016967] R (running) 9441 78028K 17952K 16224K 10980K 8924K 8580K Communications kernel.log:04-18 23:17:52.215 <6>0[ 8130.376994] R (running) 9441 88828K 29148K 26724K 22514K 20172K 7368K Communications kernel.log:04-18 23:19:02.133 <4>0[ 8200.269450] lowmem_shrink select 9441 (Communications), adj 10, size 6771, to kill kernel.log:04-18 23:19:02.133 <4>0[ 8200.269472] lowmem_shrink send sigkill to 9441 (Communications), adj 10, size 6771 kernel.log:04-18 23:19:53.091 ] S (sleeping) 9763 91168K 30780K 24300K 19026K 16924K 10956K Communications kernel.log:04-18 23:20:12.273 <6>0[ 8270.405475] S (sleeping) 9763 95828K 19560K 19560K 14028K 11740K 20396K Communications kernel.log:04-18 23:21:01.466 <6>0[ 8319.619887] R (running) 9763 102868K 28420K 27844K 23370K 20636K 19652K Communications kernel.log:04-18 23:21:01.466 <3>0[ 8319.620895] Out of memory: Kill process 9763 (Communications) score 421 or sacrifice child kernel.log:04-18 23:21:01.466 <3>0[ 8319.620968] Killed process 9763 (Communications) total-vm:102868kB, anon-rss:20884kB, file-rss:6960kB kernel.log:04-18 23:37:29.774 <6>0[ 9307.857928] S (sleeping) 11003 101244K 27800K 27224K 21481K 19048K 20384K Communications kernel.log:04-18 23:39:48.738 <4>0[ 9446.891205] lowmem_shrink select 11003 (Communications), adj 10, size 10754, to kill kernel.log:04-18 23:39:48.738 <4>0[ 9446.891285] lowmem_shrink send sigkill to 11003 (Communications), adj 10, size 10754 kernel.log:04-18 23:51:40.056 <4>0[10158.206584] Communications invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=2, oom_score_adj=134 kernel.log:04-18 23:55:01.509 <6>0[10359.659928] S (sleeping) 12326 93124K 24060K 23484K 18476K 15524K 14564K Communications kernel.log:04-18 23:55:01.509 <3>0[10359.661168] Out of memory: Kill process 12326 (Communications) score 364 or sacrifice child kernel.log:04-18 23:55:01.509 <3>0[10359.661240] Killed process 12326 (Communications) total-vm:93124kB, anon-rss:16388kB, file-rss:7096kB kernel.log:04-18 23:56:51.201 <6>0[10469.348918] R (running) 12788 81132K 28320K 28320K 21673K 17752K 4952K Communications kernel.log:04-18 23:56:51.201 <3>0[10469.350716] Out of memory: Kill process 12788 (Communications) score 601 or sacrifice child kernel.log:04-18 23:56:51.201 <3>0[10469.350788] Killed process 12788 (Communications) total-vm:81132kB, anon-rss:18828kB, file-rss:9492kB kernel.log:04-19 00:24:52.920 <6>0[12151.037046] R (running) 13778 91892K 32908K 32908K 26835K 24432K 10420K Communications kernel.log:04-19 00:25:00.272 <6>0[12158.392316] R (running) 13778 98924K 38000K 38000K 32495K 29876K 10904K Communications kernel.log:04-19 00:25:42.564 <6>0[12200.710050] S (sleeping) 13778 103212K 31140K 30564K 25960K 22996K 12204K Communications kernel.log:04-19 00:25:42.564 <3>0[12200.711107] Out of memory: Kill process 13778 (Communications) score 393 or sacrifice child kernel.log:04-19 00:25:42.564 <3>0[12200.711180] Killed process 13778 (Communications) total-vm:103212kB, anon-rss:22764kB, file-rss:7800kB
Flags: needinfo?(ying.xu)
Bug 998228 - Ensure Tarako has 2mp image size limit set : it's fixed now. I wonder whether the compositor oom can happen again or not.
Any updates here?
All lmk/oom patches are landed on 4/25. Monkey test can pass 12-14. No devices are found crash.
(In reply to thomas tsai from comment #25) > All lmk/oom patches are landed on 4/25. Monkey test can pass 12-14. No > devices are found crash. Thanks. Then lets close it!
Status: NEW → RESOLVED
Closed: 11 years ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: