On Tue, 2014-02-04 at 08:39 +0100, Svante Signell wrote:
> On Tue, 2014-02-04 at 08:14 +0100, Svante Signell wrote:
> > Hi,
> >
> > BTW: The error messages
> > end_request: I/O error, dev 02:00, sector 0
> > on the console are coming from this call.
> >
> > From the backtrace in thread 4 we see that the source pointer for memcpy
> ^thread 3
> > is 0x0. I'm not so sure the problem is with sysvinit, it might as well
> > be a libc bug.
> >
> > I managed to run gdb on a non-stripped version of boodlogd:
> ^bootlogd
Attaching an rpctrace.
task42(pid6527)->vm_statistics () = 0 {4096 178180 37201 30467 11514 792460 0
208254 191539 5405130 546239 673293 641856}
task42(pid6527)->vm_region (134217728) = 0 134512640 12288 5 7 1 0
49<--47(pid6527) 0
task42(pid6527)->mach_port_deallocate (pn{ 3}) = 0
task42(pid6527)->vm_region (134524928) = 0 134524928 4096 3 7 1 0
49<--50(pid6527) 8192
task42(pid6527)->mach_port_deallocate (pn{ 3}) = 0
task42(pid6527)->vm_region (134529024) = 0 134529024 4096 3 7 1 0
49<--47(pid6527) 0
task42(pid6527)->mach_port_deallocate (pn{ 3}) = 0
task42(pid6527)->vm_region (134533120) = 0 134533120 32768 3 7 1 0 (null) 0
task42(pid6527)->mach_port_deallocate (pn{ 0}) = 0xf ((os/kern) invalid name)
task42(pid6527)->vm_region (134565888) = 0x3 ((os/kern) no space available)
task42(pid6527)->vm_map (134565888 2012917760 0 0 (null) 0 1 0 0 1) = 0
134565888
task42(pid6527)->vm_map (0 4096 0 0 (null) 0 1 0 0 1) = 0 0
task42(pid6527)->task_get_special_port (4) = 0 49<--50(pid6527)
49<--50(pid6527)->exec_startup_get_info () = 0 134518338 134512692 288 172032
16777216 0 "/home/srs/sysvinit/sysvinit.git/debian/tmp/sbin/bootlogd"
"QUILT_REFRESH_ARGS=-p ab --no-timestamps --no-index" { 8<--47(pid6527)
22<--52(pid6527) 22<--52(pid6527)} { 6<--53(pid6527) 5<--54(pid6527)
20<--55(pid6527) 51<--56(pid6527) 32<--57(pid6527) (null)} {18 0 0 0 0}
task42(pid6527)->mach_port_deallocate (pn{ 3}) = 0
task42(pid6527)->vm_deallocate (134565888 2012917760) = 0
task42(pid6527)->vm_map (0 4096 0 1 (null) 0 0 3 7 1) = 0 16969728
5<--54(pid6527)->dir_lookup ("etc/ld.so.cache" 1 0) = 0 1 ""
49<--58(pid6527)
49<--58(pid6527)->io_stat_request () = 0 {23 5 0 63063 0 1391345013 0 33188 1
0 0 27618 0 1391344186 0 1391344186 0 1391344186 0 8192 56 0 0 0 0 0 0 0 0 0 0
0}
49<--58(pid6527)->io_map_request () = 0 59<--50(pid6527) (null)
task42(pid6527)->mach_port_deallocate (pn{ 0}) = 0xf ((os/kern) invalid name)
task42(pid6527)->vm_map (0 27618 0 1 59<--50(pid6527) 0 32 1 7 1) = 0
16973824
task42(pid6527)->mach_port_deallocate (pn{ 11}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 3}) = 0
5<--54(pid6527)->dir_lookup ("lib/i386-gnu/libutil.so.1" 1 0) = 0 1 ""
49<--60(pid6527)
49<--60(pid6527)->io_read_request (-1 512) = 0 "\x7fELF\x01\x01\x01"
49<--60(pid6527)->io_stat_request () = 0 {23 5 0 119788 0 1391119186 0 33188
1 0 0 9824 0 1391499982 0 1391097771 0 1391115526 0 8192 24 0 0 0 0 0 0 0 0 0 0
0}
49<--60(pid6527)->io_map_request () = 0 59<--58(pid6527) (null)
task42(pid6527)->mach_port_deallocate (pn{ 0}) = 0xf ((os/kern) invalid name)
task42(pid6527)->vm_map (0 12436 0 1 59<--58(pid6527) 0 32 5 7 1) = 0
17002496
task42(pid6527)->mach_port_deallocate (pn{ 11}) = 0
49<--60(pid6527)->io_map_request () = 0 59<--50(pid6527) (null)
task42(pid6527)->mach_port_deallocate (pn{ 0}) = 0xf ((os/kern) invalid name)
task42(pid6527)->vm_map (17010688 8192 0 0 59<--50(pid6527) 4096 32 3 7 1) =
0x3 ((os/kern) no space available)
task42(pid6527)->vm_deallocate (17010688 8192) = 0
task42(pid6527)->vm_map (17010688 8192 0 0 59<--50(pid6527) 4096 32 3 7 1) =
0 17010688
task42(pid6527)->mach_port_deallocate (pn{ 11}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 3}) = 0
5<--54(pid6527)->dir_lookup ("lib/i386-gnu/libc.so.0.3" 1 0) = 0 1 ""
49<--58(pid6527)
49<--58(pid6527)->io_read_request (-1 512) = 0 "\x7fELF\x01\x01\x01"
49<--58(pid6527)->io_stat_request () = 0 {23 5 0 119768 0 1391119146 0 33261
1 0 0 1818492 0 1391499982 0 1391097764 0 1391115526 0 8192 3560 0 0 0 0 0 0 0
0 0 0 0}
49<--58(pid6527)->io_map_request () = 0 59<--60(pid6527) (null)
task42(pid6527)->mach_port_deallocate (pn{ 0}) = 0xf ((os/kern) invalid name)
task42(pid6527)->vm_map (0 1822044 0 1 59<--60(pid6527) 0 32 5 7 1) = 0
17018880
task42(pid6527)->mach_port_deallocate (pn{ 11}) = 0
49<--58(pid6527)->io_map_request () = 0 59<--50(pid6527) (null)
task42(pid6527)->mach_port_deallocate (pn{ 0}) = 0xf ((os/kern) invalid name)
task42(pid6527)->vm_map (18804736 20480 0 0 59<--50(pid6527) 1785856 32 3 7
1) = 0x3 ((os/kern) no space available)
task42(pid6527)->vm_deallocate (18804736 20480) = 0
task42(pid6527)->vm_map (18804736 20480 0 0 59<--50(pid6527) 1785856 32 3 7
1) = 0 18804736
task42(pid6527)->mach_port_deallocate (pn{ 11}) = 0
task42(pid6527)->vm_map (18825216 15708 0 0 (null) 0 0 3 7 1) = 0x3 ((os/kern)
no space available)
task42(pid6527)->vm_deallocate (18825216 15708) = 0
task42(pid6527)->vm_map (18825216 15708 0 0 (null) 0 0 3 7 1) = 0 18825216
task42(pid6527)->vm_protect (172032 16777216 0 7) = 0
task42(pid6527)->mach_port_deallocate (pn{ 3}) = 0
5<--54(pid6527)->dir_lookup ("lib/i386-gnu/libmachuser.so.1" 1 0) = 0 1 ""
49<--60(pid6527)
49<--60(pid6527)->io_read_request (-1 512) = 0 "\x7fELF\x01\x01\x01"
49<--60(pid6527)->io_stat_request () = 0 {23 5 0 119774 0 1391119158 0 33188
1 0 0 87460 0 1391499982 0 1391097767 0 1391115526 0 8192 184 0 0 0 0 0 0 0 0 0
0 0}
task42(pid6527)->vm_map (0 4096 0 1 (null) 0 0 3 7 1) = 0 18841600
49<--60(pid6527)->io_map_request () = 0 59<--58(pid6527) (null)
task42(pid6527)->mach_port_deallocate (pn{ 0}) = 0xf ((os/kern) invalid name)
task42(pid6527)->vm_map (0 90172 0 1 59<--58(pid6527) 0 32 5 7 1) = 0
18845696
task42(pid6527)->mach_port_deallocate (pn{ 11}) = 0
49<--60(pid6527)->io_map_request () = 0 59<--50(pid6527) (null)
task42(pid6527)->mach_port_deallocate (pn{ 0}) = 0xf ((os/kern) invalid name)
task42(pid6527)->vm_map (18931712 8192 0 0 59<--50(pid6527) 81920 32 3 7 1)
= 0x3 ((os/kern) no space available)
task42(pid6527)->vm_deallocate (18931712 8192) = 0
task42(pid6527)->vm_map (18931712 8192 0 0 59<--50(pid6527) 81920 32 3 7 1)
= 0 18931712
task42(pid6527)->mach_port_deallocate (pn{ 11}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 3}) = 0
5<--54(pid6527)->dir_lookup ("lib/i386-gnu/libhurduser.so.0.3" 1 0) = 0 1 ""
49<--58(pid6527)
49<--58(pid6527)->io_read_request (-1 512) = 0 "\x7fELF\x01\x01\x01"
49<--58(pid6527)->io_stat_request () = 0 {23 5 0 119772 0 1391119154 0 33188
1 0 0 198208 0 1391499982 0 1391097766 0 1391115526 0 8192 400 0 0 0 0 0 0 0 0
0 0 0}
49<--58(pid6527)->io_map_request () = 0 59<--60(pid6527) (null)
task42(pid6527)->mach_port_deallocate (pn{ 0}) = 0xf ((os/kern) invalid name)
task42(pid6527)->vm_map (0 200864 0 1 59<--60(pid6527) 0 32 5 7 1) = 0
18939904
task42(pid6527)->mach_port_deallocate (pn{ 11}) = 0
49<--58(pid6527)->io_map_request () = 0 59<--50(pid6527) (null)
task42(pid6527)->mach_port_deallocate (pn{ 0}) = 0xf ((os/kern) invalid name)
task42(pid6527)->vm_map (19136512 8192 0 0 59<--50(pid6527) 192512 32 3 7 1)
= 0x3 ((os/kern) no space available)
task42(pid6527)->vm_deallocate (19136512 8192) = 0
task42(pid6527)->vm_map (19136512 8192 0 0 59<--50(pid6527) 192512 32 3 7 1)
= 0 19136512
task42(pid6527)->mach_port_deallocate (pn{ 11}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 3}) = 0
task42(pid6527)->vm_map (0 4096 0 1 (null) 0 0 3 7 1) = 0 19144704
thread44(pid6527)->i386_set_gdt (-1 {-1885011969 30405151}) = 0 75
task42(pid6527)->vm_protect (18931712 4096 0 1) = 0
task42(pid6527)->vm_protect (19136512 4096 0 1) = 0
task42(pid6527)->vm_protect (17018880 1785856 0 3) = 0
task42(pid6527)->vm_protect (17018880 1785856 0 5) = 0
task42(pid6527)->vm_protect (18804736 12288 0 1) = 0
task42(pid6527)->vm_protect (17010688 4096 0 1) = 0
task42(pid6527)->vm_protect (134524928 4096 0 1) = 0
task42(pid6527)->vm_protect (159744 4096 0 1) = 0
task42(pid6527)->mach_port_mod_refs (pn{ 11} 1 -1) = 0
task42(pid6527)->mach_port_deallocate (pn{ 1}) = 0
task42(pid6527)->vm_statistics () = 0 {4096 178157 37217 30473 11515 792472 0
208255 191539 5405548 546253 673601 642164}
task42(pid6527)->vm_map (134565888 134184960 0 0 (null) 0 0 0 7 1) = 0
134565888
task42(pid6527)->vm_allocate (18153974 4096 1) = 0 19148800
task42(pid6527)->task_set_special_port (4 (null)) = 0
task42(pid6527)->mach_port_allocate (1) = 0 pn{ 11}
task42(pid6527)->mach_port_insert_right (pn{ 11} 49) = 0
task42(pid6527)->vm_allocate (32 4096 1) = 0 19152896
task42(pid6527)->thread_create () = 0 58<--60(pid6527)
task42(pid6527)->vm_allocate (0 36864 1) = 0 19156992
task42(pid6527)->vm_protect (19156992 4096 0 0) = 0
thread58(pid6527)->thread_set_state (1 {0 0 0 0 0 0 0 0 0 0 0 0 17217824 0 0
19193856 0}) = 0
thread58(pid6527)->thread_get_state (5 17) = 0 {31 31 31 31 0 0 0 0 0 0 0 0
17217824 23 512 19193856 31}
task42(pid6527)->vm_protect (134565888 135168 0 7) = 0
thread58(pid6527)->i386_set_gdt (75 {1232404479 147845637}) = 0 75
thread58(pid6527)->thread_set_state (5 {75 31 31 31 0 0 0 0 0 0 0 0 17217824 23
512 19193856 31}) = 0
thread58(pid6527)->thread_resume () = 0
task42(pid6527)->vm_allocate (0 4096 1) ...46
task42(pid6527)->task_set_special_port (3 49<--62(pid-1)) ...61
46... = 0 19193856
61... = 0
51<--56(pid6527)->proc_setmsgport_request ( 49<--62(pid-1)) = 0 (null)
51<--56(pid6527)->proc_set_arg_locations_request (16948740 16948748) = 0
task42(pid6527)->mach_port_allocate (1) = 0 pn{ 15}
task42(pid6527)->mach_port_allocate (1) = 0 pn{ 16}
task42(pid6527)->mach_port_insert_right (pn{ 15} 45) = 0
task42(pid6527)->mach_port_set_qlimit (pn{ 16} 1) = 0
51<--56(pid6527)->proc_handle_exceptions_request ( 45<--64(pid-1)
63<--65(pid-1) 5 {75 31 31 31 0 0 0 0 0 0 0 0 17253632 23 0 18826880 0}) = 0
thread58(pid6527)->thread_set_special_port (3 64) = 0
task42(pid6527)->mach_port_deallocate (pn{ 15}) = 0
51<--56(pid6527)->proc_getpids_request () = 0 6527 6526 0
51<--56(pid6527)->proc_getpgrp_request (6527) = 0 6526
task42(pid6527)->vm_deallocate (16965632 20) = 0
task42(pid6527)->vm_deallocate (16961536 24) = 0
task42(pid6527)->vm_allocate (17155307 4096 1) = 0 16961536
task42(pid6527)->vm_allocate (16961536 4096 1) = 0 16965632
8<--47(pid6527)->term_getctty () = 0 32<--57(pid6527)
task42(pid6527)->mach_port_deallocate (pn{ 10}) = 0
8<--47(pid6527)->term_open_ctty (6527 6526) = 0 64<--59(pid6527)
22<--52(pid6527)->term_getctty () = 0xfffffed1 ((ipc/mig) bad request message
ID)
22<--52(pid6527)->term_getctty () = 0xfffffed1 ((ipc/mig) bad request message
ID)
task42(pid6527)->vm_deallocate (16957440 12) = 0
8<--47(pid6527)->io_stat_request () = 0 {14 235 0 0 0 1367641351 0 8397200 1
1001 5 0 0 1391499982 0 1391499982 0 1391499982 0 512 8 0 0 0 0 0 0 0 0 0 0 0}
5<--54(pid6527)->io_identity_request () = 0 66<--45(pid6527)
67<--68(pid6527) 2
task42(pid6527)->mach_port_deallocate (pn{ 18}) = 0
6<--53(pid6527)->io_identity_request () = 0 67<--69(pid6527)
68<--70(pid6527) 15760
6<--53(pid6527)->dir_lookup (".." 1 0) = 0 1 "" 72<--71(pid6527)
72<--71(pid6527)->io_identity_request () = 0 74<--73(pid6527)
68<--70(pid6527) 47310
72<--71(pid6527)->dir_readdir (0 -1 0) = 0 "\xce\xb8" 4
task42(pid6527)->vm_deallocate (0 0) = 0
72<--71(pid6527)->dir_lookup ("srs" 64 0) = 0 1 "" 76<--75(pid6527)
76<--75(pid6527)->io_identity_request () = 0 67<--69(pid6527)
68<--70(pid6527) 15760
task42(pid6527)->mach_port_deallocate (pn{ 22}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 18}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 18}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
72<--71(pid6527)->dir_lookup (".." 1 0) = 0 1 "" 67<--77(pid6527)
task42(pid6527)->mach_port_deallocate (pn{ 20}) = 0
67<--77(pid6527)->io_identity_request () = 0 66<--45(pid6527)
68<--70(pid6527) 2
67<--77(pid6527)->dir_readdir (0 -1 0) = 0 "\x02" 26
task42(pid6527)->vm_deallocate (16957440 4096) = 0
67<--77(pid6527)->dir_lookup ("home" 64 0) = 0 1 "" 72<--69(pid6527)
72<--69(pid6527)->io_identity_request () = 0 74<--73(pid6527)
68<--70(pid6527) 47310
task42(pid6527)->mach_port_deallocate (pn{ 20}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 21}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 21}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 18}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 17}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 17}) = 0
task42(pid6527)->vm_deallocate (19197952 4096) = 0
5<--54(pid6527)->dir_lookup ("dev/." 0 0) = 0 1 "" 66<--71(pid6527)
task42(pid6527)->mach_port_deallocate (pn{ 6}) = 0
66<--71(pid6527)->dir_lookup ("./" 2097161 0) = 0 1 "" 53<--45(pid6527)
53<--45(pid6527)->term_getctty () = 0xfffffed1 ((ipc/mig) bad request message
ID)
53<--45(pid6527)->dir_readdir (0 -1 0) = 0 "*g\x02" 386
task42(pid6527)->vm_deallocate (0 0) = 0
66<--71(pid6527)->dir_lookup ("." 64 0) = 0 1 "" 70<--68(pid6527)
70<--68(pid6527)->io_stat_request () = 0 {23 5 0 157482 0 1367628645 0 16877
2 0 0 8192 0 1391499982 0 1391294623 0 1367626826 0 8192 16 0 0 0 0 0 0 0 0 0 0
0}
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
66<--71(pid6527)->dir_lookup (".." 64 0) = 0 1 "" 70<--67(pid6527)
70<--67(pid6527)->io_stat_request () = 0 {23 5 0 2 0 1367626663 0 8405485 24
0 0 4096 0 1391499982 0 1390561281 0 1390460274 0 8192 8 0 0 0 0 0 0 0 0 0 0 0}
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
66<--71(pid6527)->dir_lookup ("MAKEDEV" 64 0) = 0 1 "" 70<--68(pid6527)
70<--68(pid6527)->io_stat_request () = 0 {23 5 0 158507 0 1389829237 0 41453
1 0 0 13 0 1389823633 0 1389668602 0 1389823635 0 8192 0 0 0 0 0 0 0 0 0 0 0 0}
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
66<--71(pid6527)->dir_lookup ("fd" 64 0) = 0 1 "" 70<--67(pid6527)
70<--67(pid6527)->io_stat_request () = 0 {15 6506 0 157772 0 1367640857 0
8405357 1 0 0 32 0 1367626809 0 1367626809 0 1367626810 0 8192 8 0 0 0 0 0 1 2
0 0 0 3}
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
5<--54(pid6527)->io_identity_request () = 0 70<--68(pid6527)
67<--77(pid6527) 2
task42(pid6527)->mach_port_deallocate (pn{ 18}) = 0
66<--71(pid6527)->io_identity_request () = 0 67<--74(pid6527)
77<--73(pid6527) 157482
66<--71(pid6527)->dir_lookup (".." 1 0) = 0 1 "" 69<--72(pid6527)
69<--72(pid6527)->io_identity_request () = 0 70<--68(pid6527)
77<--73(pid6527) 2
69<--72(pid6527)->dir_readdir (0 -1 0) = 0 "\x02" 26
task42(pid6527)->vm_deallocate (0 0) = 0
69<--72(pid6527)->dir_lookup ("dev" 64 0) = 0 1 "" 75<--76(pid6527)
75<--76(pid6527)->io_identity_request () = 0 67<--74(pid6527)
77<--73(pid6527) 157482
task42(pid6527)->mach_port_deallocate (pn{ 22}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 18}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 21}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 18}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 21}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 20}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 21}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
task42(pid6527)->vm_deallocate (16957440 4096) = 0
5<--54(pid6527)->dir_lookup ("dev/fd/." 0 0) = 0 1 "/." 70<--78(pid6527)
70<--78(pid6527)->dir_lookup ("." 0 0) = 0 1 "" 77<--68(pid6527)
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 17}) = 0
77<--68(pid6527)->dir_lookup ("./" 2097161 0) = 0 1 "" 66<--73(pid6527)
66<--73(pid6527)->term_getctty () = 0xfffffed1 ((ipc/mig) bad request message
ID)
66<--73(pid6527)->dir_readdir (0 -1 0) = 0 "\x02" 2
task42(pid6527)->vm_deallocate (0 0) = 0
77<--68(pid6527)->dir_lookup ("." 64 0) = 0 1 "" 70<--71(pid6527)
70<--71(pid6527)->io_stat_request () = 0 {15 6506 0 157772 0 1367640857 0
8405357 1 0 0 32 0 1367626809 0 1367626809 0 1367626810 0 8192 8 0 0 0 0 0 1 2
0 0 0 3}
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
77<--68(pid6527)->dir_lookup (".." 64 0) = 0 2 "" 70<--78(pid6527)
70<--78(pid6527)->io_reauthenticate ( 50<--69(pid-1));
20<--55(pid6527)->auth_user_authenticate ( 50<--69(pid-1)) = 0
50<--71(pid6527)
task42(pid6527)->mach_port_destroy (pn{ 20}) = 0
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
50<--71(pid6527)->dir_lookup ("" 64 0) = 0 1 "" 70<--69(pid6527)
task42(pid6527)->mach_port_deallocate (pn{ 18}) = 0
70<--69(pid6527)->io_stat_request () = 0 {23 5 0 157482 0 1367628645 0 16877
2 0 0 8192 0 1391499982 0 1391294623 0 1367626826 0 8192 16 0 0 0 0 0 0 0 0 0 0
0}
task42(pid6527)->mach_port_deallocate (pn{ 19}) = 0
66<--73(pid6527)->dir_readdir (2 -1 0) = 0
Segmentation fault
