PDA

View Full Version : Parent process dying



healy
2005-04-28, 18:46
Anyone else having trouble with the parent process dying on them in
linux? My server has been crapping out because the parent process
keeps dying but the child
procs stay on. Example below. Debian box w/ 4/21 nightly (happened
with the past few nightlies I've tried). Sometimes the parent will
stay up for days, sometimes it
will die in less than 15 mins. Anyone seen this behavior?

# ps -ef|grep slim
slimser 22970 1 91 18:27 ? 00:00:07 slimserver
slimser 22971 22970 0 18:27 ? 00:00:00
/usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
mDNSResponderPosix -n SlimServer -t _http._tcp -p 9000
slimser 22972 22970 0 18:27 ? 00:00:00
/usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
mDNSResponderPosix -n SlimServer -t _slimhttp._tcp -p 9000
slimser 22973 22970 0 18:27 ? 00:00:00
/usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
mDNSResponderPosix -n SlimServer -t _slimcli._tcp -p 9090
root 22976 17975 0 18:27 pts/0 00:00:00 grep slim


# ps -ef|grep slim
slimser 22971 1 0 18:27 ? 00:00:00
/usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
mDNSResponderPosix -n SlimServer -t _http._tcp -p 9000
slimser 22972 1 0 18:27 ? 00:00:00
/usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
mDNSResponderPosix -n SlimServer -t _slimhttp._tcp -p 9000
slimser 22973 1 0 18:27 ? 00:00:00
/usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
mDNSResponderPosix -n SlimServer -t _slimcli._tcp -p 9090
root 23103 17975 0 18:42 pts/0 00:00:00 grep slim

kdf
2005-04-28, 19:31
On 28-Apr-05, at 6:46 PM, Healy wrote:

> Anyone else having trouble with the parent process dying on them in
> linux? My server has been crapping out because the parent process
> keeps dying but the child
> procs stay on. Example below. Debian box w/ 4/21 nightly (happened
> with the past few nightlies I've tried). Sometimes the parent will
> stay up for days, sometimes it
> will die in less than 15 mins. Anyone seen this behavior?
>

Anything in the log from when the server crashed?
-kdf

healy
2005-04-29, 06:56
Nothing in a non-debug log. Trying again, this time running a strace
on the parent process as well.


On Apr 28, 2005, at 7:31 PM, kdf wrote:

>
> On 28-Apr-05, at 6:46 PM, Healy wrote:
>
>> Anyone else having trouble with the parent process dying on them in
>> linux? My server has been crapping out because the parent process
>> keeps dying but the child
>> procs stay on. Example below. Debian box w/ 4/21 nightly (happened
>> with the past few nightlies I've tried). Sometimes the parent will
>> stay up for days, sometimes it
>> will die in less than 15 mins. Anyone seen this behavior?
>>
>
> Anything in the log from when the server crashed?
> -kdf

dean
2005-04-29, 08:08
Healy,

Try running it from the command line and watch for a crash and see
what error is emitted.

On Apr 29, 2005, at 6:56 AM, Healy wrote:

> Nothing in a non-debug log. Trying again, this time running a
> strace on the parent process as well.
>

healy
2005-04-29, 08:31
Yeah, I'll try that next time. Of course, since I'm trying to get it
to crash, it's been solid for about the last
12 hours...


On Apr 29, 2005, at 8:08 AM, dean blackketter wrote:

> Healy,
>
> Try running it from the command line and watch for a crash and see
> what error is emitted.
>
> On Apr 29, 2005, at 6:56 AM, Healy wrote:
>
>> Nothing in a non-debug log. Trying again, this time running a strace
>> on the parent process as well.
>>
>

healy
2005-04-30, 10:23
I was playing tunes this morning for about 1 hour & the parent process
died again. Nothing was going on other than the stream. IE: no
activity on the server
web page. The server is a dedicated slimserver only running samba &
slimserver process. I'll re-run with debug options as the log shows
literally nothing
about this.

# ps -ef|grep slim
slimser 23277 1 0 Apr28 ? 00:00:00
/usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
mDNSResponderPosix -n SlimServer -t _http._tcp -p 9000
slimser 23278 1 0 Apr28 ? 00:00:00
/usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
mDNSResponderPosix -n SlimServer -t _slimhttp._tcp -p 9000
slimser 23279 1 0 Apr28 ? 00:00:00
/usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
mDNSResponderPosix -n SlimServer -t _slimcli._tcp -p 9090
root 31757 17975 0 10:17 pts/0 00:00:00 grep slim
# date
Sat Apr 30 10:18:20 PDT 2005

I did notice that the db cache was being updated at the time:

-rw-r--r-- 1 slimserver slimserver 33103872 Apr 30 10:13
..slimserversql.db
-rw------- 1 slimserver slimserver 195560 Apr 30 10:17
..slimserversql.db-journal

here is the last part of the strace output:

read(8, "\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1l\1v\1\200"...,
1024) = 1024
write(12, "\0\0}\361\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0yE\r\0\0\0?\0\216\0\3\362\3\344\3\326\3\310\3\ 272\3"...,
1032) = 1032
_llseek(8, 33046528, [33046528], SEEK_SET) = 0
read(8, "\2\0\0\0$\2\10\0\0\0~H\2N\2\\\2j\2x\2\206\2\224\2\ 242\2"...,
1024) = 1024
_llseek(8, 33102848, [33102848], SEEK_SET) = 0
read(8, "\n\0\0\0M\0\376\0\1X\1b\1l\1v\1\200\1\212\1\224\1\ 236\1"...,
1024) = 1024
write(12, "\0\0~H\n\0\0\0M\0\376\0\1X\1b\1l\1v\1\200\1\212\1\ 224\1"...,
1032) = 1032
write(12,
"\0\0}\202\r\0\0\0\6\3\254\0\3\362\3\344\3\326\3\31 0\3\272"..., 1032) =
1032
write(12, "\0\0~\21\2\0\0\0$\2\10\0\0\0~H\2N\2\\\2j\2x\2\206\ 2\224"...,
1032) = 1032
_llseek(8, 33092608, [33092608], SEEK_SET) = 0
read(8, "\n\0\0\0M\0\376\0\0\376\1\10\1\22\1\34\1&\0010\1:\1D\1"...,
1024) = 1024
_llseek(8, 33093632, [33093632], SEEK_SET) = 0
read(8, "\n\0\0\0L\1\10\0\1\10\1\22\1\34\1&\0010\1:\1D\1N\1X\1b"...,
1024) = 1024
write(12, "\0\0~>\n\0\0\0M\0\376\0\0\376\1\10\1\22\1\34\1&\0010\1"...,
1032) = 1032
write(12, "\0\0~?\n\0\0\0L\1\10\0\1\10\1\22\1\34\1&\0010\1:\1D\1N"...,
1032) = 1032
write(12, "\0\0}A\5\0\0\0f\0016\0\0\0}\202\1\203\1\212\1\221\ 1\230"...,
1032) = 1032
write(12, "\0\0}\360\r\0\0\0$\2\3\0\2\3\2\21\2\37\2-\2;\2I\2W\2e\2"...,
1032) = 1032
write(12, "\0\0}\377\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0}\372\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0}\370\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0}8\5\0\0\0f\0016\0\0\0|?\0016\1=\1D\1K\1R\1Y\1 `\1g"...,
1032) = 1032
write(12, "\0\0}@\5\0\0\0f\0016\0\0\0|\340\0016\1=\1D\1K\1R\1 Y\1`"...,
1032) = 1032
write(12, "\0\0x\261\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0}\344\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0x\307\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0}\305\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
_llseek(8, 32964608, [32964608], SEEK_SET) = 0
read(8, "\2\0\0\0007\0\376\0\0\0|\362\0\376\1\f\1\32\1(\001 6\1D"...,
1024) = 1024
_llseek(8, 32966656, [32966656], SEEK_SET) = 0
read(8, "\2\0\0\0/\1n\0\0\0}\211\1n\1|\1\212\1\230\1\246\1\264\1"...,
1024) = 1024
write(12, "\0\0}\301\2\0\0\0007\0\376\0\0\0|\362\0\376\1\f\1\ 32\1"...,
1032) = 1032
write(12,
"\0\0}\303\2\0\0\0/\1n\0\0\0}\211\1n\1|\1\212\1\230\1\246"..., 1032) =
1032
write(12, "\0\0x\320\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0z\234\2\0\0\0&\1\354\0\0\0yY\1\354\1\372\2\10\2\26"...,
1032) = 1032
write(12, "\0\0x\324\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0y\261\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0x\342\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0x\334\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0x\330\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0x\314\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0x\301\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
write(12, "\0\0x\234\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"...,
1032) = 1032
select(16, [4 5 6 7 10], [13], [10 13], {0, 0}) = 3 (in [5 10], out
[13], left {0, 0})
getpeername(10, 0xd798fc8, [256]) = -1 ENOTCONN (Transport
endpoint is not connected)
close(10) = 0
--- SIGSEGV (Segmentation fault) @ 0 (0) ---
Process 23276 detached



On Apr 29, 2005, at 8:31 AM, Healy wrote:

> Yeah, I'll try that next time. Of course, since I'm trying to get it
> to crash, it's been solid for about the last
> 12 hours...
>
>
> On Apr 29, 2005, at 8:08 AM, dean blackketter wrote:
>
>> Healy,
>>
>> Try running it from the command line and watch for a crash and see
>> what error is emitted.
>>
>> On Apr 29, 2005, at 6:56 AM, Healy wrote:
>>
>>> Nothing in a non-debug log. Trying again, this time running a
>>> strace on the parent process as well.
>>>
>>

healy
2005-04-30, 19:25
Parent process died again. I can't find anything in the debug log.
files inline. I'm stumped.

-Healy


command line output:
================
# /usr/local/slimserver/slimserver.pl --user slimserver --logfile
/home/slimserver/log/slimserver.log --prefsfile
/home/slimserver/.slimserver.pref --cachedir /home/slimserver/cache/
--pidfile /home/slimserver/var/slimserver.pid --d_server --d_info
2005-04-30 11:57:10.1281 SlimServer OSDetect init...
2005-04-30 11:57:10.1285 SlimServer OS Specific init...
2005-04-30 11:57:10.1287 SlimServer settings effective user and group
if requested...
2005-04-30 11:57:10.1297 SlimServer settings init...
2005-04-30 11:57:10.1536 SlimServer strings init...
2005-04-30 11:57:10.2792 SlimServer Setup init...
2005-04-30 11:57:10.2885 SlimServer setting language...
2005-04-30 11:57:10.2889 SlimServer IR init...
2005-04-30 11:57:10.2952 SlimServer Buttons init...
2005-04-30 11:57:10.2970 SlimServer Graphics init...
2005-04-30 11:57:12.1553 SlimServer starting up...
2005-04-30 11:57:12.1559 SlimServer saving pid file.
Segmentation fault


strace:
=====
_llseek(8, 29063168, [29063168], SEEK_SET) = 0
read(8, "\n\0\0\0Y\0018\0\0018\1@\1H\1P\1X\1`\1h\1p\1x\1\20 0\1\210"...,
1024) = 1024
_llseek(8, 29052928, [29052928], SEEK_SET) = 0
read(8, "\r\0\0\0\37\0F\0\3\344\3\310\3\254\3\220\3t\3^\3B\ 3&\3"...,
1024) = 1024
time(NULL) = 1114914016
mmap2(NULL, 1634304, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xb6c9f000
munmap(0xb6c9f000, 1634304) = 0
mremap(0xb7447000, 1634304, 1634304, MREMAP_MAYMOVE) = 0xb7447000
munmap(0xb70d5000, 1634304) = 0
_llseek(8, 3542016, [3542016], SEEK_SET) = 0
read(8, "\2\0\0\0\t\0N\0\0\0wG\3\235\3\36\2\310\2W\0N\1\360 \1\211"...,
1024) = 1024
_llseek(8, 3525632, [3525632], SEEK_SET) = 0
read(8, "\n\0\0\0\f\0\"\0\0\"\0{\0\312\1\21\1d\1\254\1\372\2G\2"...,
1024) = 1024
_llseek(8, 31273984, [31273984], SEEK_SET) = 0
read(8, "\r\0\0\0\4\0&\0\2\353\1\370\1\33\0&\0\0\0\0\0\0\0\0\0\0"...,
1024) = 1024
time(NULL) = 1114914016
time([1114914016]) = 1114914016
time([1114914016]) = 1114914016
gettimeofday({1114914016, 171266}, NULL) = 0
write(2, "2005-04-30 19:20:16.1712 CacheIt"..., 164) = 164
stat64("/raid0/mp3/David Bowie/Heroes/01 - David Bowie - Beauty And The
Beast.mp3", {st_mode=S_IFREG|0755, st_size=5222156, ...}) = 0
time(NULL) = 1114914016
time(NULL) = 1114914016
mmap2(NULL, 1634304, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xb70d5000
time(NULL) = 1114914016
time(NULL) = 1114914016
time(NULL) = 1114914016
_llseek(8, 31253504, [31253504], SEEK_SET) = 0
read(8, "\r\0\0\0\"\0d\0\3\346\3\313\3\260\3\225\3z\3_\3D\3)\3\16"...,
1024) = 1024
time(NULL) = 1114914016
mmap2(NULL, 1634304, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xb6c9f000
munmap(0xb6c9f000, 1634304) = 0
mremap(0xb7447000, 1634304, 1638400, MREMAP_MAYMOVE) = 0xb7447000
gettimeofday({1114914016, 235304}, NULL) = 0
select(24, [4 5 6 7 10 12 16], [13], [10 12 13 16], {0, 0}) = 1 (out
[13], left {0, 0})
getpeername(13, {sa_family=AF_INET, sin_port=htons(42793),
sin_addr=inet_addr("192.168.1.1")}, [16]) = 0
read(11, "?\3@\312\302Z\346\262\246\322\4\16~\326\204]\204\347\3"...,
32768) = 32768
getpeername(13, {sa_family=AF_INET, sin_port=htons(42793),
sin_addr=inet_addr("192.168.1.1")}, [16]) = 0
write(13, "?\3@\312\302Z\346\262\246\322\4\16~\326\204]\204\347\3"...,
32768) = 13156
gettimeofday({1114914016, 237844}, NULL) = 0
gettimeofday({1114914016, 238065}, NULL) = 0
munmap(0xb70d5000, 1634304) = 0
_llseek(8, 27759616, [27759616], SEEK_SET) = 0
read(8, "\n\0\0\0\f\1\6\0\1\6\1<\1{\1\330\2\17\2C\2{\2\262\2\363"...,
1024) = 1024
_llseek(8, 23598080, [23598080], SEEK_SET) = 0
read(8, "\r\0\0\0\4\0\273\0\0035\0024\0\273\1l\0\0\0\0\0\0\ 0\0\0"...,
1024) = 1024
time(NULL) = 1114914016
time([1114914016]) = 1114914016
time([1114914016]) = 1114914016
gettimeofday({1114914016, 244030}, NULL) = 0
write(2, "2005-04-30 19:20:16.2440 CacheIt"..., 114) = 114
stat64("/raid0/mp3/U2/October/01 - Gloria.mp3", {st_mode=S_IFREG|0755,
st_size=6068289, ...}) = 0
time(NULL) = 1114914016
time(NULL) = 1114914016
mmap2(NULL, 1638400, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xb70d4000
time(NULL) = 1114914016
time(NULL) = 1114914016
time(NULL) = 1114914016
--- SIGSEGV (Segmentation fault) @ 0 (0) ---
Process 745 detached


log file:
======
2005-04-30 11:57:12.1624 Old SLIMP3 Protocol init...
2005-04-30 11:57:12.1647 Slimproto Init...
2005-04-30 11:57:12.1662 SlimServer HTTP init...
2005-04-30 11:57:12.2030 SlimServer CLI init...
2005-04-30 11:57:12.2142 Source conversion init..
2005-04-30 11:57:12.2163 SlimServer Info init...
2005-04-30 11:57:12.2168 loading types config file...
2005-04-30 11:57:12.2188 Metadata database saving into:
/home/slimserver/cache/.slimserversql
..db
2005-04-30 11:57:12.2273 Connected to database
dbi:SQLite:dbname=/home/slimserver/cache/.slim
serversql.db
2005-04-30 11:57:12.2338 No upgrades found for database v. 7
2005-04-30 11:57:12.4813 DBI: Supressing periodic commit - no dirty
items
2005-04-30 11:57:12.4825 SlimServer MusicFolderScan init...
2005-04-30 11:57:12.4828 Adding Folder Scan
2005-04-30 11:57:12.4829 SlimServer Plugins init...
2005-04-30 11:57:12.6269 Adding iTunes Scan
2005-04-30 11:57:12.6284 SlimServer persist playlists...
2005-04-30 11:57:12.6287 SlimServer done start...
2005-04-30 11:57:12.7683 Converting
file:///home/playlists/__00_04_20_05_00_d4.m3u to /home/p
laylists/__00_04_20_05_00_d4.m3u
2005-04-30 11:57:12.7686 m3u file type for
file:///home/playlists/__00_04_20_05_00_d4.m3u
2005-04-30 11:57:12.7822 CacheItem: Checking status of
file:///raid0/mp3/Compilations/Alligat
or%20Records%2030th%20Annivers/1-01%20Turn%20The%20Heat%20Up.mp3 (TTL:
0).
2005-04-30 11:57:12.7839 Merging entry for
file:///raid0/mp3/Compilations/Alligator%20Records
%2030th%20Annivers/1-01%20Turn%20The%20Heat%20Up.mp3

<<< SNIP >>>>

2005-04-30 19:20:14.5090 CacheItem: Checking status of
file:///raid0/mp3/X/More%20Fun%20in%20
the%20New%20World%20%5BBonus%20Tracks%5D/10%20-%20Hot%20House.mp3 (TTL:
0).
2005-04-30 19:20:14.5814 CacheItem: Checking status of
file:///raid0/mp3/The%20Cure/Galore/02
-Catch.mp3 (TTL: 0).
2005-04-30 19:20:14.6564 CacheItem: Checking status of
file:///raid0/mp3/X/Unclogged/06%20-%2
0Burning%20House%20of%20Love.mp3 (TTL: 0).
2005-04-30 19:20:14.7294 CacheItem: Checking status of
file:///raid0/mp3/Alison%20Krauss%20&%
20Union%20Station/Live!/24%20-
%20Down%20to%20the%20River%20to%20Pray.mp3 (TTL: 0).
2005-04-30 19:20:14.8078 CacheItem: Checking status of
file:///raid0/mp3/Queen/Greatest%20Hit
s/04%20-%20Fat%20Bottomed%20Girls.mp3 (TTL: 0).
2005-04-30 19:20:14.8840 CacheItem: Checking status of
file:///raid0/mp3/Rolling%20Stones/The
%20Rolling%20Stones/03%20-
%20I%20Just%20Want%20to%20Make%20Love%20to%20You.m p3 (TTL: 0).
2005-04-30 19:20:14.9550 CacheItem: Checking status of
file:///raid0/mp3/Bob%20Marley%20&%20T
he%20Wailers/Bootlegs/Boarding%20House%201975/07%20-
%20Kinky%20Reggae.mp3 (TTL: 0).
2005-04-30 19:20:15.0323 CacheItem: Checking status of
file:///raid0/mp3/Southern%20Culture%2
0on%20the%20Skids/Dirt%20Track%20Date/13%20-
%20Whole%20Lotta%20Things.mp3 (TTL: 0).
2005-04-30 19:20:15.1039 CacheItem: Checking status of
file:///raid0/mp3/Rolling%20Stones/For
ty%20Licks/16%20-%20Ruby%20Tuesday.mp3 (TTL: 0).
2005-04-30 19:20:15.1718 CacheItem: Checking status of
file:///raid0/mp3/10,000%20Maniacs/In%
20My%20Tribe/03%20-%20Like%20the%20Weather.mp3 (TTL: 0).
2005-04-30 19:20:15.2508 CacheItem: Checking status of
file:///raid0/mp3/George%20Thorogood%2
0&%20the%20Destroyers/
The%20Baddest%20of%20George%20Thorogood%20and%20th e%20Destroyers/03%20-
%20I'm%20a%20Steady%20Rollin'%20Man.mp3 (TTL: 0).
2005-04-30 19:20:15.3179 CacheItem: Checking status of
file:///raid0/mp3/The%20Chemical%20Bro
thers/Life%20Is%20Sweet/06%20-
%20If%20You%20Kling%20to%20Me%20I'll%20Klong.mp3 (TTL: 0).
2005-04-30 19:20:15.4974 CacheItem: Checking status of
file:///raid0/mp3/Compilations/I'm%20y
our%20fan%20-%20Tribute%20To%20Leonard%20Cohen/07%20-
%20So%20Long,%20Marianne.mp3 (TTL: 0).
2005-04-30 19:20:15.5728 CacheItem: Checking status of
file:///raid0/mp3/Compilations/Badland
s%20A%20Tribute%20To%20Bruce%20Springsteen's%20Neb raska/09%20-
%20My%20Father's%20House.mp3 (T
TL: 0).
2005-04-30 19:20:15.6489 CacheItem: Checking status of
file:///raid0/mp3/Mike%20Ness/Under%20
the%20Influences/05%20-%20Big%20Iron.mp3 (TTL: 0).
2005-04-30 19:20:15.7248 CacheItem: Checking status of
file:///raid0/mp3/Sex%20Pistols/Sex%20
Box/03%20-%20No%20Feelings%201.mp3 (TTL: 0).
2005-04-30 19:20:15.7897 CacheItem: Checking status of
file:///raid0/mp3/Tom%20Waits/The%20He
art%20of%20Saturday%20Night/11%20-
%20The%20Ghosts%20of%20Saturday%20Night.mp3 (TTL: 0).
2005-04-30 19:20:15.8706 CacheItem: Checking status of
file:///raid0/mp3/Lyle%20Lovett/Live%2
0In%20Texas/02-I've%20Been%20To%20Memphis.mp3 (TTL: 0).
2005-04-30 19:20:15.9438 CacheItem: Checking status of
file:///raid0/mp3/Original%20Soundtrac
k/The%20Commitments/05%20-%20Destination%20Anywhere.mp3 (TTL: 0).
2005-04-30 19:20:16.0251 CacheItem: Checking status of
file:///raid0/mp3/Rolling%20Stones/Som
e%20Girls/03%20-
%20Just%20My%20Imagination%20(Running%20Away%20Wit h%20Me).mp3 (TTL: 0).
2005-04-30 19:20:16.0985 CacheItem: Checking status of
file:///raid0/mp3/Compilations/Genius_
%20The%20Best%20Of%20Warren%20Zevo/14%20Boom%20Boom%20Mancini.mp3 (TTL:
0).
2005-04-30 19:20:16.1712 CacheItem: Checking status of
file:///raid0/mp3/David%20Bowie/Heroes
/01%20-%20David%20Bowie%20-%20Beauty%20And%20The%20Beast.mp3 (TTL: 0).
2005-04-30 19:20:16.2440 CacheItem: Checking status of
file:///raid0/mp3/U2/October/01%20-%20
Gloria.mp3 (TTL: 0).
(END)




On Apr 30, 2005, at 10:23 AM, Healy wrote:

> I was playing tunes this morning for about 1 hour & the parent process
> died again. Nothing was going on other than the stream. IE: no
> activity on the server
> web page. The server is a dedicated slimserver only running samba &
> slimserver process. I'll re-run with debug options as the log shows
> literally nothing
> about this.
>
> # ps -ef|grep slim
> slimser 23277 1 0 Apr28 ? 00:00:00
> /usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
> mDNSResponderPosix -n SlimServer -t _http._tcp -p 9000
> slimser 23278 1 0 Apr28 ? 00:00:00
> /usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
> mDNSResponderPosix -n SlimServer -t _slimhttp._tcp -p 9000
> slimser 23279 1 0 Apr28 ? 00:00:00
> /usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
> mDNSResponderPosix -n SlimServer -t _slimcli._tcp -p 9090
> root 31757 17975 0 10:17 pts/0 00:00:00 grep slim
> # date
> Sat Apr 30 10:18:20 PDT 2005
>
> I did notice that the db cache was being updated at the time:
>
> -rw-r--r-- 1 slimserver slimserver 33103872 Apr 30 10:13
> .slimserversql.db
> -rw------- 1 slimserver slimserver 195560 Apr 30 10:17
> .slimserversql.db-journal
>
> here is the last part of the strace output:
>
> read(8, "\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1l\1v\1\200"...,
> 1024) = 1024
> write(12,
> "\0\0}\361\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12,
> "\0\0yE\r\0\0\0?\0\216\0\3\362\3\344\3\326\3\310\3\ 272\3"..., 1032) =
> 1032
> _llseek(8, 33046528, [33046528], SEEK_SET) = 0
> read(8, "\2\0\0\0$\2\10\0\0\0~H\2N\2\\\2j\2x\2\206\2\224\2\ 242\2"...,
> 1024) = 1024
> _llseek(8, 33102848, [33102848], SEEK_SET) = 0
> read(8, "\n\0\0\0M\0\376\0\1X\1b\1l\1v\1\200\1\212\1\224\1\ 236\1"...,
> 1024) = 1024
> write(12,
> "\0\0~H\n\0\0\0M\0\376\0\1X\1b\1l\1v\1\200\1\212\1\ 224\1"..., 1032) =
> 1032
> write(12,
> "\0\0}\202\r\0\0\0\6\3\254\0\3\362\3\344\3\326\3\31 0\3\272"..., 1032)
> = 1032
> write(12,
> "\0\0~\21\2\0\0\0$\2\10\0\0\0~H\2N\2\\\2j\2x\2\206\ 2\224"..., 1032) =
> 1032
> _llseek(8, 33092608, [33092608], SEEK_SET) = 0
> read(8, "\n\0\0\0M\0\376\0\0\376\1\10\1\22\1\34\1&\0010\1:\1D\1"...,
> 1024) = 1024
> _llseek(8, 33093632, [33093632], SEEK_SET) = 0
> read(8, "\n\0\0\0L\1\10\0\1\10\1\22\1\34\1&\0010\1:\1D\1N\1X\1b"...,
> 1024) = 1024
> write(12, "\0\0~>\n\0\0\0M\0\376\0\0\376\1\10\1\22\1\34\1&\0010\1"...,
> 1032) = 1032
> write(12, "\0\0~?\n\0\0\0L\1\10\0\1\10\1\22\1\34\1&\0010\1:\1D\1N"...,
> 1032) = 1032
> write(12,
> "\0\0}A\5\0\0\0f\0016\0\0\0}\202\1\203\1\212\1\221\ 1\230"..., 1032) =
> 1032
> write(12,
> "\0\0}\360\r\0\0\0$\2\3\0\2\3\2\21\2\37\2-\2;\2I\2W\2e\2"..., 1032) =
> 1032
> write(12,
> "\0\0}\377\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12,
> "\0\0}\372\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12,
> "\0\0}\370\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12, "\0\0}8\5\0\0\0f\0016\0\0\0|?\0016\1=\1D\1K\1R\1Y\1 `\1g"...,
> 1032) = 1032
> write(12, "\0\0}@\5\0\0\0f\0016\0\0\0|\340\0016\1=\1D\1K\1R\1 Y\1`"...,
> 1032) = 1032
> write(12,
> "\0\0x\261\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12,
> "\0\0}\344\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12,
> "\0\0x\307\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12,
> "\0\0}\305\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> _llseek(8, 32964608, [32964608], SEEK_SET) = 0
> read(8, "\2\0\0\0007\0\376\0\0\0|\362\0\376\1\f\1\32\1(\001 6\1D"...,
> 1024) = 1024
> _llseek(8, 32966656, [32966656], SEEK_SET) = 0
> read(8, "\2\0\0\0/\1n\0\0\0}\211\1n\1|\1\212\1\230\1\246\1\264\1"...,
> 1024) = 1024
> write(12, "\0\0}\301\2\0\0\0007\0\376\0\0\0|\362\0\376\1\f\1\ 32\1"...,
> 1032) = 1032
> write(12,
> "\0\0}\303\2\0\0\0/\1n\0\0\0}\211\1n\1|\1\212\1\230\1\246"..., 1032) =
> 1032
> write(12,
> "\0\0x\320\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12, "\0\0z\234\2\0\0\0&\1\354\0\0\0yY\1\354\1\372\2\10\2\26"...,
> 1032) = 1032
> write(12,
> "\0\0x\324\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12,
> "\0\0y\261\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12,
> "\0\0x\342\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12,
> "\0\0x\334\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12,
> "\0\0x\330\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12,
> "\0\0x\314\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12,
> "\0\0x\301\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> write(12,
> "\0\0x\234\n\0\0\0J\1\34\0\1\34\1&\0010\1:\1D\1N\1X\1b\1"..., 1032) =
> 1032
> select(16, [4 5 6 7 10], [13], [10 13], {0, 0}) = 3 (in [5 10], out
> [13], left {0, 0})
> getpeername(10, 0xd798fc8, [256]) = -1 ENOTCONN (Transport
> endpoint is not connected)
> close(10) = 0
> --- SIGSEGV (Segmentation fault) @ 0 (0) ---
> Process 23276 detached
>
>
>
> On Apr 29, 2005, at 8:31 AM, Healy wrote:
>
>> Yeah, I'll try that next time. Of course, since I'm trying to get it
>> to crash, it's been solid for about the last
>> 12 hours...
>>
>>
>> On Apr 29, 2005, at 8:08 AM, dean blackketter wrote:
>>
>>> Healy,
>>>
>>> Try running it from the command line and watch for a crash and see
>>> what error is emitted.
>>>
>>> On Apr 29, 2005, at 6:56 AM, Healy wrote:
>>>
>>>> Nothing in a non-debug log. Trying again, this time running a
>>>> strace on the parent process as well.
>>>>
>>>

Dan Sully
2005-04-30, 19:34
* Healy shaped the electrons to say...

>Parent process died again. I can't find anything in the debug log.
>files inline. I'm stumped.

Can you run inside gdb:

gdb perl
prompt> r /path/to/slimserver.pl

When it crashes, type 'bt' for backtrace.

-D
--
<dr.pox> does whistling in the dark make me go blind faster?

healy
2005-05-01, 00:31
Ack, running it this way now & will let you know.


On Apr 30, 2005, at 7:34 PM, Dan Sully wrote:

> * Healy shaped the electrons to say...
>
>> Parent process died again. I can't find anything in the debug log.
>> files inline. I'm stumped.
>
> Can you run inside gdb:
>
> gdb perl
> prompt> r /path/to/slimserver.pl
>
> When it crashes, type 'bt' for backtrace.
>
> -D
> --
> <dr.pox> does whistling in the dark make me go blind faster?
>

healy
2005-05-03, 07:11
It crashed again. However, I think I finally tracked it down. Not a
slim issue at all. Appears my boot drive has got
seek errors & is causing the / file system to remount read only. Reeks
havoc on anything needing to write to /tmp.
DOH! Looks like I have a box to rebuild....

May 3 06:25:10 mp3 kernel: hda: dma_intr: status=0x51 { DriveReady
SeekComplete Error }
May 3 06:25:11 mp3 kernel: hda: dma_intr: error=0x40 {
UncorrectableError }, LBAsect=7602274, secto
r=7602271
May 3 06:25:11 mp3 kernel: ide: failed opcode was: unknown
May 3 06:25:11 mp3 kernel: end_request: I/O error, dev hda, sector
7602271
May 3 06:25:11 mp3 kernel: EXT3-fs error (device hda1):
ext3_get_inode_loc: unable to read inode bl
ock - inode=473281, block=950276
May 3 06:25:11 mp3 kernel: Remounting filesystem read-only
May 3 06:25:14 mp3 kernel: hda: dma_intr: status=0x51 { DriveReady
SeekComplete Error }
May 3 06:25:14 mp3 kernel: hda: dma_intr: error=0x40 {
UncorrectableError }, LBAsect=7602274, secto
r=7602271


# gdb perl
GNU gdb 6.3-debian
Copyright 2004 Free Software Foundation, Inc.
GDB is free software, covered by the GNU General Public License, and
you are
welcome to change it and/or distribute copies of it under certain
conditions.
Type "show copying" to see the conditions.
There is absolutely no warranty for GDB. Type "show warranty" for
details.
This GDB was configured as "i386-linux"...(no debugging symbols found)
Using host libthread_db library "/lib/tls/libthread_db.so.1".

(gdb) r /usr/local/slimserver/slimserver.pl --user slimserver --logfile
/home/slimserver/log/slimserver.log --prefsfile
/home/slimserver/.slimserver.pref --cachedir /home/slimserver/cache/
--pidfile /home/slimserver/var/slimserver.pid --d_server --d_info
Starting program: /usr/bin/perl /usr/local/slimserver/slimserver.pl
--user slimserver --logfile /home/slimserver/log/slimserver.log
--prefsfile /home/slimserver/.slimserver.pref --cachedir
/home/slimserver/cache/ --pidfile /home/slimserver/var/slimserver.pid
--d_server --d_info
(no debugging symbols found)
(no debugging symbols found)
(no debugging symbols found)
(no debugging symbols found)
[Thread debugging using libthread_db enabled]
[New Thread -1209744640 (LWP 2244)]
(no debugging symbols found)
(no debugging symbols found)
(no debugging symbols found)
(no debugging symbols found)
(no debugging symbols found)
(no debugging symbols found)
(no debugging symbols found)
(no debugging symbols found)
2005-05-02 10:26:17.6466 SlimServer OSDetect init...
2005-05-02 10:26:17.6470 SlimServer OS Specific init...
2005-05-02 10:26:17.6472 SlimServer settings effective user and group
if requested...
2005-05-02 10:26:17.6816 SlimServer settings init...
2005-05-02 10:26:17.7050 SlimServer strings init...
2005-05-02 10:26:17.8253 SlimServer Setup init...
2005-05-02 10:26:17.8344 SlimServer setting language...
2005-05-02 10:26:17.8348 SlimServer IR init...
2005-05-02 10:26:17.8411 SlimServer Buttons init...
2005-05-02 10:26:17.8429 SlimServer Graphics init...
2005-05-02 10:26:19.6423 SlimServer starting up...
2005-05-02 10:26:19.6428 SlimServer saving pid file.

Program received signal SIGSEGV, Segmentation fault.
[Switching to Thread -1209744640 (LWP 2244)]
0x0806c18c in Perl_amagic_call ()
(gdb) bt
#0 0x0806c18c in Perl_amagic_call ()
#1 0x080c8600 in Perl_sv_2bool ()
#2 0x080bc127 in Perl_pp_and ()
#3 0x080bbdc9 in Perl_runops_standard ()
#4 0x080635e8 in perl_run ()
#5 0x080633f5 in perl_run ()
#6 0x0805fb9f in main ()
(gdb)



# ps -ef|grep slim
slimser 2244 2243 3 May02 pts/1 00:40:24 /usr/bin/perl
/usr/local/slimserver/slimserver.pl --user slimserver --logfile
/home/slimserver/log/slimserver.log --prefsfile
/home/slimserver/.slimserver.pref --cachedir /home/slimserver/cache/
--pidfile /home/slimserver/var/slimserver.pid --d_server --d_info
slimser 2250 2244 0 May02 pts/1 00:00:00
/usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
mDNSResponderPosix -n SlimServer -t _http._tcp -p 9000
slimser 2251 2244 0 May02 pts/1 00:00:00
/usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
mDNSResponderPosix -n SlimServer -t _slimhttp._tcp -p 9000
slimser 2252 2244 0 May02 pts/1 00:00:00
/usr/local/SlimServer_6_0_x_v2005-04-21/Bin/i386-linux/
mDNSResponderPosix -n SlimServer -t _slimcli._tcp -p 9090
root 22738 2194 0 06:54 pts/0 00:00:00 grep slim




On May 1, 2005, at 12:31 AM, Healy wrote:

> Ack, running it this way now & will let you know.
>
>
> On Apr 30, 2005, at 7:34 PM, Dan Sully wrote:
>
>> * Healy shaped the electrons to say...
>>
>>> Parent process died again. I can't find anything in the debug log.
>>> files inline. I'm stumped.
>>
>> Can you run inside gdb:
>>
>> gdb perl
>> prompt> r /path/to/slimserver.pl
>>
>> When it crashes, type 'bt' for backtrace.
>>
>> -D
>> --
>> <dr.pox> does whistling in the dark make me go blind faster?
>>