[HLFS]check hlfs's hdfs approach with tapdisk
harryxiyou
=====
在测试tapdisk的hlfs的hdfs方式时，出现了一些问题，
我正在查找问题，以下是对照local方式时的log，local
方式是正确的。
tap-ctl create -a hlfs:local:///tmp/testenv/testfs的log,运行正常
==============================================
4416817 Nov 16 20:35:52 111 tap-ctl: enter func main
4416818 Nov 16 20:35:52 111 tap-ctl: enter func get_command
4416819 Nov 16 20:35:52 111 tap-ctl: main -- dbg pass 1
4416820 Nov 16 20:35:52 111 tap-ctl: enter func tap_ctl_check
4416821 Nov 16 20:35:52 111 tap-ctl: leave func tap_ctl_check
4416822 Nov 16 20:35:52 111 tap-ctl: main -- dbg pass 2
4416823 Nov 16 20:35:52 111 tap-ctl: main -- dbg pass 3
4416824 Nov 16 20:35:52 111 tap-ctl: enter func tap_cli_create
4416825 Nov 16 20:35:52 111 tap-ctl: enter func tap_ctl_create
4416826 Nov 16 20:35:53 111 tapdisk2: setenv return value is 0
4416827 Nov 16 20:35:53 111 tapdisk2[13831]: main -- dbg pass 1
4416828 Nov 16 20:35:53 111 tapdisk2[13831]: tapdisk_control_open -- dbg pass 1
4416829 Nov 16 20:35:53 111 tapdisk2[13831]: tapdisk_control_open -- dbg pass 2
4416830 Nov 16 20:35:53 111 tapdisk2[13831]:
tapdisk_control_create_socket -- dbg pass 1
4416831 Nov 16 20:35:53 111 tap-ctl: enter func tap_ctl_connect_send_and_receive
4416832 Nov 16 20:35:53 111 tapdisk2[13831]: enter func tapdisk_queue_init_io
4416833 Nov 16 20:35:53 111 tap-ctl: enter func tap_ctl_write_message
4416834 Nov 16 20:35:53 111 tapdisk2[13831]: I/O queue driver: lio
4416835 Nov 16 20:35:53 111 tap-ctl: tap_ctl_write_message -- msg.type
is 5, msg.cookie is 4, pid is 0 image {sectors: 0 sector_size: 0
info: 0 }
, p arams {flag: 0, storgae: 0, devnum: 0, domid: 0, pathlen:
0, path: } , string {text: } , minors {count: 0, } ,response {error:
0,
message: } , list{count: 0, minor: 0, state: 0, path: }
4416836 Nov 16 20:35:53 111 tapdisk2[13831]: leave func tapdisk_queue_init_io
4416837 Nov 16 20:35:53 111 tap-ctl: leave func tap_ctl_write_message
4416838 Nov 16 20:35:53 111 tapdisk2[13831]: main -- dbg pass 2
4416839 Nov 16 20:35:53 111 tap-ctl: enter func tap_ctl_read_message
4416840 Nov 16 20:35:53 111 tapdisk2[13831]: received 'attach' message
(uuid = 4)
4416841 Nov 16 20:35:53 111 tapdisk2[13831]:
tapdisk_control_write_message -- dbg pass 1
4416842 Nov 16 20:35:53 111 tapdisk2[13831]: sending 'attach response'
message (uuid = 4)
4416843 Nov 16 20:35:53 111 tap-ctl: tap_ctl_read_message -- msg.type
is 6, msg.cookie is 4, pid is 0 image {sectors: 0 sector_size: 0
info: 0 }
, pa rams {flag: 0, storgae: 0, devnum: 0, domid: 0, pathlen:
0, path: } , string {text: } , minors {count: 0, } ,response {error:
0,
message: } ,l ist{count: 0, minor: 0, state: 0, path: }
4416844 Nov 16 20:35:53 111 tap-ctl: leave func tap_ctl_read_message
4416845 Nov 16 20:35:53 111 tap-ctl: leave func tap_ctl_connect_send_and_receive
4416846 Nov 16 20:35:53 111 tap-ctl: enter func tap_ctl_open
4416847 Nov 16 20:35:53 111 tap-ctl: enter func tap_ctl_connect_send_and_receive
4416848 Nov 16 20:35:53 111 tap-ctl: enter func tap_ctl_write_message
4416849 Nov 16 20:35:53 111 tap-ctl: tap_ctl_write_message -- msg.type
is 7, msg.cookie is 4, pid is 512 image {sectors: 17179869696
sector_size:
0 i nfo: 1818755072 } , params {flag: 0, storgae: 2, devnum: 4,
domid: 0, pathlen: 0, path: hlfs:local:///tmp/testenv/testfs} , string
{text: } , minors {count: 512, } ,response {error: 512,
message: ^D} ,list{count: 512, minor: 4, state: 0, path: }
4416850 Nov 16 20:35:53 111 tap-ctl: leave func tap_ctl_write_message
4416851 Nov 16 20:35:53 111 tapdisk2[13831]: received 'open' message (uuid = 4)
4416852 Nov 16 20:35:53 111 tap-ctl: enter func tap_ctl_read_message
4416853 Nov 16 20:35:53 111 tapdisk2[13831]: enter func
tapdisk_control_open_image
4416854 Nov 16 20:35:53 111 tapdisk2[13831]: enter func tapdisk_vbd_parse_stack
4416855 Nov 16 20:35:53 111 tapdisk2[13831]: 77 enter func
tapdisk_parse_disk_type
4416856 Nov 16 20:35:53 111 tapdisk2[13831]: 77 enter func
tapdisk_disktype_parse_params
4416857 Nov 16 20:35:53 111 tapdisk2[13831]: 77 params is
hlfs:local:///tmp/testenv/testfs
4416858 Nov 16 20:35:53 111 tapdisk2[13831]: 77 params is
hlfs:local:///tmp/testenv/testfs
4416859 Nov 16 20:35:53 111 tapdisk2[13831]: 77 disktype name is hlfs
4416860 Nov 16 20:35:53 111 tapdisk2[13831]: enter func tapdisk_disktype_find
4416861 Nov 16 20:35:53 111 tapdisk2[13831]: 77 i is 0 info->name is aio
4416862 Nov 16 20:35:53 111 tapdisk2[13831]: 77 tapdisk_disk_types[11] is hlfs
4416863 Nov 16 20:35:53 111 tapdisk2[13831]: 77 i is 1 info->name is sync
4416864 Nov 16 20:35:53 111 tapdisk2[13831]: 77 tapdisk_disk_types[11] is hlfs
4416865 Nov 16 20:35:53 111 tapdisk2[13831]: 77 i is 2 info->name is vmdk
4416866 Nov 16 20:35:53 111 tapdisk2[13831]: 77 tapdisk_disk_types[11] is hlfs
4416867 Nov 16 20:35:53 111 tapdisk2[13831]: 77 i is 3 info->name is vhdsync
4416868 Nov 16 20:35:53 111 tapdisk2[13831]: 77 tapdisk_disk_types[11] is hlfs
4416869 Nov 16 20:35:53 111 tapdisk2[13831]: 77 i is 4 info->name is vhd
4416870 Nov 16 20:35:53 111 tapdisk2[13831]: 77 tapdisk_disk_types[11] is hlfs
4416871 Nov 16 20:35:53 111 tapdisk2[13831]: 77 i is 5 info->name is ram
4416872 Nov 16 20:35:53 111 tapdisk2[13831]: 77 tapdisk_disk_types[11] is hlfs
4416873 Nov 16 20:35:53 111 tapdisk2[13831]: 77 i is 6 info->name is qcow
4416874 Nov 16 20:35:53 111 tapdisk2[13831]: 77 tapdisk_disk_types[11] is hlfs
4416875 Nov 16 20:35:53 111 tapdisk2[13831]: 77 i is 7 info->name is bc
4416876 Nov 16 20:35:53 111 tapdisk2[13831]: 77 tapdisk_disk_types[11] is hlfs
4416877 Nov 16 20:35:53 111 tapdisk2[13831]: 77 i is 8 info->name is log
4416878 Nov 16 20:35:53 111 tapdisk2[13831]: 77 tapdisk_disk_types[11] is hlfs
4416879 Nov 16 20:35:53 111 tapdisk2[13831]: 77 i is 9 info->name is remus
4416880 Nov 16 20:35:53 111 tapdisk2[13831]: 77 tapdisk_disk_types[11] is hlfs
4416881 Nov 16 20:35:53 111 tapdisk2[13831]: 77 i is 11 info->name is hlfs
4416882 Nov 16 20:35:53 111 tapdisk2[13831]: 77 tapdisk_disk_types[11] is hlfs
4416883 Nov 16 20:35:53 111 tapdisk2[13831]: 77 1 i is 11
4416884 Nov 16 20:35:53 111 tapdisk2[13831]: 77 2 i is 11
4416885 Nov 16 20:35:53 111 tapdisk2[13831]: 77 type is 11
4416886 Nov 16 20:35:53 111 tapdisk2[13831]: 77 enter func
tapdisk_disktype_parse_params
4416887 Nov 16 20:35:53 111 tapdisk2[13831]: disktype is 11
4416888 Nov 16 20:35:53 111 tapdisk2[13831]: 77 leave func
tapdisk_parse_disk_type
4416889 Nov 16 20:35:53 111 tapdisk2[13831]: leave func tapdisk_vbd_parse_stack
4416890 Nov 16 20:35:53 111 tapdisk2[13831]: enter func tapdisk_vbd_open_stack
4416891 Nov 16 20:35:53 111 tapdisk2[13831]: enter func tdhlfs_open
4416892 Nov 16 20:35:53 111 tapdisk2[13831]: uri is local:///tmp/testenv/testfs
4416893 Nov 16 20:35:53 111 tapdisk2[13831]: info.size is 0
4416894 Nov 16 20:35:53 111 tapdisk2[13831]:
name:local:///tmp/testenv/testfs,uri:local:///tmp/testenv/testfs
4416895 Nov 16 20:35:53 111 tapdisk2[13831]: tdhlfs_open -- over init hlfs
4416896 Nov 16 20:35:53 111 tapdisk2[13831]: enter func build_ctrl_region
4416897 Nov 16 20:35:53 111 tapdisk2[13831]: 77 dbg ctrl_region_file
is /tmp/testfs-ctrl
4416898 Nov 16 20:35:54 111 tapdisk2[13831]: 77 1 dbg pass here
4416899 Nov 16 20:35:54 111 tapdisk2[13831]: 77 2 dbg pass here
4416900 Nov 16 20:35:54 111 tapdisk2[13831]: _SC_PAGE_SIZE is 4096
4416901 Nov 16 20:35:54 111 tapdisk2[13831]: 77 3 dbg pass here
4416902 Nov 16 20:35:54 111 tapdisk2[13831]: leave func build_ctrl_region
4416903 Nov 16 20:35:54 111 tapdisk2[13831]: mmap's addr is 0x7f97d14ce000
4416904 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_open -- enter
hlfs_set_user_ctrl_region
4416905 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_open --
is_start_clean is -1763539056, copy_waterlevel is 32767
4416906 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_open --
ctrl_region's addr is 0x7f97d14ce000
4416907 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_open --
ctrl->ctrl_region's addr is 0x7f97d14ce000
4416908 Nov 16 20:35:54 111 tapdisk2[13831]: enter func set_image_info
4416909 Nov 16 20:35:54 111 tapdisk2[13831]: Image sector_size: [512]
4416910 Nov 16 20:35:54 111 tapdisk2[13831]: leave func set_image_info
4416911 Nov 16 20:35:54 111 tapdisk2[13831]: leave func tdhlfs_open
4416912 Nov 16 20:35:54 111 tapdisk2[13831]: opened image
local:///tmp/testenv/testfs (1 users, state: 0x00000001, type: 11)
4416913 Nov 16 20:35:54 111 tapdisk2[13831]: enter func tdhlfs_get_parent_id
4416914 Nov 16 20:35:54 111 tapdisk2[13831]: leave func tdhlfs_get_parent_id
4416915 Nov 16 20:35:54 111 tapdisk2[13831]: VBD CHAIN:
4416916 Nov 16 20:35:54 111 tapdisk2[13831]: local:///tmp/testenv/testfs: 11
4416917 Nov 16 20:35:54 111 tapdisk2[13831]: leave func tapdisk_vbd_open_stack
4416918 Nov 16 20:35:54 111 tapdisk2[13831]:
tapdisk_control_write_message -- dbg pass 1
4416919 Nov 16 20:35:54 111 tapdisk2[13831]: sending 'open response'
message (uuid = 4)
4416920 Nov 16 20:35:54 111 kernel: block tde: sector-size: 512
capacity: 1374388224
4416921 Nov 16 20:35:54 111 tap-ctl: tap_ctl_read_message -- msg.type
is 8, msg.cookie is 4, pid is 1374388224 image {sectors: 1374388224
sector_size : 512 info: 0 } , params {flag: 0, storgae: 128,
devnum: 0, domid: 512, pathlen: 0, path: } , string {text: } , minors
{count:
1374388224, } , response {error: 1374388224, message: }
,list{count: 1374388224, minor: 0, state: 512, path: }
4416922 Nov 16 20:35:54 111 tapdisk2[13831]: enter func
tapdisk_control_open_image
4416923 Nov 16 20:35:54 111 tap-ctl: leave func tap_ctl_read_message
4416924 Nov 16 20:35:54 111 tap-ctl: leave func tap_ctl_connect_send_and_receive
4416925 Nov 16 20:35:54 111 tap-ctl: leave func tap_ctl_open
4416926 Nov 16 20:35:54 111 tap-ctl: leave func tap_ctl_create
4416927 Nov 16 20:35:54 111 tap-ctl: leave func tap_cli_create
4416928 Nov 16 20:35:54 111 tap-ctl: leave func main
4416929 Nov 16 20:35:54 111 tapdisk2[13831]: enter func tdhlfs_queue_read
4416930 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
enter td_complete_request second parameter is 0
4416931 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
is_start_clean is -1763539056, copy_waterlevel is 32767
4416932 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
ctrl->ctrl_region's addr is 0x7f97d14ce000
4416933 Nov 16 20:35:54 111 tapdisk2[13831]: leave func tdhlfs_queue_read
4416934 Nov 16 20:35:54 111 tapdisk2[13831]: enter func tdhlfs_queue_read
4416935 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
enter td_complete_request second parameter is 0
4416936 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
is_start_clean is -1763539056, copy_waterlevel is 32767
4416937 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
ctrl->ctrl_region's addr is 0x7f97d14ce000
4416938 Nov 16 20:35:54 111 tapdisk2[13831]: leave func tdhlfs_queue_read
4416939 Nov 16 20:35:54 111 tapdisk2[13831]: enter func tdhlfs_queue_read
4416940 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
enter td_complete_request second parameter is 0
4416941 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
is_start_clean is -1763539056, copy_waterlevel is 32767
4416942 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
ctrl->ctrl_region's addr is 0x7f97d14ce000
4416943 Nov 16 20:35:54 111 tapdisk2[13831]: leave func tdhlfs_queue_read
4416944 Nov 16 20:35:54 111 tapdisk2[13831]: enter func tdhlfs_queue_read
4416945 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
enter td_complete_request second parameter is 0
4416946 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
is_start_clean is -1763539056, copy_waterlevel is 32767
4416947 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
ctrl->ctrl_region's addr is 0x7f97d14ce000
4416948 Nov 16 20:35:54 111 tapdisk2[13831]: leave func tdhlfs_queue_read
4416949 Nov 16 20:35:54 111 tapdisk2[13831]: enter func tdhlfs_queue_read
4416950 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
enter td_complete_request second parameter is 0
4416951 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
is_start_clean is -1763539056, copy_waterlevel is 32767
4416952 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
ctrl->ctrl_region's addr is 0x7f97d14ce000
4416953 Nov 16 20:35:54 111 tapdisk2[13831]: leave func tdhlfs_queue_read
4416954 Nov 16 20:35:54 111 tapdisk2[13831]: enter func tdhlfs_queue_read
4416955 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
enter td_complete_request second parameter is 0
4416956 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
is_start_clean is -1763539056, copy_waterlevel is 32767
4416957 Nov 16 20:35:54 111 tapdisk2[13831]: tdhlfs_queue_read --
ctrl->ctrl_region's addr is 0x7f97d14ce000
......................................................
tap-ctl create -a hlfs:hdfs:///tmp/testenv/testfs的log,运行不正常
==============================================
4416200 Nov 16 20:24:05 111 tap-ctl: enter func main
4416201 Nov 16 20:24:05 111 tap-ctl: enter func get_command
4416202 Nov 16 20:24:05 111 tap-ctl: main -- dbg pass 1
4416203 Nov 16 20:24:05 111 tap-ctl: enter func tap_ctl_check
4416204 Nov 16 20:24:05 111 tap-ctl: leave func tap_ctl_check
4416205 Nov 16 20:24:05 111 tap-ctl: main -- dbg pass 2
4416206 Nov 16 20:24:05 111 tap-ctl: main -- dbg pass 3
4416207 Nov 16 20:24:05 111 tap-ctl: enter func tap_cli_create
4416208 Nov 16 20:24:05 111 tap-ctl: enter func tap_ctl_create
4416209 Nov 16 20:24:05 111 tapdisk2: setenv return value is 0
4416210 Nov 16 20:24:05 111 tapdisk2[13724]: main -- dbg pass 1
4416211 Nov 16 20:24:05 111 tapdisk2[13724]: tapdisk_control_open -- dbg pass 1
4416212 Nov 16 20:24:05 111 tapdisk2[13724]: tapdisk_control_open -- dbg pass 2
4416213 Nov 16 20:24:05 111 tapdisk2[13724]:
tapdisk_control_create_socket -- dbg pass 1
4416214 Nov 16 20:24:05 111 tapdisk2[13724]: enter func tapdisk_queue_init_io
4416215 Nov 16 20:24:05 111 tap-ctl: enter func tap_ctl_connect_send_and_receive
4416216 Nov 16 20:24:05 111 tapdisk2[13724]: I/O queue driver: lio
4416217 Nov 16 20:24:05 111 tap-ctl: enter func tap_ctl_write_message
4416218 Nov 16 20:24:05 111 tapdisk2[13724]: leave func tapdisk_queue_init_io
4416219 Nov 16 20:24:05 111 tap-ctl: tap_ctl_write_message -- msg.type
is 5, msg.cookie is 3, pid is 0 image {sectors: 0 sector_size: 0
info: 0 }
, p arams {flag: 0, storgae: 0, devnum: 0, domid: 0, pathlen:
0, path: } , string {text: } , minors {count: 0, } ,response {error:
0,
message: } , list{count: 0, minor: 0, state: 0, path: }
4416220 Nov 16 20:24:05 111 tapdisk2[13724]: main -- dbg pass 2
4416221 Nov 16 20:24:05 111 tap-ctl: leave func tap_ctl_write_message
4416222 Nov 16 20:24:05 111 tap-ctl: enter func tap_ctl_read_message
4416223 Nov 16 20:24:05 111 tapdisk2[13724]: received 'attach' message
(uuid = 3)
4416224 Nov 16 20:24:05 111 tapdisk2[13724]:
tapdisk_control_write_message -- dbg pass 1
4416225 Nov 16 20:24:05 111 tapdisk2[13724]: sending 'attach response'
message (uuid = 3)
4416226 Nov 16 20:24:05 111 tap-ctl: tap_ctl_read_message -- msg.type
is 6, msg.cookie is 3, pid is 0 image {sectors: 0 sector_size: 0
info: 0 }
, pa rams {flag: 0, storgae: 0, devnum: 0, domid: 0, pathlen:
0, path: } , string {text: } , minors {count: 0, } ,response {error:
0,
message: } ,l ist{count: 0, minor: 0, state: 0, path: }
4416227 Nov 16 20:24:05 111 tap-ctl: leave func tap_ctl_read_message
4416228 Nov 16 20:24:05 111 tap-ctl: leave func tap_ctl_connect_send_and_receive
4416229 Nov 16 20:24:05 111 tap-ctl: enter func tap_ctl_open
4416230 Nov 16 20:24:05 111 tap-ctl: enter func tap_ctl_connect_send_and_receive
4416231 Nov 16 20:24:05 111 tap-ctl: enter func tap_ctl_write_message
4416232 Nov 16 20:24:05 111 tap-ctl: tap_ctl_write_message -- msg.type
is 7, msg.cookie is 3, pid is 512 image {sectors: 12884902400
sector_size:
0 i nfo: 1818755072 } , params {flag: 0, storgae: 2, devnum: 3,
domid: 0, pathlen: 0, path: hlfs:hdfs:///tmp/testenv/testfs} , string
{text: } , m inors {count: 512, } ,response {error: 512,
message: ^C} ,list{count: 512, minor: 3, state: 0, path: }
4416233 Nov 16 20:24:05 111 tapdisk2[13724]: received 'open' message (uuid = 3)
4416234 Nov 16 20:24:05 111 tap-ctl: leave func tap_ctl_write_message
4416235 Nov 16 20:24:05 111 tapdisk2[13724]: enter func
tapdisk_control_open_image
4416236 Nov 16 20:24:05 111 tap-ctl: enter func tap_ctl_read_message
4416237 Nov 16 20:24:05 111 tapdisk2[13724]: enter func tapdisk_vbd_parse_stack
4416238 Nov 16 20:24:05 111 tapdisk2[13724]: 77 enter func
tapdisk_parse_disk_type
4416239 Nov 16 20:24:05 111 tapdisk2[13724]: 77 enter func
tapdisk_disktype_parse_params
4416240 Nov 16 20:24:05 111 tapdisk2[13724]: 77 params is
hlfs:hdfs:///tmp/testenv/testfs
4416241 Nov 16 20:24:06 111 tapdisk2[13724]: 77 params is
hlfs:hdfs:///tmp/testenv/testfs
4416242 Nov 16 20:24:06 111 tapdisk2[13724]: 77 disktype name is hlfs
4416243 Nov 16 20:24:06 111 tapdisk2[13724]: enter func tapdisk_disktype_find
4416244 Nov 16 20:24:06 111 tapdisk2[13724]: 77 i is 0 info->name is aio
4416243 Nov 16 20:24:06 111 tapdisk2[13724]: enter func tapdisk_disktype_find
4416244 Nov 16 20:24:06 111 tapdisk2[13724]: 77 i is 0 info->name is aio
4416245 Nov 16 20:24:06 111 tapdisk2[13724]: 77 tapdisk_disk_types[11] is hlfs
4416246 Nov 16 20:24:06 111 tapdisk2[13724]: 77 i is 1 info->name is sync
4416247 Nov 16 20:24:06 111 tapdisk2[13724]: 77 tapdisk_disk_types[11] is hlfs
4416248 Nov 16 20:24:06 111 tapdisk2[13724]: 77 i is 2 info->name is vmdk
4416249 Nov 16 20:24:06 111 tapdisk2[13724]: 77 tapdisk_disk_types[11] is hlfs
4416250 Nov 16 20:24:06 111 tapdisk2[13724]: 77 i is 3 info->name is vhdsync
4416251 Nov 16 20:24:06 111 tapdisk2[13724]: 77 tapdisk_disk_types[11] is hlfs
4416252 Nov 16 20:24:06 111 tapdisk2[13724]: 77 i is 4 info->name is vhd
4416253 Nov 16 20:24:06 111 tapdisk2[13724]: 77 tapdisk_disk_types[11] is hlfs
4416254 Nov 16 20:24:06 111 tapdisk2[13724]: 77 i is 5 info->name is ram
4416255 Nov 16 20:24:06 111 tapdisk2[13724]: 77 tapdisk_disk_types[11] is hlfs
4416256 Nov 16 20:24:06 111 tapdisk2[13724]: 77 i is 6 info->name is qcow
4416257 Nov 16 20:24:06 111 tapdisk2[13724]: 77 tapdisk_disk_types[11] is hlfs
4416258 Nov 16 20:24:06 111 tapdisk2[13724]: 77 i is 7 info->name is bc
4416259 Nov 16 20:24:06 111 tapdisk2[13724]: 77 tapdisk_disk_types[11] is hlfs
4416260 Nov 16 20:24:06 111 tapdisk2[13724]: 77 i is 8 info->name is log
4416261 Nov 16 20:24:06 111 tapdisk2[13724]: 77 tapdisk_disk_types[11] is hlfs
4416262 Nov 16 20:24:06 111 tapdisk2[13724]: 77 i is 9 info->name is remus
4416263 Nov 16 20:24:06 111 tapdisk2[13724]: 77 tapdisk_disk_types[11] is hlfs
4416264 Nov 16 20:24:06 111 tapdisk2[13724]: 77 i is 11 info->name is hlfs
4416265 Nov 16 20:24:06 111 tapdisk2[13724]: 77 tapdisk_disk_types[11] is hlfs
4416266 Nov 16 20:24:06 111 tapdisk2[13724]: 77 1 i is 11
4416267 Nov 16 20:24:06 111 tapdisk2[13724]: 77 2 i is 11
4416268 Nov 16 20:24:06 111 tapdisk2[13724]: 77 type is 11
4416269 Nov 16 20:24:06 111 tapdisk2[13724]: 77 enter func
tapdisk_disktype_parse_params
4416270 Nov 16 20:24:06 111 tapdisk2[13724]: disktype is 11
4416271 Nov 16 20:24:06 111 tapdisk2[13724]: 77 leave func
tapdisk_parse_disk_type
4416272 Nov 16 20:24:06 111 tapdisk2[13724]: leave func tapdisk_vbd_parse_stack
4416273 Nov 16 20:24:06 111 tapdisk2[13724]: enter func tapdisk_vbd_open_stack
4416274 Nov 16 20:24:06 111 tapdisk2[13724]: enter func tdhlfs_open
4416275 Nov 16 20:24:06 111 tapdisk2[13724]: uri is hdfs:///tmp/testenv/testfs
4416276 Nov 16 20:24:06 111 tapdisk2[13724]: info.size is 0
4416277 Nov 16 20:24:06 111 tapdisk2[13724]:
name:hdfs:///tmp/testenv/testfs,uri:hdfs:///tmp/testenv/testfs
4416278 Nov 16 20:24:11 111 tap-ctl: tap-err:tap_ctl_read_message:
failure reading message
4416279 Nov 16 20:24:11 111 tap-ctl: tap-err:tap_ctl_send_and_receive:
failed to receive 'unknown' message
4416280 Nov 16 20:24:11 111 tap-ctl: leave func tap_ctl_connect_send_and_receive
4416281 Nov 16 20:24:11 111 tap-ctl: enter func tap_ctl_connect_send_and_receive
4416282 Nov 16 20:24:12 111 tap-ctl: enter func tap_ctl_write_message
4416283 Nov 16 20:24:12 111 tap-ctl: tap_ctl_write_message -- msg.type
is 15, msg.cookie is 3, pid is 0 image {sectors: 0 sector_size: 0
info: 0
} , params {flag: 0, storgae: 0, devnum: 0, domid: 0, pathlen:
0, path: } , string {text: } , minors {count: 0, } ,response {error:
0,
message: } ,list{count: 0, minor: 0, state: 0, path: }
4416284 Nov 16 20:24:12 111 tap-ctl: leave func tap_ctl_write_message
4416285 Nov 16 20:24:12 111 tap-ctl: enter func tap_ctl_read_message
4416286 Nov 16 20:24:17 111 tap-ctl: tap-err:tap_ctl_read_message:
failure reading message
4416287 Nov 16 20:24:17 111 tap-ctl: tap-err:tap_ctl_send_and_receive:
failed to receive 'unknown' message
4416288 Nov 16 20:24:17 111 tap-ctl: leave func tap_ctl_connect_send_and_receive
4416289 Nov 16 20:24:17 111 tap-ctl: leave func tap_ctl_create
4416290 Nov 16 20:24:17 111 tap-ctl: leave func tap_cli_create
4416291 Nov 16 20:24:19 111 tap-ctl: leave func main
4416290 Nov 16 20:24:17 111 tap-ctl: leave func tap_cli_create
4416291 Nov 16 20:24:19 111 tap-ctl: leave func main
4416292 Nov 16 20:26:10 111 kernel: java invoked oom-killer:
gfp_mask=0x200da, order=0, oom_adj=0
4416293 Nov 16 20:26:11 111 kernel: Pid: 6362, comm: java Not tainted
2.6.32.39 #1
4416294 Nov 16 20:26:11 111 kernel: Call Trace:
4416295 Nov 16 20:26:11 111 kernel: [<ffffffff810a7866>] ?
oom_kill_process+0x71/0x217
4416296 Nov 16 20:26:11 111 kernel: [<ffffffff810a7c34>] ?
__out_of_memory+0x3a/0x12e
4416297 Nov 16 20:26:11 111 kernel: [<ffffffff810a7df0>] ?
out_of_memory+0xc8/0x125
4416298 Nov 16 20:26:11 111 kernel: [<ffffffff810aae28>] ?
__alloc_pages_nodemask+0x45e/0x56a
4416299 Nov 16 20:26:11 111 kernel: [<ffffffff810ca3a1>] ?
read_swap_cache_async+0x48/0xdf
4416300 Nov 16 20:26:11 111 kernel: [<ffffffff810ca48f>] ?
swapin_readahead+0x57/0x98
4416301 Nov 16 20:26:11 111 kernel: [<ffffffff8100c51d>] ?
__raw_callee_save_xen_pte_val+0x11/0x1e
4416302 Nov 16 20:26:11 111 kernel: [<ffffffff810bdcf3>] ?
handle_mm_fault+0x603/0xa0d
4416303 Nov 16 20:26:11 111 kernel: [<ffffffff8101361d>] ?
retint_restore_args+0x5/0x6
4416304 Nov 16 20:26:14 111 kernel: [<ffffffff8133603f>] ?
do_page_fault+0x279/0x326
4416305 Nov 16 20:26:14 111 kernel: [<ffffffff81333f65>] ? page_fault+0x25/0x30
4416306 Nov 16 20:26:14 111 kernel: Mem-Info:
4416307 Nov 16 20:26:14 111 kernel: Node 0 DMA per-cpu:
4416308 Nov 16 20:26:14 111 kernel: CPU 0: hi: 0, btch: 1 usd: 0
4416309 Nov 16 20:26:14 111 kernel: CPU 1: hi: 0, btch: 1 usd: 0
4416310 Nov 16 20:26:14 111 kernel: Node 0 DMA32 per-cpu:
4416311 Nov 16 20:26:14 111 kernel: CPU 0: hi: 186, btch: 31 usd: 68
4416312 Nov 16 20:26:14 111 kernel: CPU 1: hi: 186, btch: 31 usd: 30
4416313 Nov 16 20:26:14 111 kernel: Node 0 Normal per-cpu:
4416314 Nov 16 20:26:14 111 kernel: CPU 0: hi: 186, btch: 31 usd: 0
4416315 Nov 16 20:26:14 111 kernel: CPU 1: hi: 186, btch: 31 usd: 0
4416316 Nov 16 20:26:14 111 kernel: active_anon:278 inactive_anon:369
isolated_anon:343
4416317 Nov 16 20:26:14 111 kernel: active_file:45 inactive_file:120
isolated_file:0
4416318 Nov 16 20:26:14 111 kernel: unevictable:85329 dirty:1
writeback:449 unstable:0
4416319 Nov 16 20:26:14 111 kernel: free:4829 slab_reclaimable:1311
slab_unreclaimable:3326
4416320 Nov 16 20:26:14 111 kernel: mapped:3509 shmem:6
pagetables:2037 bounce:0
4416321 Nov 16 20:26:14 111 kernel: Node 0 DMA free:7804kB min:32kB
low:40kB high:48kB active_anon:0kB inactive_anon:68kB active_file:20kB
inactive_fi le:64kB unevictable:3580kB isolated(anon):92kB
isolated(file):0kB present:11772kB mlocked:3580kB dirty:0kB
writeback:60kB
mapped:40kB shmem:0k B slab_reclaimable:12kB
slab_unreclaimable:52kB kernel_stack:8kB pagetables:104kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:264 all_unreclaimable? yes
4416322 Nov 16 20:26:14 111 kernel: lowmem_reserve[]: 0 440 1945 1945
4416323 Nov 16 20:26:14 111 kernel: Node 0 DMA32 free:7556kB
min:1272kB low:1588kB high:1908kB active_anon:1112kB
inactive_anon:1408kB
active_file:160 kB inactive_file:416kB unevictable:337524kB
isolated(anon):1280kB isolated(file):0kB present:450784kB
mlocked:337524kB
dirty:4kB writeback:173 6kB mapped:13996kB shmem:24kB
slab_reclaimable:5196kB slab_unreclaimable:12612kB kernel_stack:2488kB
pagetables:8040kB unstable:0kB bounce:0kB writeback_tmp:0kB
pages_scanned:20455 all_unreclaimable? yes
4416324 Nov 16 20:26:14 111 kernel: lowmem_reserve[]: 0 0 1505
15054416325 Nov 16 20:26:14 111 kernel: Node 0 Normal free:3956kB
min:4352kB
low:5440kB high:6528kB active_anon:0kB inactive_anon:0kB
active_file:0kB inac tive_file:0kB unevictable:212kB
isolated(anon):0kB isolated
(file):0kB present:1541132kB mlocked:212kB dirty:0kB writeback:0kB
mapped:0kB shmem :0kB slab_reclaimable:36kB
slab_unreclaimable:640kB
kernel_stack:8kB pagetables:4kB unstable:0kB bounce:0kB
writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
4416326 Nov 16 20:26:14 111 kernel: lowmem_reserve[]: 0 0 0 0
4416327 Nov 16 20:26:14 111 kernel: Node 0 DMA: 9*4kB 5*8kB 2*16kB
1*32kB 4*64kB 2*128kB 2*256kB 3*512kB 3*1024kB 1*2048kB 0*4096kB =
7820kB
4416328 Nov 16 20:26:14 111 kernel: Node 0 DMA32: 319*4kB 75*8kB
39*16kB 16*32kB 37*64kB 1*128kB 0*256kB 0*512kB 0*1024kB 1*2048kB
0*4096kB =
7556kB
4416329 Nov 16 20:26:14 111 kernel: Node 0 Normal: 59*4kB 34*8kB
10*16kB 5*32kB 3*64kB 3*128kB 4*256kB 3*512kB 0*1024kB 0*2048kB
0*4096kB = 3964kB
4416330 Nov 16 20:26:36 111 kernel: 4467 total pagecache pages
4416331 Nov 16 20:26:38 111 kernel: 839 pages in swap cache
4416330 Nov 16 20:26:36 111 kernel: 4467 total pagecache pages
4416331 Nov 16 20:26:38 111 kernel: 839 pages in swap cache
4416332 Nov 16 20:26:38 111 kernel: Swap cache stats: add 10059985,
delete 10059146, find 3729690/4611653
4416333 Nov 16 20:26:39 111 kernel: Free swap = 1462812kB
4416334 Nov 16 20:26:39 111 kernel: Total swap = 1710912kB
4416335 Nov 16 20:26:39 111 kernel: 521696 pages RAM
4416336 Nov 16 20:26:39 111 kernel: 418351 pages reserved
4416337 Nov 16 20:26:39 111 kernel: 15711 pages shared
4416338 Nov 16 20:26:39 111 kernel: 93667 pages non-shared
4416339 Nov 16 20:26:39 111 kernel: Out of memory: kill process 6080
(su) score 356580 or a child
4416340 Nov 16 20:26:39 111 kernel: Killed process 6083 (java)
4416341 Nov 16 20:26:39 111 kernel: java invoked oom-killer:
gfp_mask=0x201da, order=0, oom_adj=0
4416342 Nov 16 20:26:39 111 kernel: Pid: 6748, comm: java Not tainted
2.6.32.39 #1
4416343 Nov 16 20:26:39 111 kernel: Call Trace:
4416344 Nov 16 20:26:39 111 kernel: [<ffffffff810a7866>] ?
oom_kill_process+0x71/0x217
4416345 Nov 16 20:26:40 111 kernel: [<ffffffff810a7c34>] ?
__out_of_memory+0x3a/0x12e
4416346 Nov 16 20:26:40 111 kernel: [<ffffffff810a7df0>] ?
out_of_memory+0xc8/0x125
4416347 Nov 16 20:26:40 111 kernel: [<ffffffff810aae28>] ?
__alloc_pages_nodemask+0x45e/0x56a
4416348 Nov 16 20:26:40 111 kernel: [<ffffffff8100bb0b>] ?
xen_load_gs_index+0xe/0x17
4416349 Nov 16 20:26:41 111 kernel: [<ffffffff810ace63>] ?
__do_page_cache_readahead+0x7c/0x161
4416350 Nov 16 20:26:41 111 kernel: [<ffffffff810acf64>] ? ra_submit+0x1c/0x20
4416351 Nov 16 20:26:41 111 kernel: [<ffffffff810a6e07>] ?
filemap_fault+0x194/0x31c
4416352 Nov 16 20:26:42 111 kernel: [<ffffffff810bb378>] ?
__do_fault+0x54/0x46a
4416353 Nov 16 20:26:43 111 kernel: [<ffffffff81332914>] ?
__wait_on_bit_lock+0x75/0x84
4416354 Nov 16 20:26:44 111 kernel: [<ffffffff8100cc58>] ?
pte_pfn_to_mfn+0x2c/0x6a
4416355 Nov 16 20:26:45 111 kernel: [<ffffffff810bdbfa>] ?
handle_mm_fault+0x50a/0xa0d
4416356 Nov 16 20:26:45 111 kernel: [<ffffffff8102e5f9>] ?
pvclock_clocksource_read+0x3a/0x8b
4416357 Nov 16 20:26:46 111 kernel: [<ffffffff8133603f>] ?
do_page_fault+0x279/0x326
4416358 Nov 16 20:26:46 111 kernel: [<ffffffff81333f65>] ? page_fault+0x25/0x30
4416359 Nov 16 20:26:46 111 kernel: Mem-Info:
4416360 Nov 16 20:26:47 111 kernel: Node 0 DMA per-cpu:
4416361 Nov 16 20:26:47 111 kernel: CPU 0: hi: 0, btch: 1 usd: 0
4416362 Nov 16 20:26:47 111 kernel: CPU 1: hi: 0, btch: 1 usd: 0
4416363 Nov 16 20:26:47 111 kernel: Node 0 DMA32 per-cpu:
4416364 Nov 16 20:26:47 111 kernel: CPU 0: hi: 186, btch: 31 usd: 37
4416365 Nov 16 20:26:47 111 kernel: CPU 1: hi: 186, btch: 31 usd: 48
4416366 Nov 16 20:26:47 111 kernel: Node 0 Normal per-cpu:
4416367 Nov 16 20:26:47 111 kernel: CPU 0: hi: 186, btch: 31 usd: 0
4416368 Nov 16 20:26:47 111 kernel: CPU 1: hi: 186, btch: 31 usd: 0
4416369 Nov 16 20:26:47 111 kernel: active_anon:304 inactive_anon:362
isolated_anon:311
4416370 Nov 16 20:26:47 111 kernel: active_file:47 inactive_file:136
isolated_file:0
4416371 Nov 16 20:26:47 111 kernel: unevictable:85329 dirty:0
writeback:349 unstable:0
4416372 Nov 16 20:26:47 111 kernel: free:4739 slab_reclaimable:1311
slab_unreclaimable:3326
4416373 Nov 16 20:26:47 111 kernel: mapped:3504 shmem:6
pagetables:2037 bounce:0
4416374 Nov 16 20:26:47 111 kernel: Node 0 DMA free:7800kB min:32kB
low:40kB high:48kB active_anon:12kB inactive_anon:32kB active_file:8kB
inactive_fi le:4kB unevictable:3580kB isolated(anon):92kB
isolated(file):0kB present:11772kB mlocked:3580kB dirty:0kB
writeback:28kB
mapped:8kB shmem:0kB slab_reclaimable:12kB
slab_unreclaimable:52kB kernel_stack:8kB pagetables:104kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:0 all_ unreclaimable? no
4416375 Nov 16 20:26:47 111 kernel: lowmem_reserve[]: 0 440 1945 1945
4416374 Nov 16 20:26:47 111 kernel: Node 0 DMA free:7800kB min:32kB
low:40kB high:48kB active_anon:12kB inactive_anon:32kB active_file:8kB
inactive_fi le:4kB unevictable:3580kB isolated(anon):92kB
isolated(file):0kB present:11772kB mlocked:3580kB dirty:0kB
writeback:28kB
mapped:8kB shmem:0kB slab_reclaimable:12kB
slab_unreclaimable:52kB kernel_stack:8kB pagetables:104kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:0 all_ unreclaimable? no
4416375 Nov 16 20:26:47 111 kernel: lowmem_reserve[]: 0 440 1945 1945
4416376 Nov 16 20:26:47 111 kernel: Node 0 DMA32 free:7192kB
min:1272kB low:1588kB high:1908kB active_anon:1204kB
inactive_anon:1416kB
active_file:180 kB inactive_file:540kB unevictable:337524kB
isolated(anon):1152kB isolated(file):0kB present:450784kB
mlocked:337524kB
dirty:0kB writeback:136 8kB mapped:14008kB shmem:24kB
slab_reclaimable:5196kB slab_unreclaimable:12612kB kernel_stack:2368kB
pagetables:8040kB unstable:0kB bounce:0kB writeback_tmp:0kB
pages_scanned:1856 all_unreclaimable? no
4416377 Nov 16 20:26:47 111 kernel: lowmem_reserve[]: 0 0 1505 1505
4416378 Nov 16 20:26:47 111 kernel: Node 0 Normal free:3964kB
min:4352kB low:5440kB high:6528kB active_anon:0kB inactive_anon:0kB
active_file:0kB
inac tive_file:0kB unevictable:212kB isolated(anon):0kB
isolated(file):0kB present:1541132kB mlocked:212kB dirty:0kB
writeback:0kB
mapped:0kB shmem :0kB slab_reclaimable:36kB
slab_unreclaimable:640kB kernel_stack:8kB pagetables:4kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
4416379 Nov 16 20:26:47 111 kernel: lowmem_reserve[]: 0 0 0 0
4416380 Nov 16 20:26:47 111 kernel: Node 0 DMA: 4*4kB 5*8kB 2*16kB
1*32kB 4*64kB 2*128kB 2*256kB 3*512kB 3*1024kB 1*2048kB 0*4096kB =
7800kB
4416381 Nov 16 20:26:47 111 kernel: Node 0 DMA32: 262*4kB 85*8kB
38*16kB 16*32kB 34*64kB 1*128kB 0*256kB 0*512kB 0*1024kB 1*2048kB
0*4096kB =
7200kB
4416382 Nov 16 20:26:47 111 kernel: Node 0 Normal: 59*4kB 34*8kB
10*16kB 5*32kB 3*64kB 3*128kB 4*256kB 3*512kB 0*1024kB 0*2048kB
0*4096kB = 3964kB
4416383 Nov 16 20:26:47 111 kernel: 4480 total pagecache pages
4416384 Nov 16 20:26:47 111 kernel: 841 pages in swap cache
4416385 Nov 16 20:26:47 111 kernel: Swap cache stats: add 10060175,
delete 10059334, find 3729702/4611708
4416386 Nov 16 20:26:47 111 kernel: Free swap = 1462760kB
4416387 Nov 16 20:26:47 111 kernel: Total swap = 1710912kB
4416388 Nov 16 20:26:47 111 kernel: 521696 pages RAM
4416389 Nov 16 20:26:47 111 kernel: 418351 pages reserved
4416390 Nov 16 20:26:47 111 kernel: 15705 pages shared
4416391 Nov 16 20:26:47 111 kernel: 93784 pages non-shared
4416392 Nov 16 20:26:47 111 kernel: Out of memory: kill process 6151
(su) score 347448 or a child
4416393 Nov 16 20:26:47 111 kernel: Killed process 6154 (java)
4416394 Nov 16 20:26:47 111 kernel: java invoked oom-killer:
gfp_mask=0x200da, order=0, oom_adj=0
4416395 Nov 16 20:26:47 111 kernel: Pid: 6345, comm: java Not tainted
2.6.32.39 #1
4416396 Nov 16 20:26:47 111 kernel: Call Trace:
4416397 Nov 16 20:26:47 111 kernel: [<ffffffff810a7866>] ?
oom_kill_process+0x71/0x217
4416398 Nov 16 20:26:47 111 kernel: [<ffffffff810a7c34>] ?
__out_of_memory+0x3a/0x12e
4416399 Nov 16 20:26:47 111 kernel: [<ffffffff810a7df0>] ?
out_of_memory+0xc8/0x125
4416400 Nov 16 20:26:47 111 kernel: [<ffffffff810aae28>] ?
__alloc_pages_nodemask+0x45e/0x56a
4416401 Nov 16 20:26:47 111 kernel: [<ffffffff8100fdf2>] ?
check_events+0x12/0x20
4416402 Nov 16 20:26:47 111 kernel: [<ffffffff810ca3a1>] ?
read_swap_cache_async+0x48/0xdf
4416403 Nov 16 20:26:47 111 kernel: [<ffffffff810ca48f>] ?
swapin_readahead+0x57/0x98
4416404 Nov 16 20:26:47 111 kernel: [<ffffffff8100c51d>] ?
__raw_callee_save_xen_pte_val+0x11/0x1e
4416405 Nov 16 20:26:47 111 kernel: [<ffffffff810bdcf3>] ?
handle_mm_fault+0x603/0xa0d
4416406 Nov 16 20:26:47 111 kernel: [<ffffffff8100f749>] ?
xen_force_evtchn_callback+0x9/0xa
4416407 Nov 16 20:26:47 111 kernel: [<ffffffff8133603f>] ?
do_page_fault+0x279/0x326
4416408 Nov 16 20:26:47 111 kernel: [<ffffffff81333f65>] ? page_fault+0x25/0x30
4416409 Nov 16 20:26:47 111 kernel: [<ffffffff81188dec>] ?
__get_user_4+0x1c/0x304416410 Nov 16 20:26:47 111 kernel:
[<ffffffff810718a8>] ?
compat_exit_robust_list+0x32/0x12e4416411 Nov 16 20:26:47 111 kernel:
[<ffffffff8100f749>] ? xen_force_evtchn_callback+0x9/0xa4416412 Nov 16
20:26:47 111 kernel: [<ffffffff8100fdf2>] ? check_events+0x12/0x20
4416413 Nov 16 20:26:47 111 kernel: [<ffffffff8104de40>] ? mm_release+0x43/0xfb
4416414 Nov 16 20:26:47 111 kernel: [<ffffffff8104ff21>] ?
exit_mm+0x1c/0x1014416415 Nov 16 20:26:47 111 kernel:
[<ffffffff81051300>] ?
do_exit+0x1da/0x667
4416414 Nov 16 20:26:47 111 kernel: [<ffffffff8104ff21>] ? exit_mm+0x1c/0x101
4416415 Nov 16 20:26:47 111 kernel: [<ffffffff81051300>] ? do_exit+0x1da/0x667
4416416 Nov 16 20:26:47 111 kernel: [<ffffffff810375a2>] ?
ptep_set_access_flags+0x46/0x4f
4416417 Nov 16 20:26:47 111 kernel: [<ffffffff8105180e>] ?
do_group_exit+0x81/0xab
4416418 Nov 16 20:26:47 111 kernel: [<ffffffff8105e366>] ?
get_signal_to_deliver+0x30c/0x33c
4416419 Nov 16 20:26:47 111 kernel: [<ffffffff81012137>] ?
do_notify_resume+0x87/0x756
4416420 Nov 16 20:26:47 111 kernel: [<ffffffff810836b8>] ?
audit_syscall_exit+0x312/0x331
4416421 Nov 16 20:26:48 111 kernel: [<ffffffff81012f0e>] ? int_signal+0x12/0x17
4416422 Nov 16 20:26:48 111 kernel: Mem-Info:
4416423 Nov 16 20:26:48 111 kernel: Node 0 DMA per-cpu:
4416424 Nov 16 20:26:48 111 kernel: CPU 0: hi: 0, btch: 1 usd: 0
4416425 Nov 16 20:26:48 111 kernel: CPU 1: hi: 0, btch: 1 usd: 0
4416426 Nov 16 20:26:48 111 kernel: Node 0 DMA32 per-cpu:
4416427 Nov 16 20:26:48 111 kernel: CPU 0: hi: 186, btch: 31 usd: 23
4416428 Nov 16 20:26:48 111 kernel: CPU 1: hi: 186, btch: 31 usd: 0
4416429 Nov 16 20:26:48 111 kernel: Node 0 Normal per-cpu:
4416430 Nov 16 20:26:48 111 kernel: CPU 0: hi: 186, btch: 31 usd: 0
4416431 Nov 16 20:26:48 111 kernel: CPU 1: hi: 186, btch: 31 usd: 0
4416432 Nov 16 20:26:48 111 kernel: active_anon:301 inactive_anon:377
isolated_anon:279
4416433 Nov 16 20:26:48 111 kernel: active_file:47 inactive_file:145
isolated_file:0
4416434 Nov 16 20:26:48 111 kernel: unevictable:85329 dirty:0
writeback:368 unstable:0
4416435 Nov 16 20:26:48 111 kernel: free:4821 slab_reclaimable:1311
slab_unreclaimable:3326
4416436 Nov 16 20:26:48 111 kernel: mapped:3504 shmem:6
pagetables:2037 bounce:0
4416437 Nov 16 20:26:48 111 kernel: Node 0 DMA free:7800kB min:32kB
low:40kB high:48kB active_anon:12kB inactive_anon:32kB active_file:8kB
inactive_fi le:84kB unevictable:3580kB isolated(anon):92kB
isolated(file):0kB present:11772kB mlocked:3580kB dirty:0kB
writeback:28kB
mapped:8kB shmem:0kB slab_reclaimable:12kB
slab_unreclaimable:52kB kernel_stack:8kB pagetables:104kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:189 a ll_unreclaimable? no
4416438 Nov 16 20:26:48 111 kernel: lowmem_reserve[]: 0 440 1945 1945
4416439 Nov 16 20:26:48 111 kernel: Node 0 DMA32 free:7552kB
min:1272kB low:1588kB high:1908kB active_anon:1192kB
inactive_anon:1476kB
active_file:180 kB inactive_file:496kB unevictable:337524kB
isolated(anon):1024kB isolated(file):0kB present:450784kB
mlocked:337524kB
dirty:0kB writeback:144 4kB mapped:14008kB shmem:24kB
slab_reclaimable:5196kB slab_unreclaimable:12612kB kernel_stack:2368kB
pagetables:8040kB unstable:0kB bounce:0kB writeback_tmp:0kB
pages_scanned:4224 all_unreclaimable? no
4416440 Nov 16 20:26:48 111 kernel: lowmem_reserve[]: 0 0 1505 1505
4416441 Nov 16 20:26:48 111 kernel: Node 0 Normal free:3932kB
min:4352kB low:5440kB high:6528kB active_anon:0kB inactive_anon:0kB
active_file:0kB
inac tive_file:0kB unevictable:212kB isolated(anon):0kB
isolated(file):0kB present:1541132kB mlocked:212kB dirty:0kB
writeback:0kB
mapped:0kB shmem :0kB slab_reclaimable:36kB
slab_unreclaimable:640kB kernel_stack:8kB pagetables:4kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
4416442 Nov 16 20:26:48 111 kernel: lowmem_reserve[]: 0 0 0 0
4416443 Nov 16 20:26:48 111 kernel: Node 0 DMA: 8*4kB 5*8kB 2*16kB
1*32kB 4*64kB 2*128kB 2*256kB 3*512kB 3*1024kB 1*2048kB 0*4096kB =
7816kB
4416444 Nov 16 20:26:48 111 kernel: Node 0 DMA32: 298*4kB 105*8kB
39*16kB 17*32kB 34*64kB 1*128kB 0*256kB 0*512kB 0*1024kB 1*2048kB
0*4096kB =
7552kB
4416445 Nov 16 20:26:48 111 kernel: Node 0 Normal: 51*4kB 34*8kB
10*16kB 5*32kB 3*64kB 3*128kB 4*256kB 3*512kB 0*1024kB 0*2048kB
0*4096kB = 3932kB
4416446 Nov 16 20:26:48 111 kernel: 4508 total pagecache pages
4416447 Nov 16 20:26:48 111 kernel: 840 pages in swap cache
4416448 Nov 16 20:26:48 111 kernel: Swap cache stats: add 10060289,
delete 10059449, find 3729708/4611750
4416449 Nov 16 20:26:48 111 kernel: Free swap = 1462732kB
4416450 Nov 16 20:26:48 111 kernel: Total swap = 1710912kB
4416451 Nov 16 20:26:48 111 kernel: 521696 pages RAM
4416452 Nov 16 20:26:48 111 kernel: 418351 pages reserved
4416453 Nov 16 20:26:48 111 kernel: 15685 pages shared
4416454 Nov 16 20:26:48 111 kernel: 93785 pages non-shared
4416455 Nov 16 20:26:48 111 kernel: Out of memory: kill process 13733
(java) score 299128 or a child
4416454 Nov 16 20:26:48 111 kernel: 93785 pages non-shared
4416455 Nov 16 20:26:48 111 kernel: Out of memory: kill process 13733
(java) score 299128 or a child
4416456 Nov 16 20:26:48 111 kernel: Killed process 13733 (java)
4416457 Nov 16 20:26:48 111 kernel: java invoked oom-killer:
gfp_mask=0x200da, order=0, oom_adj=0
4416458 Nov 16 20:26:48 111 kernel: Pid: 6277, comm: java Not tainted
2.6.32.39 #1
4416459 Nov 16 20:26:48 111 kernel: Call Trace:
4416460 Nov 16 20:26:48 111 kernel: [<ffffffff810a7866>] ?
oom_kill_process+0x71/0x217
4416461 Nov 16 20:26:48 111 kernel: [<ffffffff810a7c34>] ?
__out_of_memory+0x3a/0x12e
4416462 Nov 16 20:26:48 111 kernel: [<ffffffff810a7df0>] ?
out_of_memory+0xc8/0x125
4416463 Nov 16 20:26:48 111 kernel: [<ffffffff810aae28>] ?
__alloc_pages_nodemask+0x45e/0x56a
4416464 Nov 16 20:26:48 111 kernel: [<ffffffff8100fdf2>] ?
check_events+0x12/0x20
4416465 Nov 16 20:26:48 111 kernel: [<ffffffff810ca3a1>] ?
read_swap_cache_async+0x48/0xdf
4416466 Nov 16 20:26:48 111 kernel: [<ffffffff810ca4c1>] ?
swapin_readahead+0x89/0x98
4416467 Nov 16 20:26:48 111 kernel: [<ffffffff8100c51d>] ?
__raw_callee_save_xen_pte_val+0x11/0x1e
4416468 Nov 16 20:26:48 111 kernel: [<ffffffff810bdcf3>] ?
handle_mm_fault+0x603/0xa0d
4416469 Nov 16 20:26:48 111 kernel: [<ffffffff8133603f>] ?
do_page_fault+0x279/0x326
4416470 Nov 16 20:26:48 111 kernel: [<ffffffff81333f65>] ? page_fault+0x25/0x30
4416471 Nov 16 20:26:48 111 kernel: [<ffffffff81188dec>] ?
__get_user_4+0x1c/0x30
4416472 Nov 16 20:26:48 111 kernel: [<ffffffff810718a8>] ?
compat_exit_robust_list+0x32/0x12e
4416473 Nov 16 20:26:48 111 kernel: [<ffffffff8100f749>] ?
xen_force_evtchn_callback+0x9/0xa
4416474 Nov 16 20:26:48 111 kernel: [<ffffffff8100fdf2>] ?
check_events+0x12/0x20
4416475 Nov 16 20:26:48 111 kernel: [<ffffffff8104de40>] ? mm_release+0x43/0xfb
4416476 Nov 16 20:26:48 111 kernel: [<ffffffff8104ff21>] ? exit_mm+0x1c/0x101
4416477 Nov 16 20:26:48 111 kernel: [<ffffffff81051300>] ?
do_exit+0x1da/0x6674416478 Nov 16 20:26:48 111 kernel:
[<ffffffff810375a2>] ?
ptep_set_access_flags+0x46/0x4f4416479 Nov 16 20:26:48 111 kernel:
[<ffffffff8105180e>] ? do_group_exit+0x81/0xab4416480 Nov 16 20:26:48
111
kernel: [<ffffffff8105e366>] ? get_signal_to_deliver+0x30c/0x33c
4416481 Nov 16 20:26:48 111 kernel: [<ffffffff81012137>] ?
do_notify_resume+0x87/0x756
4416482 Nov 16 20:26:48 111 kernel: [<ffffffff810836b8>] ?
audit_syscall_exit+0x312/0x3314416483 Nov 16 20:26:48 111 kernel:
[<ffffffff81012f0e>] ? int_signal+0x12/0x174416484 Nov 16 20:26:48 111
kernel: Mem-Info:4416485 Nov 16 20:26:48 111 kernel: Node 0 DMA
per-cpu:
4416486 Nov 16 20:26:48 111 kernel: CPU 0: hi: 0, btch: 1 usd: 0
4416487 Nov 16 20:26:48 111 kernel: CPU 1: hi: 0, btch: 1 usd:
04416488 Nov 16 20:26:48 111 kernel: Node 0 DMA32 per-cpu:4416489
Nov 16
20:26:48 111 kernel: CPU 0: hi: 186, btch: 31 usd: 04416490 Nov
16 20:26:48 111 kernel: CPU 1: hi: 186, btch: 31 usd: 23
4416491 Nov 16 20:26:48 111 kernel: Node 0 Normal per-cpu:
4416492 Nov 16 20:26:48 111 kernel: CPU 0: hi: 186, btch: 31 usd: 0
4416493 Nov 16 20:26:48 111 kernel: CPU 1: hi: 186, btch: 31 usd: 0
4416494 Nov 16 20:26:48 111 kernel: active_anon:329 inactive_anon:395
isolated_anon:384
4416495 Nov 16 20:26:48 111 kernel: active_file:116 inactive_file:174
isolated_file:0
4416496 Nov 16 20:26:48 111 kernel: unevictable:85329 dirty:4
writeback:389 unstable:0
4416497 Nov 16 20:26:48 111 kernel: free:4913 slab_reclaimable:1281
slab_unreclaimable:3297
4416498 Nov 16 20:26:48 111 kernel: mapped:3592 shmem:6
pagetables:1990 bounce:0
4416499 Nov 16 20:26:48 111 kernel: Node 0 DMA free:7812kB min:32kB
low:40kB high:48kB active_anon:0kB inactive_anon:140kB active_file:0kB
inactive_fi le:108kB unevictable:3580kB isolated(anon):0kB
isolated(file):0kB present:11772kB mlocked:3580kB dirty:4kB
writeback:52kB
mapped:16kB shmem:0k B slab_reclaimable:8kB
slab_unreclaimable:56kB kernel_stack:8kB pagetables:104kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:0 all _unreclaimable? no
4416500 Nov 16 20:26:48 111 kernel: lowmem_reserve[]: 0 440 1945 1945
4416501 Nov 16 20:26:48 111 kernel: Node 0 DMA32 free:7884kB
min:1272kB low:1588kB high:1908kB active_anon:1316kB
inactive_anon:1436kB
active_file:464 kB inactive_file:588kB unevictable:337524kB
isolated(anon):1536kB isolated(file):0kB present:450784kB
mlocked:337524kB
dirty:12kB writeback:15 04kB mapped:14352kB shmem:24kB
slab_reclaimable:5084kB slab_unreclaimable:12484kB kernel_stack:1936kB
pagetables:7852kB unstable:0kB bounce:0k B writeback_tmp:0kB
pages_scanned:0 all_unreclaimable? no
4416502 Nov 16 20:26:48 111 kernel: lowmem_reserve[]: 0 0 1505 1505
4416503 Nov 16 20:26:48 111 kernel: Node 0 Normal free:3956kB
min:4352kB low:5440kB high:6528kB active_anon:0kB inactive_anon:4kB
active_file:0kB
inac tive_file:0kB unevictable:212kB isolated(anon):0kB
isolated(file):0kB present:1541132kB mlocked:212kB dirty:0kB
writeback:0kB
mapped:0kB shmem :0kB slab_reclaimable:32kB
slab_unreclaimable:648kB kernel_stack:8kB pagetables:4kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
4416504 Nov 16 20:26:48 111 kernel: lowmem_reserve[]: 0 0 0 0
4416505 Nov 16 20:26:48 111 kernel: Node 0 DMA: 1*4kB 5*8kB 3*16kB
1*32kB 4*64kB 2*128kB 2*256kB 3*512kB 3*1024kB 1*2048kB 0*4096kB =
7804kB
4416506 Nov 16 20:26:48 111 kernel: Node 0 DMA32: 485*4kB 143*8kB
38*16kB 17*32kB 23*64kB 1*128kB 0*256kB 0*512kB 0*1024kB 1*2048kB
0*4096kB =
7884kB
4416507 Nov 16 20:26:48 111 kernel: Node 0 Normal: 55*4kB 35*8kB
10*16kB 5*32kB 3*64kB 3*128kB 4*256kB 3*512kB 0*1024kB 0*2048kB
0*4096kB = 3956kB
4416508 Nov 16 20:26:48 111 kernel: 4708 total pagecache pages
4416509 Nov 16 20:26:48 111 kernel: 954 pages in swap cache
4416510 Nov 16 20:26:48 111 kernel: Swap cache stats: add 10062016,
delete 10061062, find 3729846/4612337
4416511 Nov 16 20:26:48 111 kernel: Free swap = 1487364kB
4416512 Nov 16 20:26:48 111 kernel: Total swap = 1710912kB
4416513 Nov 16 20:26:48 111 kernel: 521696 pages RAM
4416514 Nov 16 20:26:48 111 kernel: 418351 pages reserved
4416515 Nov 16 20:26:48 111 kernel: 16023 pages shared
4416516 Nov 16 20:26:48 111 kernel: 93495 pages non-shared
4416517 Nov 16 20:26:48 111 kernel: Out of memory: kill process 6321
(java) score 298302 or a child
4416518 Nov 16 20:26:48 111 kernel: Killed process 6321 (java)
4416519 Nov 16 20:26:48 111 kernel: su invoked oom-killer:
gfp_mask=0x200da, order=0, oom_adj=0
4416520 Nov 16 20:26:48 111 kernel: Pid: 6080, comm: su Not tainted 2.6.32.39 #1
4416521 Nov 16 20:26:48 111 kernel: Call Trace:
4416522 Nov 16 20:26:48 111 kernel: [<ffffffff810a7866>] ?
oom_kill_process+0x71/0x217
4416523 Nov 16 20:26:48 111 kernel: [<ffffffff810a7c34>] ?
__out_of_memory+0x3a/0x12e
4416524 Nov 16 20:26:48 111 kernel: [<ffffffff810a7df0>] ?
out_of_memory+0xc8/0x125
4416525 Nov 16 20:26:48 111 kernel: [<ffffffff810aae28>] ?
__alloc_pages_nodemask+0x45e/0x56a
4416526 Nov 16 20:26:48 111 kernel: [<ffffffff810ca3a1>] ?
read_swap_cache_async+0x48/0xdf
4416527 Nov 16 20:26:48 111 kernel: [<ffffffff810ca48f>] ?
swapin_readahead+0x57/0x98
4416528 Nov 16 20:26:48 111 kernel: [<ffffffff8100c51d>] ?
__raw_callee_save_xen_pte_val+0x11/0x1e
4416529 Nov 16 20:26:48 111 kernel: [<ffffffff810bdcf3>] ?
handle_mm_fault+0x603/0xa0d
4416530 Nov 16 20:26:48 111 kernel: [<ffffffff81103b52>] ?
inotify_d_instantiate+0x1a/0x41
4416531 Nov 16 20:26:48 111 kernel: [<ffffffff8133603f>] ?
do_page_fault+0x279/0x326
4416532 Nov 16 20:26:48 111 kernel: [<ffffffff81333f65>] ? page_fault+0x25/0x30
4416533 Nov 16 20:26:48 111 kernel: Mem-Info:
4416534 Nov 16 20:26:48 111 kernel: Node 0 DMA per-cpu:
4416535 Nov 16 20:26:48 111 kernel: CPU 0: hi: 0, btch: 1 usd: 0
4416536 Nov 16 20:26:48 111 kernel: CPU 1: hi: 0, btch: 1 usd: 0
4416537 Nov 16 20:26:48 111 kernel: Node 0 DMA32 per-cpu:
4416538 Nov 16 20:26:48 111 kernel: CPU 0: hi: 186, btch: 31 usd: 42
4416539 Nov 16 20:26:48 111 kernel: CPU 1: hi: 186, btch: 31 usd: 158
4416540 Nov 16 20:26:48 111 kernel: Node 0 Normal per-cpu:
4416539 Nov 16 20:26:48 111 kernel: CPU 1: hi: 186, btch: 31 usd: 158
4416540 Nov 16 20:26:48 111 kernel: Node 0 Normal per-cpu:
4416541 Nov 16 20:26:48 111 kernel: CPU 0: hi: 186, btch: 31 usd: 0
4416542 Nov 16 20:26:48 111 kernel: CPU 1: hi: 186, btch: 31 usd: 30
4416543 Nov 16 20:26:48 111 kernel: active_anon:309 inactive_anon:364
isolated_anon:319
4416544 Nov 16 20:26:48 111 kernel: active_file:46 inactive_file:151
isolated_file:1
4416545 Nov 16 20:26:49 111 kernel: unevictable:85842 dirty:13
writeback:392 unstable:0
4416546 Nov 16 20:26:49 111 kernel: free:4771 slab_reclaimable:1249
slab_unreclaimable:3256
4416547 Nov 16 20:26:49 111 kernel: mapped:3528 shmem:6
pagetables:1891 bounce:0
4416548 Nov 16 20:26:49 111 kernel: Node 0 DMA free:7804kB min:32kB
low:40kB high:48kB active_anon:0kB inactive_anon:116kB
active_file:28kB
inactive_f ile:56kB unevictable:3724kB isolated(anon):0kB
isolated(file):0kB present:11772kB mlocked:3724kB dirty:4kB
writeback:56kB
mapped:28kB shmem:0k B slab_reclaimable:8kB
slab_unreclaimable:56kB kernel_stack:8kB pagetables:104kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:0 all _unreclaimable? no
4416549 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 440 1945 1945
4416550 Nov 16 20:26:49 111 kernel: Node 0 DMA32 free:7444kB
min:1272kB low:1588kB high:1908kB active_anon:1236kB
inactive_anon:1340kB
active_file:156 kB inactive_file:548kB unevictable:339432kB
isolated(anon):1276kB isolated(file):4kB present:450784kB
mlocked:339432kB
dirty:48kB writeback:15 12kB mapped:14084kB shmem:24kB
slab_reclaimable:4964kB slab_unreclaimable:12312kB kernel_stack:1640kB
pagetables:7456kB unstable:0kB bounce:0k B writeback_tmp:0kB
pages_scanned:4395 all_unreclaimable? no
4416551 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 0 1505 1505
4416552 Nov 16 20:26:49 111 kernel: Node 0 Normal free:3836kB
min:4352kB low:5440kB high:6528kB active_anon:0kB inactive_anon:0kB
active_file:0kB
inac tive_file:0kB unevictable:212kB isolated(anon):0kB
isolated(file):0kB present:1541132kB mlocked:212kB dirty:0kB
writeback:0kB
mapped:0kB shmem :0kB slab_reclaimable:24kB
slab_unreclaimable:656kB kernel_stack:8kB pagetables:4kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
4416553 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 0 0 0
4416554 Nov 16 20:26:49 111 kernel: Node 0 DMA: 8*4kB 2*8kB 3*16kB
1*32kB 4*64kB 2*128kB 2*256kB 3*512kB 3*1024kB 1*2048kB 0*4096kB =
7808kB
4416555 Nov 16 20:26:49 111 kernel: Node 0 DMA32: 445*4kB 206*8kB
39*16kB 20*32kB 9*64kB 1*128kB 0*256kB 0*512kB 0*1024kB 1*2048kB
0*4096kB =
7444kB
4416556 Nov 16 20:26:49 111 kernel: Node 0 Normal: 25*4kB 35*8kB
10*16kB 5*32kB 3*64kB 3*128kB 4*256kB 3*512kB 0*1024kB 0*2048kB
0*4096kB = 3836kB
4416557 Nov 16 20:26:49 111 kernel: 4507 total pagecache pages
4416558 Nov 16 20:26:49 111 kernel: 836 pages in swap cache
4416559 Nov 16 20:26:49 111 kernel: Swap cache stats: add 10063514,
delete 10062678, find 3729987/4613005
4416560 Nov 16 20:26:49 111 kernel: Free swap = 1557888kB
4416561 Nov 16 20:26:49 111 kernel: Total swap = 1710912kB
4416562 Nov 16 20:26:49 111 kernel: 521696 pages RAM
4416563 Nov 16 20:26:49 111 kernel: 418351 pages reserved
4416564 Nov 16 20:26:49 111 kernel: 15751 pages shared
4416565 Nov 16 20:26:49 111 kernel: 93633 pages non-shared
4416566 Nov 16 20:26:49 111 kernel: Out of memory: kill process 6234
(java) score 297475 or a child
4416567 Nov 16 20:26:49 111 kernel: Killed process 6234 (java)
4416568 Nov 16 20:26:49 111 kernel: irqbalance invoked oom-killer:
gfp_mask=0x280da, order=0, oom_adj=0
4416569 Nov 16 20:26:49 111 kernel: Pid: 3058, comm: irqbalance Not
tainted 2.6.32.39 #1
4416570 Nov 16 20:26:49 111 kernel: Call Trace:
4416571 Nov 16 20:26:49 111 kernel: [<ffffffff810a7866>] ?
oom_kill_process+0x71/0x217
4416572 Nov 16 20:26:49 111 kernel: [<ffffffff810a7c34>] ?
__out_of_memory+0x3a/0x12e
4416573 Nov 16 20:26:49 111 kernel: [<ffffffff810a7df0>] ?
out_of_memory+0xc8/0x125
4416574 Nov 16 20:26:49 111 kernel: [<ffffffff810aae28>] ?
__alloc_pages_nodemask+0x45e/0x56a
4416575 Nov 16 20:26:49 111 kernel: [<ffffffff810bda42>] ?
handle_mm_fault+0x352/0xa0d
4416576 Nov 16 20:26:49 111 kernel: [<ffffffff8133603f>] ?
do_page_fault+0x279/0x326
4416577 Nov 16 20:26:49 111 kernel: [<ffffffff81333f65>] ? page_fault+0x25/0x30
4416578 Nov 16 20:26:49 111 kernel: [<ffffffff811889bd>] ?
copy_user_generic_string+0x2d/0x40
4416579 Nov 16 20:26:49 111 kernel: [<ffffffff810f3d5b>] ?
simple_read_from_buffer+0x3d/0x62
4416580 Nov 16 20:26:49 111 kernel: [<ffffffff81129b31>] ?
sysfs_read_file+0x108/0x12f
4416579 Nov 16 20:26:49 111 kernel: [<ffffffff810f3d5b>] ?
simple_read_from_buffer+0x3d/0x62
4416580 Nov 16 20:26:49 111 kernel: [<ffffffff81129b31>] ?
sysfs_read_file+0x108/0x12f
4416581 Nov 16 20:26:49 111 kernel: [<ffffffff810dc1de>] ? vfs_read+0xaa/0x146
4416582 Nov 16 20:26:49 111 kernel: [<ffffffff810dc572>] ? sys_read+0x45/0x6e
4416583 Nov 16 20:26:49 111 kernel: [<ffffffff81012c42>] ?
system_call_fastpath+0x16/0x1b
4416584 Nov 16 20:26:49 111 kernel: Mem-Info:
4416585 Nov 16 20:26:49 111 kernel: Node 0 DMA per-cpu:
4416586 Nov 16 20:26:49 111 kernel: CPU 0: hi: 0, btch: 1 usd: 0
4416587 Nov 16 20:26:49 111 kernel: CPU 1: hi: 0, btch: 1 usd: 0
4416588 Nov 16 20:26:49 111 kernel: Node 0 DMA32 per-cpu:4416589 Nov
16 20:26:49 111 kernel: CPU 0: hi: 186, btch: 31 usd: 624416590
Nov 16
20:26:49 111 kernel: CPU 1: hi: 186, btch: 31 usd: 1324416591 Nov
16 20:26:49 111 kernel: Node 0 Normal per-cpu:
4416592 Nov 16 20:26:49 111 kernel: CPU 0: hi: 186, btch: 31 usd: 0
4416593 Nov 16 20:26:49 111 kernel: CPU 1: hi: 186, btch: 31 usd:
304416594 Nov 16 20:26:49 111 kernel: active_anon:293
inactive_anon:336
isolated_anon:3194416595 Nov 16 20:26:49 111 kernel: active_file:54
inactive_file:225 isolated_file:14416596 Nov 16 20:26:49 111 kernel:
unevictable:85846 dirty:13 writeback:411 unstable:0
4416597 Nov 16 20:26:49 111 kernel: free:4716 slab_reclaimable:1248
slab_unreclaimable:3257
4416598 Nov 16 20:26:49 111 kernel: mapped:3507 shmem:6
pagetables:1891 bounce:04416599 Nov 16 20:26:49 111 kernel: Node 0 DMA
free:7808kB
min:32kB low:40kB high:48kB active_anon:0kB inactive_anon:92kB
active_file:28kB inactive_fi le:56kB unevictable:3716kB
isolated(anon):0kB
isolated(file):0kB present:11772kB mlocked:3716kB dirty:4kB
writeback:68kB mapped:24kB shmem:0kB slab_reclaimable:8kB
slab_unreclaimable:56kB kernel_stack:8kB pagetables:104kB unstable:0kB
bounce:0kB writeback_tmp:0kB pages_scanned:25 all
_unreclaimable? no
4416600 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 440 1945 1945
4416601 Nov 16 20:26:49 111 kernel: Node 0 DMA32 free:7220kB
min:1272kB low:1588kB high:1908kB active_anon:1172kB
inactive_anon:1252kB
active_file:188 kB inactive_file:844kB unevictable:339456kB
isolated(anon):1276kB isolated(file):4kB present:450784kB
mlocked:339456kB
dirty:48kB writeback:15 76kB mapped:14004kB shmem:24kB
slab_reclaimable:4960kB slab_unreclaimable:12312kB kernel_stack:1640kB
pagetables:7456kB unstable:0kB bounce:0k B writeback_tmp:0kB
pages_scanned:1472 all_unreclaimable? no
4416602 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 0 1505 1505
4416603 Nov 16 20:26:49 111 kernel: Node 0 Normal free:3836kB
min:4352kB low:5440kB high:6528kB active_anon:0kB inactive_anon:0kB
active_file:0kB
inac tive_file:0kB unevictable:212kB isolated(anon):0kB
isolated(file):0kB present:1541132kB mlocked:212kB dirty:0kB
writeback:0kB
mapped:0kB shmem :0kB slab_reclaimable:24kB
slab_unreclaimable:660kB kernel_stack:8kB pagetables:4kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
4416604 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 0 0 0
4416605 Nov 16 20:26:49 111 kernel: Node 0 DMA: 5*4kB 4*8kB 3*16kB
1*32kB 4*64kB 2*128kB 2*256kB 3*512kB 3*1024kB 1*2048kB 0*4096kB =
7812kB
4416606 Nov 16 20:26:49 111 kernel: Node 0 DMA32: 427*4kB 189*8kB
39*16kB 20*32kB 9*64kB 1*128kB 0*256kB 0*512kB 0*1024kB 1*2048kB
0*4096kB =
7236kB
4416607 Nov 16 20:26:49 111 kernel: Node 0 Normal: 25*4kB 35*8kB
10*16kB 5*32kB 3*64kB 3*128kB 4*256kB 3*512kB 0*1024kB 0*2048kB
0*4096kB = 3836kB
4416608 Nov 16 20:26:49 111 kernel: 4595 total pagecache pages
4416609 Nov 16 20:26:49 111 kernel: 817 pages in swap cache
4416610 Nov 16 20:26:49 111 kernel: Swap cache stats: add 10063568,
delete 10062751, find 3729995/4613026
4416611 Nov 16 20:26:49 111 kernel: Free swap = 1557872kB
4416612 Nov 16 20:26:49 111 kernel: Total swap = 1710912kB
4416613 Nov 16 20:26:49 111 kernel: 521696 pages RAM
4416614 Nov 16 20:26:49 111 kernel: 418351 pages reserved
4416615 Nov 16 20:26:49 111 kernel: 15739 pages shared
4416616 Nov 16 20:26:49 111 kernel: 93715 pages non-shared
4416617 Nov 16 20:26:49 111 kernel: Out of memory: kill process 6001
(su) score 262711 or a child
4416618 Nov 16 20:26:49 111 kernel: Killed process 6004 (java)
4416619 Nov 16 20:26:49 111 kernel: su invoked oom-killer:
gfp_mask=0x200da, order=0, oom_adj=0
4416620 Nov 16 20:26:49 111 kernel: Pid: 6151, comm: su Not tainted 2.6.32.39 #1
4416621 Nov 16 20:26:49 111 kernel: Call Trace:
4416622 Nov 16 20:26:49 111 kernel: [<ffffffff810a7866>] ?
oom_kill_process+0x71/0x217
4416623 Nov 16 20:26:49 111 kernel: [<ffffffff810a7c34>] ?
__out_of_memory+0x3a/0x12e
4416624 Nov 16 20:26:49 111 kernel: [<ffffffff810a7df0>] ?
out_of_memory+0xc8/0x125
4416625 Nov 16 20:26:49 111 kernel: [<ffffffff810aae28>] ?
__alloc_pages_nodemask+0x45e/0x56a
4416626 Nov 16 20:26:49 111 kernel: [<ffffffff8100f749>] ?
xen_force_evtchn_callback+0x9/0xa
4416627 Nov 16 20:26:49 111 kernel: [<ffffffff810ca3a1>] ?
read_swap_cache_async+0x48/0xdf
4416628 Nov 16 20:26:49 111 kernel: [<ffffffff810ca48f>] ?
swapin_readahead+0x57/0x98
4416629 Nov 16 20:26:49 111 kernel: [<ffffffff8100c51d>] ?
__raw_callee_save_xen_pte_val+0x11/0x1e
4416630 Nov 16 20:26:49 111 kernel: [<ffffffff810bdcf3>] ?
handle_mm_fault+0x603/0xa0d
4416631 Nov 16 20:26:49 111 kernel: [<ffffffff81103b52>] ?
inotify_d_instantiate+0x1a/0x41
4416632 Nov 16 20:26:49 111 kernel: [<ffffffff8133603f>] ?
do_page_fault+0x279/0x326
4416633 Nov 16 20:26:49 111 kernel: [<ffffffff81333f65>] ? page_fault+0x25/0x30
4416634 Nov 16 20:26:49 111 kernel: Mem-Info:
4416635 Nov 16 20:26:49 111 kernel: Node 0 DMA per-cpu:
4416636 Nov 16 20:26:49 111 kernel: CPU 0: hi: 0, btch: 1 usd: 0
4416637 Nov 16 20:26:49 111 kernel: CPU 1: hi: 0, btch: 1 usd: 0
4416638 Nov 16 20:26:49 111 kernel: Node 0 DMA32 per-cpu:
4416639 Nov 16 20:26:49 111 kernel: CPU 0: hi: 186, btch: 31 usd: 64
4416640 Nov 16 20:26:49 111 kernel: CPU 1: hi: 186, btch: 31 usd: 48
4416641 Nov 16 20:26:49 111 kernel: Node 0 Normal per-cpu:
4416642 Nov 16 20:26:49 111 kernel: CPU 0: hi: 186, btch: 31 usd: 30
4416643 Nov 16 20:26:49 111 kernel: CPU 1: hi: 186, btch: 31 usd: 30
4416644 Nov 16 20:26:49 111 kernel: active_anon:204 inactive_anon:413
isolated_anon:31
4416645 Nov 16 20:26:49 111 kernel: active_file:38 inactive_file:20
isolated_file:1
4416646 Nov 16 20:26:49 111 kernel: unevictable:86748 dirty:13
writeback:404 unstable:0
4416647 Nov 16 20:26:49 111 kernel: free:4773 slab_reclaimable:1199
slab_unreclaimable:3244
4416648 Nov 16 20:26:49 111 kernel: mapped:3488 shmem:6
pagetables:1772 bounce:0
4416649 Nov 16 20:26:49 111 kernel: Node 0 DMA free:7812kB min:32kB
low:40kB high:48kB active_anon:0kB inactive_anon:88kB active_file:8kB
inactive_fil e:0kB unevictable:3800kB isolated(anon):0kB
isolated(file):0kB present:11772kB mlocked:3800kB dirty:4kB
writeback:84kB
mapped:20kB shmem:0kB s lab_reclaimable:8kB
slab_unreclaimable:60kB kernel_stack:8kB pagetables:104kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:492 all_ unreclaimable? yes
4416650 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 440 1945 1945
4416651 Nov 16 20:26:49 111 kernel: Node 0 DMA32 free:7572kB
min:1272kB low:1588kB high:1908kB active_anon:816kB
inactive_anon:1564kB
active_file:144k B inactive_file:80kB unevictable:342980kB
isolated(anon):124kB isolated(file):4kB present:450784kB
mlocked:342980kB
dirty:48kB writeback:1532k B mapped:13932kB shmem:24kB
slab_reclaimable:4764kB slab_unreclaimable:12256kB kernel_stack:1288kB
pagetables:6984kB unstable:0kB bounce:0kB w riteback_tmp:0kB
pages_scanned:6924 all_unreclaimable? yes
4416652 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 0 1505 1505
4416653 Nov 16 20:26:49 111 kernel: Node 0 Normal free:3708kB
min:4352kB low:5440kB high:6528kB active_anon:0kB inactive_anon:0kB
active_file:0kB
inac tive_file:0kB unevictable:212kB isolated(anon):0kB
isolated(file):0kB present:1541132kB mlocked:212kB dirty:0kB
writeback:0kB
mapped:0kB shmem :0kB slab_reclaimable:24kB
slab_unreclaimable:660kB kernel_stack:8kB pagetables:0kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
4416654 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 0 0 0
4416655 Nov 16 20:26:49 111 kernel: Node 0 DMA: 3*4kB 5*8kB 3*16kB
1*32kB 4*64kB 2*128kB 2*256kB 3*512kB 3*1024kB 1*2048kB 0*4096kB =
7812kB
4416656 Nov 16 20:26:49 111 kernel: Node 0 DMA32: 609*4kB 244*8kB
19*16kB 4*32kB 9*64kB 1*128kB 0*256kB 0*512kB 0*1024kB 1*2048kB
0*4096kB =
7572kB
4416657 Nov 16 20:26:49 111 kernel: Node 0 Normal: 9*4kB 27*8kB
10*16kB 5*32kB 3*64kB 3*128kB 4*256kB 3*512kB 0*1024kB 0*2048kB
0*4096kB = 3708kB
4416658 Nov 16 20:26:49 111 kernel: 4092 total pagecache pages
4416659 Nov 16 20:26:49 111 kernel: 560 pages in swap cache
4416660 Nov 16 20:26:49 111 kernel: Swap cache stats: add 10063927,
delete 10063367, find 3730036/4613176
4416659 Nov 16 20:26:49 111 kernel: 560 pages in swap cache
4416660 Nov 16 20:26:49 111 kernel: Swap cache stats: add 10063927,
delete 10063367, find 3730036/4613176
4416661 Nov 16 20:26:49 111 kernel: Free swap = 1668860kB
4416662 Nov 16 20:26:49 111 kernel: Total swap = 1710912kB
4416663 Nov 16 20:26:49 111 kernel: 521696 pages RAM
4416664 Nov 16 20:26:49 111 kernel: 418351 pages reserved
4416665 Nov 16 20:26:49 111 kernel: 15211 pages shared
4416666 Nov 16 20:26:49 111 kernel: 94176 pages non-shared
4416667 Nov 16 20:26:49 111 kernel: Out of memory: kill process 4461
(xend) score 23877 or a child
4416668 Nov 16 20:26:49 111 kernel: Killed process 5854 (xend)
4416669 Nov 16 20:26:49 111 kernel: su invoked oom-killer:
gfp_mask=0x201da, order=0, oom_adj=0
4416670 Nov 16 20:26:49 111 kernel: Pid: 6080, comm: su Not tainted 2.6.32.39 #1
4416671 Nov 16 20:26:49 111 kernel: Call Trace:
4416672 Nov 16 20:26:49 111 kernel: [<ffffffff810a7866>] ?
oom_kill_process+0x71/0x217
4416673 Nov 16 20:26:49 111 kernel: [<ffffffff8100f749>] ?
xen_force_evtchn_callback+0x9/0xa
4416674 Nov 16 20:26:49 111 kernel: [<ffffffff810a7c34>] ?
__out_of_memory+0x3a/0x12e
4416675 Nov 16 20:26:49 111 kernel: [<ffffffff810a7df0>] ?
out_of_memory+0xc8/0x125
4416676 Nov 16 20:26:49 111 kernel: [<ffffffff810aae28>] ?
__alloc_pages_nodemask+0x45e/0x56a
4416677 Nov 16 20:26:49 111 kernel: [<ffffffff810ace63>] ?
__do_page_cache_readahead+0x7c/0x161
4416678 Nov 16 20:26:49 111 kernel: [<ffffffff810acf64>] ? ra_submit+0x1c/0x20
4416679 Nov 16 20:26:49 111 kernel: [<ffffffff810a6e07>] ?
filemap_fault+0x194/0x31c
4416680 Nov 16 20:26:49 111 kernel: [<ffffffff810bb378>] ?
__do_fault+0x54/0x46a
4416681 Nov 16 20:26:49 111 kernel: [<ffffffff81332914>] ?
__wait_on_bit_lock+0x75/0x84
4416682 Nov 16 20:26:49 111 kernel: [<ffffffff8100cc58>] ?
pte_pfn_to_mfn+0x2c/0x6a
4416683 Nov 16 20:26:49 111 kernel: [<ffffffff810bdbfa>] ?
handle_mm_fault+0x50a/0xa0d
4416684 Nov 16 20:26:49 111 kernel: [<ffffffff8133603f>] ?
do_page_fault+0x279/0x326
4416685 Nov 16 20:26:49 111 kernel: [<ffffffff81333f65>] ? page_fault+0x25/0x30
4416686 Nov 16 20:26:49 111 kernel: Mem-Info:
4416687 Nov 16 20:26:49 111 kernel: Node 0 DMA per-cpu:
4416688 Nov 16 20:26:49 111 kernel: CPU 0: hi: 0, btch: 1 usd: 0
4416689 Nov 16 20:26:49 111 kernel: CPU 1: hi: 0, btch: 1 usd:
04416690 Nov 16 20:26:49 111 kernel: Node 0 DMA32 per-cpu:4416691
Nov 16
20:26:49 111 kernel: CPU 0: hi: 186, btch: 31 usd: 304416692 Nov
16 20:26:49 111 kernel: CPU 1: hi: 186, btch: 31 usd: 26
4416693 Nov 16 20:26:49 111 kernel: Node 0 Normal per-cpu:
4416694 Nov 16 20:26:49 111 kernel: CPU 0: hi: 186, btch: 31 usd:
304416695 Nov 16 20:26:49 111 kernel: CPU 1: hi: 186, btch: 31
usd:
304416696 Nov 16 20:26:49 111 kernel: active_anon:204
inactive_anon:451 isolated_anon:31 4416697 Nov 16 20:26:49 111 kernel:
active_file:38
inactive_file:77 isolated_file:1
4416698 Nov 16 20:26:49 111 kernel: unevictable:86786 dirty:13
writeback:404 unstable:0
4416699 Nov 16 20:26:49 111 kernel: free:4680 slab_reclaimable:1199
slab_unreclaimable:32444416700 Nov 16 20:26:49 111 kernel:
mapped:3488
shmem:6 pagetables:1772 bounce:04416701 Nov 16 20:26:49 111 kernel:
Node 0 DMA free:7812kB min:32kB low:40kB high:48kB active_anon:0kB
inactive_anon:88kB active_file:8kB inactive_fil e:0kB
unevictable:3800kB isolated(anon):0kB isolated(file):0kB
present:11772kB
mlocked:3800kB dirty:4kB writeback:84kB mapped:20kB shmem:0kB s
lab_reclaimable:8kB slab_unreclaimable:60kB kernel_stack:8kB
pagetables:104kB unstable:0kB bounce:0kB writeback_tmp:0kB
pages_scanned:492 all_ unreclaimable? yes
4416702 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 440 1945 1945
4416703 Nov 16 20:26:49 111 kernel: Node 0 DMA32 free:7200kB
min:1272kB low:1588kB high:1908kB active_anon:816kB
inactive_anon:1716kB
active_file:144k B inactive_file:308kB unevictable:343132kB
isolated(anon):124kB isolated(file):4kB present:450784kB
mlocked:343132kB
dirty:48kB writeback:1532 kB mapped:13932kB shmem:24kB
slab_reclaimable:4764kB slab_unreclaimable:12256kB kernel_stack:1288kB
pagetables:6984kB unstable:0kB bounce:0kB writeback_tmp:0kB
pages_scanned:6924 all_unreclaimable? yes
4416704 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 0 1505 1505
4416705 Nov 16 20:26:49 111 kernel: Node 0 Normal free:3708kB
min:4352kB low:5440kB high:6528kB active_anon:0kB inactive_anon:0kB
active_file:0kB
inac tive_file:0kB unevictable:212kB isolated(anon):0kB
isolated(file):0kB present:1541132kB mlocked:212kB dirty:0kB
writeback:0kB
mapped:0kB shmem :0kB slab_reclaimable:24kB
slab_unreclaimable:660kB kernel_stack:8kB pagetables:0kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
4416706 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 0 0 0
4416707 Nov 16 20:26:49 111 kernel: Node 0 DMA: 3*4kB 5*8kB 3*16kB
1*32kB 4*64kB 2*128kB 2*256kB 3*512kB 3*1024kB 1*2048kB 0*4096kB =
7812kB
4416708 Nov 16 20:26:49 111 kernel: Node 0 DMA32: 558*4kB 241*8kB
18*16kB 2*32kB 8*64kB 1*128kB 0*256kB 0*512kB 0*1024kB 1*2048kB
0*4096kB =
7200kB
4416709 Nov 16 20:26:49 111 kernel: Node 0 Normal: 9*4kB 27*8kB
10*16kB 5*32kB 3*64kB 3*128kB 4*256kB 3*512kB 0*1024kB 0*2048kB
0*4096kB = 3708kB
4416710 Nov 16 20:26:49 111 kernel: 4206 total pagecache pages
4416711 Nov 16 20:26:49 111 kernel: 614 pages in swap cache
4416712 Nov 16 20:26:49 111 kernel: Swap cache stats: add 10063982,
delete 10063368, find 3730039/4613184
4416713 Nov 16 20:26:49 111 kernel: Free swap = 1668864kB
4416714 Nov 16 20:26:49 111 kernel: Total swap = 1710912kB
4416715 Nov 16 20:26:49 111 kernel: 521696 pages RAM
4416716 Nov 16 20:26:49 111 kernel: 418351 pages reserved
4416717 Nov 16 20:26:49 111 kernel: 15217 pages shared
4416718 Nov 16 20:26:49 111 kernel: 94320 pages non-shared
4416719 Nov 16 20:26:49 111 kernel: Out of memory: kill process 4461
(xend) score 21515 or a child
4416720 Nov 16 20:26:49 111 kernel: Killed process 5855 (xend)
4416721 Nov 16 20:26:49 111 kernel: sshd invoked oom-killer:
gfp_mask=0x200da, order=0, oom_adj=0
4416722 Nov 16 20:26:49 111 kernel: Pid: 4550, comm: sshd Not tainted
2.6.32.39 #1
4416723 Nov 16 20:26:49 111 kernel: Call Trace:
4416724 Nov 16 20:26:49 111 kernel: [<ffffffff810a7866>] ?
oom_kill_process+0x71/0x217
4416725 Nov 16 20:26:49 111 kernel: [<ffffffff810a7c34>] ?
__out_of_memory+0x3a/0x12e
4416726 Nov 16 20:26:49 111 kernel: [<ffffffff810a7df0>] ?
out_of_memory+0xc8/0x125
4416727 Nov 16 20:26:49 111 kernel: [<ffffffff810aae28>] ?
__alloc_pages_nodemask+0x45e/0x56a
4416728 Nov 16 20:26:49 111 kernel: [<ffffffff810ca3a1>] ?
read_swap_cache_async+0x48/0xdf
4416729 Nov 16 20:26:49 111 kernel: [<ffffffff810ca4c1>] ?
swapin_readahead+0x89/0x98
4416730 Nov 16 20:26:49 111 kernel: [<ffffffff8100c51d>] ?
__raw_callee_save_xen_pte_val+0x11/0x1e
4416731 Nov 16 20:26:49 111 kernel: [<ffffffff810bdcf3>] ?
handle_mm_fault+0x603/0xa0d
4416732 Nov 16 20:26:49 111 kernel: [<ffffffff8133603f>] ?
do_page_fault+0x279/0x326
4416733 Nov 16 20:26:49 111 kernel: [<ffffffff81333f65>] ? page_fault+0x25/0x30
4416734 Nov 16 20:26:49 111 kernel: Mem-Info:
4416735 Nov 16 20:26:49 111 kernel: Node 0 DMA per-cpu:
4416736 Nov 16 20:26:49 111 kernel: CPU 0: hi: 0, btch: 1 usd: 0
4416737 Nov 16 20:26:49 111 kernel: CPU 1: hi: 0, btch: 1 usd: 0
4416738 Nov 16 20:26:49 111 kernel: Node 0 DMA32 per-cpu:
4416739 Nov 16 20:26:49 111 kernel: CPU 0: hi: 186, btch: 31 usd: 30
4416740 Nov 16 20:26:49 111 kernel: CPU 1: hi: 186, btch: 31 usd: 47
4416741 Nov 16 20:26:49 111 kernel: Node 0 Normal per-cpu:
4416742 Nov 16 20:26:49 111 kernel: CPU 0: hi: 186, btch: 31 usd: 0
4416743 Nov 16 20:26:49 111 kernel: CPU 1: hi: 186, btch: 31 usd: 30
4416744 Nov 16 20:26:49 111 kernel: active_anon:204 inactive_anon:398
isolated_anon:31
4416745 Nov 16 20:26:49 111 kernel: active_file:38 inactive_file:70
isolated_file:1
4416746 Nov 16 20:26:49 111 kernel: unevictable:86786 dirty:13
writeback:404 unstable:0
4416745 Nov 16 20:26:49 111 kernel: active_file:38 inactive_file:70
isolated_file:1
4416746 Nov 16 20:26:49 111 kernel: unevictable:86786 dirty:13
writeback:404 unstable:0
4416747 Nov 16 20:26:49 111 kernel: free:4775 slab_reclaimable:1199
slab_unreclaimable:3244
4416748 Nov 16 20:26:49 111 kernel: mapped:3488 shmem:6
pagetables:1772 bounce:0
4416749 Nov 16 20:26:49 111 kernel: Node 0 DMA free:7812kB min:32kB
low:40kB high:48kB active_anon:0kB inactive_anon:88kB active_file:8kB
inactive_fil e:0kB unevictable:3800kB isolated(anon):0kB
isolated(file):0kB present:11772kB mlocked:3800kB dirty:4kB
writeback:84kB
mapped:20kB shmem:0kB s lab_reclaimable:8kB
slab_unreclaimable:60kB kernel_stack:8kB pagetables:104kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:120 all_ unreclaimable? no
4416750 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 440 1945 1945
4416751 Nov 16 20:26:49 111 kernel: Node 0 DMA32 free:7464kB
min:1272kB low:1588kB high:1908kB active_anon:816kB
inactive_anon:1504kB
active_file:144k B inactive_file:280kB unevictable:343132kB
isolated(anon):124kB isolated(file):4kB present:450784kB
mlocked:343132kB
dirty:48kB writeback:1532 kB mapped:13932kB shmem:24kB
slab_reclaimable:4764kB slab_unreclaimable:12256kB kernel_stack:1288kB
pagetables:6984kB unstable:0kB bounce:0kB writeback_tmp:0kB
pages_scanned:1728 all_unreclaimable? no
4416752 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 0 1505 1505
4416753 Nov 16 20:26:49 111 kernel: Node 0 Normal free:3824kB
min:4352kB low:5440kB high:6528kB active_anon:0kB inactive_anon:0kB
active_file:0kB
inac tive_file:0kB unevictable:212kB isolated(anon):0kB
isolated(file):0kB present:1541132kB mlocked:212kB dirty:0kB
writeback:0kB
mapped:0kB shmem :0kB slab_reclaimable:24kB
slab_unreclaimable:660kB kernel_stack:8kB pagetables:0kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
4416754 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 0 0 0
4416755 Nov 16 20:26:49 111 kernel: Node 0 DMA: 3*4kB 5*8kB 3*16kB
1*32kB 4*64kB 2*128kB 2*256kB 3*512kB 3*1024kB 1*2048kB 0*4096kB =
7812kB
4416756 Nov 16 20:26:49 111 kernel: Node 0 DMA32: 576*4kB 259*8kB
21*16kB 2*32kB 8*64kB 1*128kB 0*256kB 0*512kB 0*1024kB 1*2048kB
0*4096kB =
7464kB
4416757 Nov 16 20:26:49 111 kernel: Node 0 Normal: 22*4kB 35*8kB
10*16kB 5*32kB 3*64kB 3*128kB 4*256kB 3*512kB 0*1024kB 0*2048kB
0*4096kB = 3824kB
4416758 Nov 16 20:26:49 111 kernel: 4149 total pagecache pages
4416759 Nov 16 20:26:49 111 kernel: 559 pages in swap cache
4416760 Nov 16 20:26:49 111 kernel: Swap cache stats: add 10063985,
delete 10063426, find 3730039/4613185
4416761 Nov 16 20:26:49 111 kernel: Free swap = 1668888kB
4416762 Nov 16 20:26:49 111 kernel: Total swap = 1710912kB
4416763 Nov 16 20:26:49 111 kernel: 521696 pages RAM
4416764 Nov 16 20:26:49 111 kernel: 418351 pages reserved
4416765 Nov 16 20:26:49 111 kernel: 15219 pages shared
4416766 Nov 16 20:26:49 111 kernel: 94238 pages non-shared
4416767 Nov 16 20:26:49 111 kernel: Out of memory: kill process 13725
(tapdisk2) score 8506 or a child
4416768 Nov 16 20:26:49 111 kernel: Killed process 13725 (tapdisk2)
4416769 Nov 16 20:26:49 111 kernel: automount invoked oom-killer:
gfp_mask=0x200da, order=0, oom_adj=0
4416770 Nov 16 20:26:49 111 kernel: Pid: 13734, comm: automount Not
tainted 2.6.32.39 #1
4416771 Nov 16 20:26:49 111 kernel: Call Trace:
4416772 Nov 16 20:26:49 111 kernel: [<ffffffff810a7866>] ?
oom_kill_process+0x71/0x217
4416773 Nov 16 20:26:49 111 kernel: [<ffffffff810a7c34>] ?
__out_of_memory+0x3a/0x12e
4416774 Nov 16 20:26:49 111 kernel: [<ffffffff810a7df0>] ?
out_of_memory+0xc8/0x125
4416775 Nov 16 20:26:49 111 kernel: [<ffffffff810aae28>] ?
__alloc_pages_nodemask+0x45e/0x56a
4416776 Nov 16 20:26:49 111 kernel: [<ffffffff810ca3a1>] ?
read_swap_cache_async+0x48/0xdf
4416777 Nov 16 20:26:49 111 kernel: [<ffffffff810ca4c1>] ?
swapin_readahead+0x89/0x98
4416778 Nov 16 20:26:49 111 kernel: [<ffffffff8100c51d>] ?
__raw_callee_save_xen_pte_val+0x11/0x1e
4416779 Nov 16 20:26:49 111 kernel: [<ffffffff810bdcf3>] ?
handle_mm_fault+0x603/0xa0d
4416780 Nov 16 20:26:49 111 kernel: [<ffffffff8133603f>] ?
do_page_fault+0x279/0x326
4416781 Nov 16 20:26:49 111 kernel: [<ffffffff81333f65>] ? page_fault+0x25/0x30
4416782 Nov 16 20:26:49 111 kernel: Mem-Info:
4416783 Nov 16 20:26:49 111 kernel: Node 0 DMA per-cpu:
4416784 Nov 16 20:26:49 111 kernel: CPU 0: hi: 0, btch: 1 usd: 0
4416785 Nov 16 20:26:49 111 kernel: CPU 1: hi: 0, btch: 1 usd: 0
4416786 Nov 16 20:26:49 111 kernel: Node 0 DMA32 per-cpu:
4416785 Nov 16 20:26:49 111 kernel: CPU 1: hi: 0, btch: 1 usd: 0
4416786 Nov 16 20:26:49 111 kernel: Node 0 DMA32 per-cpu:
4416787 Nov 16 20:26:49 111 kernel: CPU 0: hi: 186, btch: 31 usd: 93
4416788 Nov 16 20:26:49 111 kernel: CPU 1: hi: 186, btch: 31 usd: 34
4416789 Nov 16 20:26:49 111 kernel: Node 0 Normal per-cpu: 4416790 Nov
16 20:26:49 111 kernel: CPU 0: hi: 186, btch: 31 usd: 04416791
Nov
16 20:26:49 111 kernel: CPU 1: hi: 186, btch: 31 usd: 304416792
Nov 16 20:26:49 111 kernel: active_anon:8982 inactive_anon:59534
isolated_anon:63
4416793 Nov 16 20:26:49 111 kernel: active_file:57 inactive_file:99
isolated_file:1
4416794 Nov 16 20:26:49 111 kernel: unevictable:18837 dirty:5
writeback:408 unstable:04416795 Nov 16 20:26:49 111 kernel: free:4742
slab_reclaimable:1190 slab_unreclaimable:32444416796 Nov 16 20:26:49
111 kernel: mapped:3503 shmem:6 pagetables:1697 bounce:04416797 Nov
16
20:26:49 111 kernel: Node 0 DMA free:7812kB min:32kB low:40kB
high:48kB active_anon:0kB inactive_anon:3864kB active_file:0kB
inactive_f
ile:4kB unevictable:24kB isolated(anon):0kB isolated(file):0kB
present:11772kB mlocked:24kB dirty:4kB writeback:84kB mapped:20kB
shmem:0kB sla
b_reclaimable:8kB slab_unreclaimable:60kB kernel_stack:8kB
pagetables:104kB unstable:0kB bounce:0kB writeback_tmp:0kB
pages_scanned:186 all_un
reclaimable? yes4416798 Nov 16 20:26:49 111 kernel:
lowmem_reserve[]: 0 440 1945 19454416799 Nov 16 20:26:49 111 kernel:
Node 0 DMA32
free:7336kB min:1272kB low:1588kB high:1908kB active_anon:37448kB
inactive_anon:232488kB active_file: 228kB inactive_file:392kB
unevictable:75360kB isolated(anon):252kB isolated(file):4kB
present:450784kB mlocked:75360kB dirty:16kB writeback:15 48kB
mapped:13992kB
shmem:24kB slab_reclaimable:4728kB slab_unreclaimable:12240kB
kernel_stack:1232kB pagetables:6684kB unstable:0kB bounce:0k B
writeback_tmp:0kB pages_scanned:13754 all_unreclaimable? no
4416800 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 0 1505 1505
4416801 Nov 16 20:26:49 111 kernel: Node 0 Normal free:3820kB
min:4352kB low:5440kB high:6528kB active_anon:0kB inactive_anon:248kB
active_file:0kB in active_file:0kB unevictable:0kB
isolated(anon):0kB isolated(file):0kB present:1541132kB mlocked:0kB
dirty:0kB
writeback:0kB mapped:0kB shmem:0 kB slab_reclaimable:24kB
slab_unreclaimable:676kB kernel_stack:8kB pagetables:0kB unstable:0kB
bounce:0kB
writeback_tmp:0kB pages_scanned:0 al l_unreclaimable? yes
4416802 Nov 16 20:26:49 111 kernel: lowmem_reserve[]: 0 0 0 0
4416803 Nov 16 20:26:49 111 kernel: Node 0 DMA: 3*4kB 5*8kB 3*16kB
1*32kB 4*64kB 2*128kB 2*256kB 3*512kB 3*1024kB 1*2048kB 0*4096kB =
7812kB
4416804 Nov 16 20:26:49 111 kernel: Node 0 DMA32: 600*4kB 253*8kB
20*16kB 1*32kB 6*64kB 1*128kB 0*256kB 0*512kB 0*1024kB 1*2048kB
0*4096kB =
7336kB
4416805 Nov 16 20:26:49 111 kernel: Node 0 Normal: 21*4kB 35*8kB
10*16kB 5*32kB 3*64kB 3*128kB 4*256kB 3*512kB 0*1024kB 0*2048kB
0*4096kB = 3820kB
4416806 Nov 16 20:26:49 111 kernel: 4247 total pagecache pages
4416807 Nov 16 20:26:49 111 kernel: 556 pages in swap cache
4416808 Nov 16 20:26:49 111 kernel: Swap cache stats: add 10063999,
delete 10063443, find 3730040/4613195
4416809 Nov 16 20:26:49 111 kernel: Free swap = 1677780kB
4416810 Nov 16 20:26:49 111 kernel: Total swap = 1710912kB
4416811 Nov 16 20:26:49 111 kernel: 521696 pages RAM
4416812 Nov 16 20:26:49 111 kernel: 418351 pages reserved
4416813 Nov 16 20:26:49 111 kernel: 15154 pages shared
4416814 Nov 16 20:26:49 111 kernel: 94213 pages non-shared
4416815 Nov 16 20:26:49 111 kernel: Out of memory: kill process 4086
(avahi-daemon) score 5822 or a child
4416816 Nov 16 20:26:49 111 kernel: Killed process 4086 (avahi-daemon)
--
Thanks
Harry Wei
harryxiyou
> 总述
> =====
> 在测试tapdisk的hlfs的hdfs方式时，出现了一些问题，
> 我正在查找问题，以下是对照local方式时的log，local
> 方式是正确的。
>
>
> tap-ctl create -a hlfs:local:///tmp/testenv/testfs的log,运行正常
> ==============================================
> 4416817 Nov 16 20:35:52 111 tap-ctl: enter func main
> 4416818 Nov 16 20:35:52 111 tap-ctl: enter func get_command
> 4416819 Nov 16 20:35:52 111 tap-ctl: main -- dbg pass 1
> 4416820 Nov 16 20:35:52 111 tap-ctl: enter func tap_ctl_check
> 4416821 Nov 16 20:35:52 111 tap-ctl: leave func tap_ctl_check
> 4416822 Nov 16 20:35:52 111 tap-ctl: main -- dbg pass 2
>
>
>
通过我的调试，发现问题出现在
int tap_ctl_read_message(.......) 函数。
43 int
44 tap_ctl_read_message(int fd, tapdisk_message_t *message, int timeout)
45 {
46 fd_set readfds;
47 int ret, len, offset;
48 struct timeval tv, *t;
49
50 DPRINTF("enter func %s\n", __func__);
51 t = NULL;
52 offset = 0;
53 len = sizeof(tapdisk_message_t);
54
55 if (timeout) {
56 tv.tv_sec = timeout;
57 tv.tv_usec = 0;
58 t = &tv;
59 }
60
61 memset(message, 0, sizeof(tapdisk_message_t));
62
63 while (offset < len) {
64 FD_ZERO(&readfds);
65 FD_SET(fd, &readfds);
66
67 ret = select(fd + 1, &readfds, NULL, NULL, t);
68 DPRINTF("%s -- fd is %d, out while ret is %d, offset is
%d\n", __func__, fd, ret, offset);
69 if (ret == -1)
70 break;
71 else if (FD_ISSET(fd, &readfds)) {
72 ret = read(fd, message + offset, len - offset);
73 DPRINTF("%s -- fd is %d, in while ret is %d, offset is
%d\n", __func__, fd, ret, offset);
74 if (ret <= 0)
75 break;
76 offset += ret;
77 } else {
78 DPRINTF("%s -- fd is %d, else branch ret is %d, offset
is %d\n", __func__, fd, ret, offset);
79 break;
80 }
81 }
tap-ctl create -a hlfs:hdfs:///tmp/testenv/testfs
==================================
如上代码对应的log如下：
[snip]
4419298 Nov 16 21:30:58 111 tapdisk2[21539]: uri is hdfs:///tmp/testenv/testfs
4419299 Nov 16 21:30:58 111 tapdisk2[21539]: info.size is 0
4419300 Nov 16 21:30:58 111 tapdisk2[21539]:
name:hdfs:///tmp/testenv/testfs,uri:hdfs:///tmp/testenv/testfs
4419301 Nov 16 21:31:03 111 tap-ctl: tap_ctl_read_message -- fd is 4,
out while ret is 0, offset is 0
4419302 Nov 16 21:31:03 111 tap-ctl: tap_ctl_read_message -- fd is 4,
else branch ret is 0, offset is 0
4419303 Nov 16 21:31:03 111 tap-ctl: tap_ctl_read_message -- offset is
0, len is 280
4419304 Nov 16 21:31:03 111 tap-ctl: tap-err:tap_ctl_read_message:
failure reading message
4419305 Nov 16 21:31:03 111 tap-ctl: tap-err:tap_ctl_send_and_receive:
failed to receive 'unknown' message
4419306 Nov 16 21:31:03 111 tap-ctl: leave func tap_ctl_connect_send_and_receive
4419307 Nov 16 21:31:03 111 tap-ctl: enter func tap_ctl_connect_send_and_receive
4419308 Nov 16 21:31:03 111 tap-ctl: enter func tap_ctl_write_message
4419309 Nov 16 21:31:03 111 tap-ctl: tap_ctl_write_message -- msg.type
is 15, msg.cookie is 7, pid is 0 image {sectors: 0 sector_size: 0
info: 0 } , params {flag: 0, storgae: 0, devnum: 0, domid: 0,
pathlen: 0, path: } , string {text: } , minors {count: 0, } ,response
{error: 0, message: } ,list{count: 0, minor: 0, state: 0,
path: }
4419310 Nov 16 21:31:03 111 tap-ctl: leave func tap_ctl_write_message
4419311 Nov 16 21:31:03 111 tap-ctl: enter func tap_ctl_read_message
4419312 Nov 16 21:31:08 111 tap-ctl: tap_ctl_read_message -- fd is 4,
out while ret is 0, offset is 0
4419313 Nov 16 21:31:08 111 tap-ctl: tap_ctl_read_message -- fd is 4,
else branch ret is 0, offset is 0
4419314 Nov 16 21:31:09 111 tap-ctl: tap_ctl_read_message -- offset is
0, len is 280
4419315 Nov 16 21:31:09 111 tap-ctl: tap-err:tap_ctl_read_message:
failure reading message
4419316 Nov 16 21:31:11 111 tap-ctl: tap-err:tap_ctl_send_and_receive:
failed to receive 'unknown' message
4419317 Nov 16 21:31:25 111 tap-ctl: leave func tap_ctl_connect_send_and_receive
4419318 Nov 16 21:31:45 111 tap-ctl: leave func tap_ctl_create
[snip]
tap-ctl create -a hlfs:local:///tmp/testenv/testfs
==================================
如上代码对应的log如下：
4419924 Nov 16 21:53:20 111 tapdisk2[21647]:
name:local:///tmp/testenv/testfs,uri:local:///tmp/testenv/testfs
4419925 Nov 16 21:53:20 111 tapdisk2[21647]: tdhlfs_open -- over init hlfs
4419926 Nov 16 21:53:20 111 tapdisk2[21647]: enter func build_ctrl_region
4419927 Nov 16 21:53:20 111 tapdisk2[21647]: 77 dbg ctrl_region_file
is /tmp/testfs-ctrl
4419928 Nov 16 21:53:20 111 tapdisk2[21647]: 77 1 dbg pass here
4419929 Nov 16 21:53:20 111 tapdisk2[21647]: 77 2 dbg pass here
4419930 Nov 16 21:53:20 111 tapdisk2[21647]: _SC_PAGE_SIZE is 4096
4419931 Nov 16 21:53:20 111 tapdisk2[21647]: 77 3 dbg pass here
4419932 Nov 16 21:53:20 111 tapdisk2[21647]: leave func build_ctrl_region
4419933 Nov 16 21:53:20 111 tapdisk2[21647]: mmap's addr is 0x7fc72be51000
4419934 Nov 16 21:53:20 111 tapdisk2[21647]: tdhlfs_open -- enter
hlfs_set_user_ctrl_region
4419935 Nov 16 21:53:20 111 tapdisk2[21647]: tdhlfs_open --
is_start_clean is -1171986624, copy_waterlevel is 32767
4419936 Nov 16 21:53:20 111 tapdisk2[21647]: tdhlfs_open --
ctrl_region's addr is 0x7fc72be51000
4419937 Nov 16 21:53:20 111 tapdisk2[21647]: tdhlfs_open --
ctrl->ctrl_region's addr is 0x7fc72be51000
4419938 Nov 16 21:53:20 111 tapdisk2[21647]: enter func set_image_info
4419939 Nov 16 21:53:20 111 tapdisk2[21647]: Image sector_size: [512]
4419940 Nov 16 21:53:20 111 tapdisk2[21647]: leave func set_image_info
4419941 Nov 16 21:53:20 111 tapdisk2[21647]: leave func tdhlfs_open
4419942 Nov 16 21:53:20 111 tapdisk2[21647]: opened image
local:///tmp/testenv/testfs (1 users, state: 0x00000001, type: 11)
4419943 Nov 16 21:53:20 111 tapdisk2[21647]: enter func tdhlfs_get_parent_id
4419944 Nov 16 21:53:20 111 tapdisk2[21647]: leave func tdhlfs_get_parent_id
4419945 Nov 16 21:53:20 111 tapdisk2[21647]: VBD CHAIN:
4419946 Nov 16 21:53:20 111 tapdisk2[21647]: local:///tmp/testenv/testfs: 11
4419947 Nov 16 21:53:20 111 tapdisk2[21647]: leave func tapdisk_vbd_open_stack
4419948 Nov 16 21:53:20 111 tapdisk2[21647]:
tapdisk_control_write_message -- dbg pass 1
4419949 Nov 16 21:53:20 111 tapdisk2[21647]: sending 'open response'
message (uuid = 7)
4419950 Nov 16 21:53:20 111 kernel: block tdh: sector-size: 512
capacity: 1374388224
4419951 Nov 16 21:53:20 111 tap-ctl: tap_ctl_read_message -- fd is 4,
out while ret is 1, offset is 0
4419952 Nov 16 21:53:20 111 tapdisk2[21647]: enter func
tapdisk_control_open_image
4419953 Nov 16 21:53:20 111 tap-ctl: tap_ctl_read_message -- fd is 4,
in while ret is 280, offset is 0
4419954 Nov 16 21:53:20 111 tapdisk2[21647]: enter func tdhlfs_queue_read
4419955 Nov 16 21:53:20 111 tap-ctl: tap_ctl_read_message -- offset is
280, len is 280
4419956 Nov 16 21:53:20 111 tapdisk2[21647]: tdhlfs_queue_read --
enter td_complete_request second parameter is 0
4419957 Nov 16 21:53:20 111 tap-ctl: tap_ctl_read_message -- msg.type
is 8, msg.cookie is 7, pid is 1374388224 image {sectors: 1374388224
sector_size : 512 info: 0 } , params {flag: 0, storgae: 128,
devnum: 0, domid: 512, pathlen: 0, path: } , string {text: } , minors
{count: 1374388224, } , response {error: 1374388224, message: }
,list{count: 1374388224, minor: 0, state: 512, path: }
4419958 Nov 16 21:53:20 111 tapdisk2[21647]: tdhlfs_queue_read --
is_start_clean is -1171986624, copy_waterlevel is 32767
4419959 Nov 16 21:53:20 111 tap-ctl: leave func tap_ctl_read_message
4419960 Nov 16 21:53:20 111 tapdisk2[21647]: tdhlfs_queue_read --
ctrl->ctrl_region's addr is 0x7fc72be51000
4419961 Nov 16 21:53:20 111 tap-ctl: leave func tap_ctl_connect_send_and_receive
4419962 Nov 16 21:53:20 111 tapdisk2[21647]: leave func tdhlfs_queue_read
4419963 Nov 16 21:53:20 111 tap-ctl: leave func tap_ctl_open
4419964 Nov 16 21:53:20 111 tapdisk2[21647]: enter func tdhlfs_queue_read
4419965 Nov 16 21:53:20 111 tap-ctl: leave func tap_ctl_create
4419966 Nov 16 21:53:20 111 tapdisk2[21647]: tdhlfs_queue_read --
enter td_complete_request second parameter is 0
4419967 Nov 16 21:53:20 111 tap-ctl: leave func tap_cli_create
4419968 Nov 16 21:53:20 111 tapdisk2[21647]: tdhlfs_queue_read --
is_start_clean is -1171986624, copy_waterlevel is 32767
4419969 Nov 16 21:53:20 111 tap-ctl: leave func main
4419970 Nov 16 21:53:20 111 tapdisk2[21647]: tdhlfs_queue_read --
ctrl->ctrl_region's addr is 0x7fc72be51000
4419971 Nov 16 21:53:20 111 tapdisk2[21647]: leave func tdhlfs_queue_read
总结
=====
hdfs 方式的 socket通信出现了问题。我怀疑是hdfs本身的干扰，
导致了socket通信失败，原因是hdfs方式完成的时候，hadoop
自动关闭了。
[root@111 /hlfs/build]$ps aux | grep hadoop
root 21673 0.0 0.1 61168 764 pts/5 S+ 21:58 0:00 grep
--color=auto hadoop
--
Thanks
Harry Wei