command top log
=======================================


Mem: 31592K used, 28224K free, 0K shrd, 1016K buff, 17440K cached
CPU:  75% usr  13% sys   0% nic   6% idle   1% io   0% irq   2% sirq
Load average: 0.16 0.05 0.30 2/56 13846
  PID  PPID USER     STAT   VSZ %MEM %CPU COMMAND
13840 13837 root     S    63732 106%  81% /opt/am1808/gstreamer/bin/gst-launch-0.10 playbin2 uri=http
...

Mem: 32376K used, 27440K free, 0K shrd, 1020K buff, 17440K cached
CPU:  18% usr   5% sys   0% nic  75% idle   0% io   0% irq   0% sirq
Load average: 0.13 0.05 0.30 1/55 13846
  PID  PPID USER     STAT   VSZ %MEM %CPU COMMAND
13840 13837 root     S    63732 106%  16% /opt/am1808/gstreamer/bin/gst-launch-0.10 playbin2 uri=http
...

Mem: 35484K used, 24332K free, 0K shrd, 1056K buff, 17656K cached
CPU:  14% usr   2% sys   0% nic  83% idle   0% io   0% irq   0% sirq
Load average: 0.00 0.01 0.05 1/54 13884
  PID  PPID USER     STAT   VSZ %MEM %CPU COMMAND
13840 13837 root     S    67060 112%  11% /opt/am1808/gstreamer/bin/gst-launch-0.10 playbin2 uri=http
...
~~~~~~~ After 1 hour 32 minutes playing ~~~~~
...

Mem: 45180K used, 14636K free, 0K shrd, 1056K buff, 17656K cached
CPU:  76% usr  15% sys   0% nic   0% idle   0% io   0% irq   8% sirq
Load average: 0.08 0.03 0.05 2/54 13884
  PID  PPID USER     STAT   VSZ %MEM %CPU COMMAND
13840 13837 root     S    77372 129%  90% /opt/am1808/gstreamer/bin/gst-launch-0.10 playbin2 uri=http
...

Mem: 55284K used, 4532K free, 0K shrd, 1056K buff, 17656K cached
CPU:  72% usr  20% sys   0% nic   0% idle   0% io   0% irq   7% sirq
Load average: 0.24 0.06 0.06 2/54 13884
  PID  PPID USER     STAT   VSZ %MEM %CPU COMMAND
13840 13837 root     S    87940 147%  91% /opt/am1808/gstreamer/bin/gst-launch-0.10 playbin2 uri=http
...


Mem: 57652K used, 2164K free, 0K shrd, 1056K buff, 17656K cached
CPU:   3% usr   1% sys   0% nic  95% idle   0% io   0% irq   0% sirq
Load average: 0.16 0.06 0.06 1/54 13884
  PID  PPID USER     STAT   VSZ %MEM %CPU COMMAND
13884     2 root     SW       0   0%   3% [kworker/0:0]
13840 13837 root     S    90524 151%   1% /opt/am1808/gstreamer/bin/gst-launch-0.10 playbin2 uri=http
...
~~~~~~~ After futher 24 minutes playing ~~~~~~~~
...

Mem: 58152K used, 1664K free, 0K shrd, 1056K buff, 17656K cached
CPU:  13% usr   1% sys   0% nic  83% idle   0% io   0% irq   0% sirq
Load average: 0.01 0.03 0.05 1/54 13885
  PID  PPID USER     STAT   VSZ %MEM %CPU COMMAND
13840 13837 root     S    91088 152%  10% /opt/am1808/gstreamer/bin/gst-launch-0.10 playbin2 uri=http
...

Mem: 58744K used, 1072K free, 0K shrd, 76K buff, 3384K cached
CPU:   0% usr  19% sys   0% nic   0% idle  79% io   0% irq   0% sirq
Load average: 5.60 3.79 1.74 2/54 13896
  PID  PPID USER     STAT   VSZ %MEM %CPU COMMAND
13840 13837 root     D     103m 177%   6% /opt/am1808/gstreamer/bin/gst-launch-0.10 playbin2 uri=http
...

Mem: 58704K used, 1112K free, 0K shrd, 104K buff, 3096K cached
CPU:   0% usr  20% sys   0% nic   0% idle  79% io   0% irq   0% sirq
Load average: 5.59 4.83 2.65 2/54 13897
  PID  PPID USER     STAT   VSZ %MEM %CPU COMMAND
13840 13837 root     D     104m 179%   9% /opt/am1808/gstreamer/bin/gst-launch-0.10 playbin2 uri=http
13897     2 root     SW       0   0%   4% [kworker/0:0]
...
~~~~~~~~ OOM kill gst-launch ~~~~~~~~
...

Mem: 11892K used, 47924K free, 0K shrd, 164K buff, 4476K cached
CPU:   0% usr  21% sys   0% nic   0% idle  78% io   0% irq   0% sirq
Load average: 5.92 4.98 2.77 3/50 13898
  PID  PPID USER     STAT   VSZ %MEM %CPU COMMAND
  531     2 root     DW       0   0%   8% [mmcqd/0]
13897     2 root     SW       0   0%   8% [kworker/0:0]
13808 13806 root     R     3116   5%   2% top 
  285     2 root     DW       0   0%   1% [kswapd0]
...


====================================================
console log running the gst-launch
====================================================
root@M1:/usr/tests# ./testlargefile.sh 
MemTotal:          59816 kB
MemFree:           39064 kB
Buffers:             884 kB
Cached:            12576 kB
SwapCached:            0 kB
Active:             4764 kB
Inactive:           9896 kB
Active(anon):       2452 kB
Inactive(anon):       88 kB
Active(file):       2312 kB
Inactive(file):     9808 kB
Unevictable:           0 kB
Mlocked:               0 kB
SwapTotal:             0 kB
SwapFree:              0 kB
Dirty:                20 kB
Writeback:             0 kB
AnonPages:          1224 kB
Mapped:             1096 kB
Shmem:              1340 kB
Slab:               3340 kB
SReclaimable:        772 kB
SUnreclaim:         2568 kB
KernelStack:         408 kB
PageTables:          188 kB
NFS_Unstable:          0 kB
Bounce:                0 kB
WritebackTmp:          0 kB
CommitLimit:       29908 kB
Committed_AS:       5560 kB
VmallocTotal:     952320 kB
VmallocUsed:        1092 kB
VmallocChunk:     950140 kB

Wed Jun 13 09:52:45 HKT 2012

Setting pipeline to PAUSED ...
Pipeline is PREROLLING ...  
Pipeline is PREROLLED ...
Setting pipeline to PLAYING ...
New clock: GstAudioSinkClock
Done buffering, setting pipeline to PLAYING ...
Buffering, setting pipeline to PAUSED ...
Buffering, setting pipeline to PAUSED ...

~~~~~~~~~ After 1 hour 32 minutes playing ~~~~~~~~

Done buffering, setting pipeline to PLAYING ...
Buffering, setting pipeline to PAUSED ...
Done buffering, setting pipeline to PLAYING ...
Buffering, setting pipeline to PAUSED ...
Done buffering, setting pipeline to PLAYING ...
Buffering, setting pipeline to PAUSED ...
Done buffering, setting pipeline to PLAYING ...
Buffering, setting pipeline to PAUSED ...
Done buffering, setting pipeline to PLAYING ...
Buffering, setting pipeline to PAUSED ...
Prerolled, waiting for buffering to finish...
Done buffering, setting pipeline to PLAYING ...
Buffering, setting pipeline to PAUSED ...
Done buffering, setting pipeline to PLAYING ...
Buffering, setting pipeline to PAUSED ...
Done buffering, setting pipeline to PLAYING ...
Buffering, setting pipeline to PAUSED ...
Prerolled, waiting for buffering to finish...
Done buffering, setting pipeline to PLAYING ...
Buffering, setting pipeline to PAUSED ...
Done buffering, setting pipeline to PLAYING ...
Buffering, setting pipeline to PAUSED ...
Prerolled, waiting for buffering to finish...
Done buffering, setting pipeline to PLAYING ...
Buffering, setting pipeline to PAUSED ...
Prerolled, waiting for buffering to finish...
Done buffering, setting pipeline to PLAYING ...
Buffering, setting pipeline to PAUSED ...
Done buffering, setting pipeline to PLAYING ...
gst-launch-0.10 invoked oom-killer: gfp_mask=0x201da, order=0, oom_adj=0, oom_score_adj=0
Backtrace: 
[<c00306c0>] (dump_backtrace+0x0/0x114) from [<c03b2e3c>] (dump_stack+0x18/0x1c)
 r7:00000318 r6:00000000 r5:000201da r4:c3540000
[<c03b2e24>] (dump_stack+0x0/0x1c) from [<c007c8b4>] (dump_header+0x80/0x18c)
[<c007c834>] (dump_header+0x0/0x18c) from [<c007cd38>] (oom_kill_process+0x50/0x280)
[<c007cce8>] (oom_kill_process+0x0/0x280) from [<c007d234>] (out_of_memory+0x2cc/0x388)
[<c007cf68>] (out_of_memory+0x0/0x388) from [<c0080690>] (__alloc_pages_nodemask+0x424/0x51c)
[<c008026c>] (__alloc_pages_nodemask+0x0/0x51c) from [<c00823a0>] (__do_page_cache_readahead+0xd8/0x2
30)
[<c00822c8>] (__do_page_cache_readahead+0x0/0x230) from [<c0082528>] (ra_submit+0x30/0x38)
[<c00824f8>] (ra_submit+0x0/0x38) from [<c00827d8>] (ondemand_readahead+0x1c8/0x1d8)
[<c0082610>] (ondemand_readahead+0x0/0x1d8) from [<c008292c>] (page_cache_sync_readahead+0x68/0x70)
[<c00828c4>] (page_cache_sync_readahead+0x0/0x70) from [<c007a658>] (filemap_fault+0x144/0x440)
 r5:c34d52f0 r4:00000000
[<c007a514>] (filemap_fault+0x0/0x440) from [<c00903e0>] (__do_fault+0x54/0x418)
[<c009038c>] (__do_fault+0x0/0x418) from [<c0091878>] (handle_mm_fault+0x350/0x800)
[<c0091528>] (handle_mm_fault+0x0/0x800) from [<c003207c>] (do_page_fault+0xf4/0x1e8)
[<c0031f88>] (do_page_fault+0x0/0x1e8) from [<c002c2d8>] (do_DataAbort+0x3c/0x9c)
[<c002c29c>] (do_DataAbort+0x0/0x9c) from [<c002cf04>] (ret_from_exception+0x0/0x10)
Exception stack(0xc3541fb0 to 0xc3541ff8)
1fa0:                                     0011b118 44953788 0001e3a8 4086ee10
1fc0: 0011b118 40565718 4086e36c 44953788 0011b118 44953788 00030180 000008a0
1fe0: 403d96b8 bed7dac4 408438b4 402b8908 80000010 ffffffff
 r8:0011b118 r7:44953788 r6:4086e36c r5:40565718 r4:ffffffff
Mem-info:
DMA per-cpu:
CPU    0: hi:   18, btch:   3 usd:  11
active_anon:12622 inactive_anon:22 isolated_anon:0
 active_file:45 inactive_file:119 isolated_file:0
 unevictable:257 dirty:0 writeback:0 unstable:0
 free:254 slab_reclaimable:157 slab_unreclaimable:685
 mapped:301 shmem:592 pagetables:84 bounce:0
DMA free:1016kB min:1016kB low:1268kB high:1524kB active_anon:50488kB inactive_anon:88kB active_file:
180kB inactive_file:476kB unevictable:1028kB isolated(anon):0kB isolated(file):0kB present:65024kB ml
ocked:1028kB dirty:0kB writeback:0kB mapped:1204kB shmem:2368kB slab_reclaimable:628kB slab_unreclaim
able:2740kB kernel_stack:432kB pagetables:336kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scann
ed:1057 all_unreclaimable? yes
lowmem_reserve[]: 0 0 0
DMA: 4*4kB 27*8kB 39*16kB 3*32kB 1*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 1016kB
756 total pagecache pages
0 pages in swap cache
Swap cache stats: add 0, delete 0, find 0/0
Free swap  = 0kB
Total swap = 0kB
16384 pages of RAM
355 free pages
1430 reserved pages
637 slab pages
321 pages shared
0 pages swap cached
[ pid ]   uid  tgid total_vm      rss cpu oom_adj oom_score_adj name
[  554]     0   554      489       41   0     -17         -1000 udevd
[ 1180]     0  1180      731       18   0       0             0 telnetd
[ 1183]     0  1183      746       29   0       0             0 syslogd
[ 1185]     0  1185      730       18   0       0             0 klogd
[ 1192]     0  1192      778       33   0       0             0 sh
[ 1258]     0  1258      576       60   0       0             0 thttpd
[13798]     0 13798      732       19   0       0             0 udhcpc
[13806]     0 13806      778       28   0       0             0 sh
[13808]     0 13808      779       28   0       0             0 top
[13837]     0 13837      731       17   0       0             0 testlargefile.s
[13840]     0 13840    26810    12304   0       0             0 gst-launch-0.10
Out of memory: Kill process 13840 (gst-launch-0.10) score 792 or sacrifice child
Killed process 13840 (gst-launch-0.10) total-vm:107240kB, anon-rss:48076kB, file-rss:1140kB
Killed

Wed Jun 13 11:44:06 HKT 2012

MemTotal:          59816 kB
MemFree:           47276 kB
Buffers:             300 kB
Cached:             4948 kB
SwapCached:            0 kB
Active:             4004 kB
Inactive:           2444 kB
Active(anon):       3480 kB
Inactive(anon):       88 kB
Active(file):        524 kB
Inactive(file):     2356 kB
Unevictable:           0 kB
Mlocked:               0 kB
SwapTotal:             0 kB
SwapFree:              0 kB
Dirty:                 0 kB
Writeback:             0 kB
AnonPages:          1224 kB
Mapped:              820 kB
Shmem:              2368 kB
Slab:               3356 kB
SReclaimable:        632 kB
SUnreclaim:         2724 kB
KernelStack:         400 kB
PageTables:          188 kB
NFS_Unstable:          0 kB
Bounce:                0 kB
WritebackTmp:          0 kB
CommitLimit:       29908 kB
Committed_AS:       6588 kB
VmallocTotal:     952320 kB
VmallocUsed:        1092 kB
VmallocChunk:     950140 kB
root@M1:/usr/tests# 
root@M1:/usr/tests# 
