Go Back   Winamp & Shoutcast Forums > Shoutcast > Shoutcast Technical Support

Reply
Thread Tools Search this Thread Display Modes
Old 10th November 2011, 22:07   #1
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
DNAS v2 using a lot more CPU than v1 + Crashes [Crashes Resolved in Internal Builds]

I've noticed since switching to DNAS v2 that it's using a lot more CPU than v1 did. Before, I had five instances of v1 running, and combined they would use less than 10% of my CPU. Now with v2 and all the streams running through the one exe instance, it's usually upwards of 60% of my CPU at times, an averages around 30-50%, which is significantly higher...

Any idea why?
jeffy777 is offline   Reply With Quote
Old 10th November 2011, 22:26   #2
DrO
 
Join Date: Sep 2003
Posts: 27,873
i really cannot comment on how the v1 DNAS compares to the v2 DNAS as i've never actually researched it (probably should have but was never put in my criteria of things to work on). though it's harder to compare something which is built in a completely different manner internally to the v1 DNAS.

i do know that the v2 DNAS does a lot more internally than the v1 DNAS ever did (due to having to cope with more things going on, internal conversion of the stream between v1 and v2 formats depending on the client and source types connected to the DNAS. that would add to cpu usage though if you've got one instance doing everything then it is likely that it would use more cpu in general compared to separate DNAS instances - though OS, and other aspects could be an aspect affecting it - as the Windows versions always seem to do better for me due to having better compiler optimisations done on them (or so it has seemed to be).

at some point i'd like to (assuming i'm still on the project) be able to look into doing optimisation to cut cpu / memory usage where possible though at the moment, having a functional v2 DNAS is a higher priority before then going back and re-assessing code i've never touched to look for speed improvements.

though that probably doesn't answer the question, at the moment it's the unfortunate trade off when moving to a new codebase and broadcasting platform until things are fully stabilised to make optimising sensible (yes i know things should be optimised as you go and that is attempted but only so much can be done without hindering getting things released).

-daz
DrO is offline   Reply With Quote
Old 10th November 2011, 22:43   #3
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
Thanks for the detailed reply. I appreciate it,

With v1, all the instances combined used very little CPU, with a total average of less than 10%, so each one was using less than 1% most of the time. It's just a bit shocking to see it increase so much (especially when I use the box for other stuff too).

The weird part is that it seemed like the CPU usage was low for the first few days after installing the new version, but it's risen over the past week. I'll try restarting it and see if that helps (I just hate to drop my listeners, but it may be worth it).
jeffy777 is offline   Reply With Quote
Old 10th November 2011, 22:49   #4
DrO
 
Join Date: Sep 2003
Posts: 27,873
that does seem a bit strange if it increased and i assume the listener counts were about the same over that time?

funnily enough i'm in the process of setting up some additional test setups to allow me to better assess how things work over longer periods of time, though at the moment nothing really comes to mind which would cause cpu usage to keep growing (unless some of the client connection threads aren't ending correctly / going into a dead lock or something).

what specs is the machine you're seeing this on?

-daz
DrO is offline   Reply With Quote
Old 10th November 2011, 23:09   #5
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
Yes, the listener counts were the same throughout.

I restarted sc_serv and CPU usage is much lower now, though not all the listeners came back. I'll see how it is tomorrow when there'll probably be around 400 listeners again. Right now there's about 150 listeners and the CPU usage is only 5-10%....so that's good.

Here are the specs:

Intel dual core E8400
4GB RAM
2x 500GB HDD
Windows 2008 Standard 64-bit

thanks,
Jeff
jeffy777 is offline   Reply With Quote
Old 11th November 2011, 11:28   #6
DrO
 
Join Date: Sep 2003
Posts: 27,873
hmm, strange indeed as that should be more than capable without having any excessive cpu usage. will be interesting to see what happens with a fuller load, etc and may have to give you a newer version of the DNAS to test things out with if it's still happening.

-daz
DrO is offline   Reply With Quote
Old 11th November 2011, 20:41   #7
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
Today I have about 300 listeners total and it's using 20-30% CPU, so it's not quite as bad, but I'll keep any eye on it and see if it keeps going up after a few days like it did before.
jeffy777 is offline   Reply With Quote
Old 14th November 2011, 22:22   #8
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
Today it's creeped back up to 30-50% again.....314 listeners total....
jeffy777 is offline   Reply With Quote
Old 14th November 2011, 22:32   #9
DrO
 
Join Date: Sep 2003
Posts: 27,873
there's nothing dodgy jumping out from the logs / connected client listener reports (e.g. possible zombie connections), etc?

will have to sort out a test build for you to try out against the current code over the next day or so (have gone through a few places and removed some unnecessary processing though build isn't quite ready for testing at the moment).

though is a bit perplexing, will have to have a sleep on it to think what might be causing it.

-daz
DrO is offline   Reply With Quote
Old 15th November 2011, 00:59   #10
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
Quote:
Originally Posted by DrO View Post
there's nothing dodgy jumping out from the logs / connected client listener reports (e.g. possible zombie connections), etc?

will have to sort out a test build for you to try out against the current code over the next day or so (have gone through a few places and removed some unnecessary processing though build isn't quite ready for testing at the moment).

though is a bit perplexing, will have to have a sleep on it to think what might be causing it.

-daz
Is there anything specific I should look for in the logs?

A test build would be great. I really appreciate your help, DrO.
jeffy777 is offline   Reply With Quote
Old 15th November 2011, 23:07   #11
DrO
 
Join Date: Sep 2003
Posts: 27,873
the main things would be any sort of dodgy client connections / requests being made or fake sources trying to connect to the DNAS i.e. anything that might be hammering or any clients with dodgy looking user agents / behaviour.

-daz
DrO is offline   Reply With Quote
Old 18th November 2011, 17:48   #12
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
The CPU usage has seemed OK lately.....However, the past two mornings I've woken up to find that the server has crashed both mornings (the window is still open, but Windows tells me that sc_serv has stopped responding). This morning, the last thing in the log was nothing unusual: just the shoutcast puller closing a connection....

I have no idea why it just started doing this, as I haven't changed anything, or even installed anything new on the server.

I really hope this is not going to be a continuing trend, or I might have to go back to v1, but I really don't want to

EDIT:

I took a closer look at the logs.

This is the end of the log before it crashed this morning. I bolded the part that looks suspicious:

Quote:
2011-11-19 01:58:19 I msg:[DST 64.12.243.206:43505 sid=1] SHOUTcast 1 client connection accepted. SHOUTcast Metadata Puller
2011-11-19 01:58:19 E msg:[DST 64.12.243.206:43505 sid=1] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-19 01:58:19 I msg:[DST 64.12.243.206:43505 sid=1] SHOUTcast 1 client connection closed (0 seconds) [Bytes: 16384] Agent: `SHOUTcast Metadata Puller'
2011-11-19 01:58:20 E msg:[DST 202.161.33.32:44748 sid=1] Timeout waiting to send data (1321696700 1321696670[30] )
2011-11-19 01:58:20 I msg:[DST 202.161.33.32:44748 sid=1] SHOUTcast 1 client connection closed (146 seconds) [Bytes: 598016] Agent: `NSPlayer/12.00.7600.16385 WMFSDK/12.00.7600.16385'
2011-11-19 01:58:21 I msg:[DST 205.188.202.174:54115 sid=1] SHOUTcast 1 client connection accepted. SHOUTcast Metadata Puller
2011-11-19 01:58:22 E msg:[DST 205.188.202.174:54115 sid=1] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-19 01:58:22 I msg:[DST 205.188.202.174:54115 sid=1] SHOUTcast 1 client connection closed (1 seconds) [Bytes: 16384] Agent: `SHOUTcast Metadata Puller'
2011-11-19 01:58:27 I msg:[DST 205.188.202.174:54509 sid=1] SHOUTcast 1 client connection accepted. SHOUTcast Metadata Puller
2011-11-19 01:58:27 E msg:[DST 205.188.202.174:54509 sid=1] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-19 01:58:27 I msg:[DST 205.188.202.174:54509 sid=1] SHOUTcast 1 client connection closed (0 seconds) [Bytes: 16384] Agent: `SHOUTcast Metadata Puller'
2011-11-19 01:58:30 I msg:[DST 205.188.202.174:54720 sid=1] SHOUTcast 1 client connection accepted. SHOUTcast Metadata Puller
2011-11-19 01:58:30 E msg:[DST 205.188.202.174:54720 sid=1] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-19 01:58:30 I msg:[DST 205.188.202.174:54720 sid=1] SHOUTcast 1 client connection closed (0 seconds) [Bytes: 16384] Agent: `SHOUTcast Metadata Puller'
2011-11-19 01:58:35 I msg:[SRC 211.72.110.37:18943 sid=1] SHOUTcast 1 source connection.
2011-11-19 01:58:35 E msg:[SRC 211.72.110.37:18943 sid=1] connection denied. Bad password.
2011-11-19 01:58:35 I msg:[DST 211.72.110.37:18944 sid=1] SHOUTcast 1 client connection accepted.
2011-11-19 01:58:35 I msg:[DST 211.72.110.37:18942 sid=2] SHOUTcast 1 client connection accepted. Lavf53.4.0
2011-11-19 01:58:35 I msg:[DST 211.72.110.37:18945 sid=2] SHOUTcast 1 client connection accepted. NSPlayer/4.1.0.3856
2011-11-19 01:58:37 E msg:[DST 211.72.110.37:18945 sid=2] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-19 01:58:37 I msg:[DST 211.72.110.37:18945 sid=2] SHOUTcast 1 client connection closed (2 seconds) [Bytes: 267079] Agent: `NSPlayer/4.1.0.3856'

2011-11-19 01:58:37 I msg:[DST 205.188.202.174:55069 sid=1] SHOUTcast 1 client connection accepted. SHOUTcast Metadata Puller
2011-11-19 01:58:37 E msg:[DST 205.188.202.174:55069 sid=1] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-19 01:58:37 I msg:[DST 205.188.202.174:55069 sid=1] SHOUTcast 1 client connection closed (0 seconds) [Bytes: 16384] Agent: `SHOUTcast Metadata Puller'
2011-11-19 01:58:37 I msg:[DST 64.12.243.204:56295 sid=1] SHOUTcast 1 client connection accepted. SHOUTcast Metadata Puller
2011-11-19 01:58:37 E msg:[DST 64.12.243.204:56295 sid=1] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-19 01:58:37 I msg:[DST 64.12.243.204:56295 sid=1] SHOUTcast 1 client connection closed (0 seconds) [Bytes: 16384] Agent: `SHOUTcast Metadata Puller'
2011-11-19 01:58:37 I msg:[DST 64.12.243.203:45705 sid=1] SHOUTcast 1 client connection accepted. SHOUTcast Metadata Puller
2011-11-19 01:58:37 E msg:[DST 64.12.243.203:45705 sid=1] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-19 01:58:37 I msg:[DST 64.12.243.203:45705 sid=1] SHOUTcast 1 client connection closed (0 seconds) [Bytes: 16384] Agent: `SHOUTcast Metadata Puller'
Is it strange that someone was trying to make a "source connection"?

And here's the end of the log from yesterday right before it crashed......to me it's weird that the same IP is there near the end trying to make a source connection, but there may be more going on:

Quote:
2011-11-18 03:05:04 I msg:[DST 205.188.202.172:64954 sid=1] SHOUTcast 1 client connection accepted. SHOUTcast Metadata Puller
2011-11-18 03:05:04 E msg:[DST 205.188.202.172:64954 sid=1] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-18 03:05:04 I msg:[DST 205.188.202.172:64954 sid=1] SHOUTcast 1 client connection closed (0 seconds) [Bytes: 16384] Agent: `SHOUTcast Metadata Puller'
2011-11-18 03:05:04 I msg:[DST 205.188.202.174:52197 sid=1] SHOUTcast 1 client connection accepted. SHOUTcast Metadata Puller
2011-11-18 03:05:04 E msg:[DST 205.188.202.174:52197 sid=1] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-18 03:05:04 I msg:[DST 205.188.202.174:52197 sid=1] SHOUTcast 1 client connection closed (0 seconds) [Bytes: 16384] Agent: `SHOUTcast Metadata Puller'
2011-11-18 03:05:12 I msg:[DST 64.12.243.206:6026 sid=1] SHOUTcast 1 client connection accepted. SHOUTcast Metadata Puller
2011-11-18 03:05:12 E msg:[DST 64.12.243.206:6026 sid=1] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-18 03:05:12 I msg:[DST 64.12.243.206:6026 sid=1] SHOUTcast 1 client connection closed (0 seconds) [Bytes: 16384] Agent: `SHOUTcast Metadata Puller'
2011-11-18 03:05:13 I msg:[DST 211.72.110.37:14596 sid=2] SHOUTcast 1 client connection accepted. Lavf53.4.0
2011-11-18 03:05:13 I msg:[SRC 211.72.110.37:14598 sid=1] SHOUTcast 1 source connection.
2011-11-18 03:05:13 E msg:[SRC 211.72.110.37:14598 sid=1] connection denied. Bad password.
2011-11-18 03:05:13 I msg:[DST 211.72.110.37:14599 sid=1] SHOUTcast 1 client connection accepted.
2011-11-18 03:05:13 I msg:[DST 211.72.110.37:14600 sid=2] SHOUTcast 1 client connection accepted. NSPlayer/4.1.0.3856
2011-11-18 03:05:14 E msg:[DST 211.72.110.37:14600 sid=2] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-18 03:05:14 I msg:[DST 211.72.110.37:14600 sid=2] SHOUTcast 1 client connection closed (1 seconds) [Bytes: 266659] Agent: `NSPlayer/4.1.0.3856'

2011-11-18 03:05:15 I msg:[DST 64.12.243.204:62497 sid=1] SHOUTcast 1 client connection accepted. SHOUTcast Metadata Puller
2011-11-18 03:05:15 E msg:[DST 64.12.243.204:62497 sid=1] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-18 03:05:15 I msg:[DST 64.12.243.204:62497 sid=1] SHOUTcast 1 client connection closed (0 seconds) [Bytes: 16384] Agent: `SHOUTcast Metadata Puller'
2011-11-18 03:05:15 I msg:[DST 205.188.94.204:62502 sid=2] SHOUTcast 1 client connection accepted. SHOUTcast Metadata Puller
2011-11-18 03:05:16 E msg:[DST 205.188.94.204:62502 sid=2] Socket error while waiting to send data. err=Software caused connection abort.(10053)
2011-11-18 03:05:16 I msg:[DST 205.188.94.204:62502 sid=2] SHOUTcast 1 client connection closed (1 seconds) [Bytes: 16384] Agent: `SHOUTcast Metadata Puller'
2011-11-18 14:18:48 E msg:[SRC 127.0.0.1:61447 sid=5] Timeout waiting for data (1321654728 1321614316[40412] )
2011-11-18 14:18:48 E msg:[SRC 127.0.0.1:61448 sid=1] Timeout waiting for data (1321654728 1321614316[40412] )
2011-11-18 14:18:48 E msg:[SRC 127.0.0.1:61451 sid=6] Timeout waiting for data (1321654728 1321614316[40412] )
2011-11-18 14:18:48 E msg:[DST 98.88.92.68:1132 sid=6] Timeout waiting to send data (1321654728 1321614316[40412] )
2011-11-18 14:18:48 E msg:[DST 71.34.92.2:49900 sid=6] Timeout waiting to send data (1321654728 1321614316[40412] )
2011-11-18 14:18:48 E msg:[DST 173.12.175.85:59761 sid=1] Timeout waiting to send data (1321654728 1321614301[40427] )
jeffy777 is offline   Reply With Quote
Old 18th November 2011, 17:56   #13
DrO
 
Join Date: Sep 2003
Posts: 27,873
that does seem strange especially if all had been ok beforehand. i'll send you a pm with a link to a win64 test build in the next few hours (need to do food shopping and all that other fun stuff first).

[edit]
just seen edit - wasn't there when i first started to reply, will look through things / answer when i'm back.

-daz
DrO is offline   Reply With Quote
Old 18th November 2011, 17:59   #14
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
Quote:
Originally Posted by DrO View Post
that does seem strange especially if all had been ok beforehand. i'll send you a pm with a link to a win64 test build in the next few hours (need to do food shopping and all that other fun stuff first).

[edit]
just seen edit - wasn't there when i first started to reply, will look through things / answer when i'm back.

-daz
Thank you for the fast reply. I really appreciate your help. Please let me know if you need more logs or anything.
jeffy777 is offline   Reply With Quote
Old 18th November 2011, 18:10   #15
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
Also, do you think I should ban that IP, or is it just a coincidence?

I know I banned an IP the other day because it appeared to be trying to DOS attack the SC server (it kept hitting the server with hundreds of connections every minute), but it appears that the ban list is empty now. Does it empty out every time the server is restarted? It could've been this same IP, but I'm not sure.

Thanks again.
jeffy777 is offline   Reply With Quote
Old 18th November 2011, 21:16   #16
DrO
 
Join Date: Sep 2003
Posts: 27,873
i've seen 'fake' source connection attempts when running a DNAS on my own home network though never seen one also attempt to connect as a normal client on stream #2 (was always against stream #1) so could be a bot or something else. if in doubt i'd just ban it.

Quote:
I know I banned an IP the other day because it appeared to be trying to DOS attack the SC server (it kept hitting the server with hundreds of connections every minute), but it appears that the ban list is empty now. Does it empty out every time the server is restarted?
if it crashed then it wouldn't have correctly saved the banned ip to the ban file or the option to not save on exit was disabled or there wasn't write access. will get that changed so it saves to the file when a change to the ban and rip lists happens (if saving is enabled) though it isn't in the win64 test build i've just pm'd.

-daz
DrO is offline   Reply With Quote
Old 18th November 2011, 22:53   #17
MrSinatra
Forum King
 
MrSinatra's Avatar
 
Join Date: Dec 2004
Location: WKPS, State College
Posts: 5,486
Send a message via AIM to MrSinatra
Quote:
Originally Posted by DrO View Post
i do know that the v2 DNAS does a lot more internally than the v1 DNAS ever did (due to having to cope with more things going on, internal conversion of the stream between v1 and v2 formats depending on the client and source types connected to the DNAS.
i'm confused by what you mean here... i thought if you used DNASv2, u had to connect to it in v2 mode, no?

one thing i've always liked about DNASv1 was that it didn't need resources,...old, crappy machines could run it very well, and do a lot of listeners.

PENN STATE Radio or http://www.LION-Radio.org/
--
BUG #1 = Winamp skips short tracks
Wish #1 = Multiple Column Sorting
Wish #2 = Add TCMP/Compilation editing
MrSinatra is offline   Reply With Quote
Old 18th November 2011, 23:06   #18
DrO
 
Join Date: Sep 2003
Posts: 27,873
the v2 DNAS will accept v2 and v1 clients and will take v2 and v1 sources (as long as v1 source support hasn't been disabled - sneaky feature that is present in the internal builds) so you can mix and match things as needed, though generally running it all as v2 is preferred.

-daz
DrO is offline   Reply With Quote
Old 18th November 2011, 23:33   #19
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
Thanks man, I'm gonna try the server here in a bit when the listener counts die down a bit. I really appreciate you sending it to me.

I just went ahead and banned the subnet since the IP is from Taiwan.....

Does "SrcIP" work in v2? I might as well set that to 127.0.0.1 since I don't connect remotely, eh?
jeffy777 is offline   Reply With Quote
Old 18th November 2011, 23:37   #20
DrO
 
Join Date: Sep 2003
Posts: 27,873
srcip should work and you'd also probably want to enable the requirestreamconfigs option which will only allow configured sources to be able to connect.

-daz
DrO is offline   Reply With Quote
Old 19th November 2011, 08:16   #21
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
Alright, thanks. I added that to the config as well.

I have the updated build running and everything is good so far. I'll keep ya updated. Thanks again for all your help.

Last edited by jeffy777; 19th November 2011 at 09:26.
jeffy777 is offline   Reply With Quote
Old 21st November 2011, 04:23   #22
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
So far so good. No crashes and CPU load stays 5-15% with about 200 listeners. I'll let you know how it does tomorrow under heavier load.

Thanks again
jeffy777 is offline   Reply With Quote
Old 21st November 2011, 11:02   #23
DrO
 
Join Date: Sep 2003
Posts: 27,873
rightio, that sounds like it's better than the public build was doing on a similar load, but will have to see how it goes when you've got double the load again

-daz
DrO is offline   Reply With Quote
Old 22nd November 2011, 18:28   #24
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
Woke up this morning to find the server down again. Here is what I see when it crashes, dunno if this helps or not:





As far as the logs go, I don't see anything like last time in terms of source connections. I'm not really sure what to look for otherwise.

I am seeing a lot of "Connection rejected. Bad HTTP header string [Accept]" from one IP.....not sure if that's anything though.

Do you want me to send you logs? I really don't know what else to do, but I'm starting to feel bad for my listeners and missing DNAS v1

Thanks for your help.
jeffy777 is offline   Reply With Quote
Old 22nd November 2011, 20:15   #25
DrO
 
Join Date: Sep 2003
Posts: 27,873
if you can send me the logs (email address is on my site linked below) then will see what i can work out from there, as the crash is in the crt so is likely to be down to the processing of a string as a first guess from the screenshots (seems like might be a bufferoverflow as that pops up for BEX64 errors from a quick search so could be coming from longer usage - the win64 build isn't the one i generally run on Windows so might be i've just missed the issue beforehand).

can feel your pain about this as i'm not happy it keeps dieing since it doesn't make the v2 DNAS look good

-daz
DrO is offline   Reply With Quote
Old 22nd November 2011, 22:30   #26
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
I just PMed you the logs. Thanks again.
jeffy777 is offline   Reply With Quote
Old 22nd November 2011, 22:46   #27
DrO
 
Join Date: Sep 2003
Posts: 27,873
have had a quick look and nothing really jumped out though will have to have a proper look in the morning (though that bad header one is somewhat strange and could be related).

-daz
DrO is offline   Reply With Quote
Old 23rd November 2011, 02:35   #28
ToonRadio
Junior Member
 
Join Date: Nov 2011
Posts: 7
It seems I am having the same issue. The DNAS has been crashing every day for a week now.

Here's my last log file:

============================================

2011-11-22 17:54:42 I msg:[YP2] Connecting to yp.shoutcast.com
2011-11-22 17:57:16 E msg:[DST 82.130.102.46:54779] connection rejected. Bad HTTP header string [Accept]
2011-11-22 17:57:16 E msg:[DST 82.130.102.46:54813] connection rejected. Bad HTTP header string [Accept]
2011-11-22 17:57:19 E msg:[DST 82.130.102.46:55419] connection rejected. Bad HTTP header string [Accept]
2011-11-22 17:57:19 E msg:[DST 82.130.102.46:55517] connection rejected. Bad HTTP header string [Accept]
2011-11-22 17:58:30 I msg:[SRC 208.109.124.134:3686 sid=1] Got complete metadata message type=3902 [355 bytes] id=148 span=1 content=
<?xml version="1.0" encoding="UTF-8"?>
<metadata><TIT2>You are now listening</TIT2><TPE1>[ID}</TPE1><TALB>Toon Radio 2008 IDs</TALB><TLEN>5799</TLEN><TXXX id="songtype">I</TXXX><TENC>SAM Broadcaster 4.9.2</TENC><TRSN>Toon Radio</TRSN><WORS>http://roonradio.net</WORS><extension><title seq="1">[ID} - You are now listening</title></extension></metadata>

2011-11-22 17:58:35 I msg:[SRC 208.109.124.134:3686 sid=1] Got complete metadata message type=3902 [338 bytes] id=149 span=1 content=
<?xml version="1.0" encoding="UTF-8"?>
<metadata><TIT2>Anime 3000 Engrish Promo</TIT2><TPE1>[Promo]</TPE1><TLEN>63530</TLEN><TXXX id="songtype">P</TXXX><TENC>SAM Broadcaster 4.9.2</TENC><TRSN>Toon Radio</TRSN><WORS>http://roonradio.net</WORS><extension><title seq="1">[Promo] - Anime 3000 Engrish Promo</title></extension></metadata>

2011-11-22 17:58:37 I msg:[YP2] Connecting to yp.shoutcast.com
2011-11-22 17:58:48 I msg:[YP2] Connecting to yp.shoutcast.com
2011-11-22 17:59:37 I msg:[SRC 208.109.124.134:3686 sid=1] Got complete metadata message type=3902 [438 bytes] id=150 span=1 content=
<?xml version="1.0" encoding="UTF-8"?>
<metadata><TIT2>Kool Kids Klub Podcast Perfect</TIT2><TPE1>[Promo]</TPE1><TALB>Kool Kids Klub Podcast</TALB><TCON>Vocal</TCON><TLEN>56372</TLEN><TRCK>2</TRCK><TPUB>Toon Radio</TPUB><TXXX id="songtype">P</TXXX><TENC>SAM Broadcaster 4.9.2</TENC><TRSN>Toon Radio</TRSN><WORS>http://roonradio.net</WORS><extension><title seq="1">[Promo] - Kool Kids Klub Podcast Perfect</title></extension></metadata>

2011-11-22 17:59:44 I msg:[YP2] Connecting to yp.shoutcast.com
2011-11-22 18:00:27 E msg:[DST 82.130.102.46:44643] connection rejected. Bad HTTP header string [Accept]
2011-11-22 18:00:28 E msg:[DST 82.130.102.46:44761] connection rejected. Bad HTTP header string [Accept]
2011-11-22 18:00:30 I msg:[SRC 208.109.124.134:3686 sid=1] Got complete metadata message type=3902 [342 bytes] id=151 span=1 content=
<?xml version="1.0" encoding="UTF-8"?>
<metadata><TIT2>Toon Radio</TIT2><TPE1>[ID]</TPE1><TCON>Vocal</TCON><TLEN>9718</TLEN><TPUB>Toon Radio</TPUB><TXXX id="songtype">I</TXXX><TENC>SAM Broadcaster 4.9.2</TENC><TRSN>Toon Radio</TRSN><WORS>http://roonradio.net</WORS><extension><title seq="1">[ID] - Toon Radio</title></extension></metadata>

2011-11-22 18:00:36 I msg:[YP2] Connecting to yp.shoutcast.com
2011-11-22 18:00:39 I msg:[SRC 208.109.124.134:3686 sid=1] Got complete metadata message type=3902 [443 bytes] id=152 span=1 content=
<?xml version="1.0" encoding="UTF-8"?>
<metadata><TIT2>Barbie and the Rockers Theme</TIT2><TPE1>Halm Saban and Shuki Ley</TPE1><TALB>Barbie and the Rockers</TALB><TCON>Pop</TCON><TLEN>63843</TLEN><TRCK>1</TRCK><TXXX id="songtype">S</TXXX><TENC>SAM Broadcaster 4.9.2</TENC><TRSN>Toon Radio</TRSN><WORS>http://roonradio.net</WORS><extension><title seq="1">Halm Saban and Shuki Ley - Barbie and the Rockers Theme</title></extension></metadata>

2011-11-22 18:00:47 I msg:[YP2] Connecting to yp.shoutcast.com
2011-11-22 18:01:42 I msg:[SRC 208.109.124.134:3686 sid=1] Got complete metadata message type=3902 [797 bytes] id=153 span=1 content=
<?xml version="1.0" encoding="UTF-8"?>
<metadata><TIT2>Super Hero</TIT2><TPE1>Takeo Yamashita</TPE1><TALB>Lupin the 3rd - Sideburn Club Mix</TALB><TCON>Pop</TCON><TLEN>371348</TLEN><TRCK>11</TRCK><TCOM>Takeo Yamashita</TCOM><TPUB>Columbia Music</TPUB><WCOM>http://www.amazon.com/exec/obidos/ASIN/B00007E8QT/toonradio-20?dev-t=D3N5BGC55OM0P7%26camp=2025%26link_code=xm2</WCOM><WPAY>http://www.amazon.com/exec/obidos/ASIN/B00007E8QT/toonradio-20?dev-t=D3N5BGC55OM0P7%26camp=2025%26link_code=xm2</WPAY><TXXX id="picture">az_2395_Lupin the 3rd Sideburn Club Mix [2002]_Various Artists.jpg</TXXX><TXXX id="songtype">S</TXXX><TENC>SAM Broadcaster 4.9.2</TENC><TRSN>Toon Radio</TRSN><WORS>http://roonradio.net</WORS><extension><title seq="1">Takeo Yamashita - Super Hero</title></extension></metadata>

2011-11-22 18:01:49 I msg:[YP2] Connecting to yp.shoutcast.com
2011-11-22 18:05:39 I msg:[59.120.53.127] IP in ban list. Disconnecting
2011-11-22 18:06:12 I msg:[59.120.53.127] IP in ban list. Disconnecting
2011-11-22 18:06:12 I msg:[59.120.53.127] IP in ban list. Disconnecting
2011-11-22 18:06:12 I msg:[59.120.53.127] IP in ban list. Disconnecting
2011-11-22 18:06:12 I msg:[SRC 59.120.53.127:52461 sid=1] SHOUTcast 1 source connection.
2011-11-22 18:06:12 E msg:[SRC 59.120.53.127:52461 sid=1] connection denied. Bad password.

================================================================

The DNAS just srashes after that.

This is DNAS 2 on Windows 2003 Web Endition SP2
Computer: GoDaddy.com Dedicated Server: Pentium 4 3.00 GHz, 1.98 GB RAM.

Please note that I am running 3 instances of the DNAS. 2 are designated for mp3 streaming. The thrid aac streaming. Only the mp3 instances crash (with similar log files / IP address / etc). The aac isntance gets the same errors but does not crash. I know the source format shouldn't matter, but it seems like it does.
ToonRadio is offline   Reply With Quote
Old 23rd November 2011, 05:16   #29
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
crashed again

EDIT:

Would running a 32bit build make a difference?

Thanks again for looking at the logs and for all your help.

Last edited by jeffy777; 23rd November 2011 at 06:46.
jeffy777 is offline   Reply With Quote
Old 23rd November 2011, 08:57   #30
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
Quote:
Originally Posted by jeffy777 View Post
crashed again

EDIT:

Would running a 32bit build make a difference?

Thanks again for looking at the logs and for all your help.
I tried the 32bit, but it crashed too....
jeffy777 is offline   Reply With Quote
Old 23rd November 2011, 10:29   #31
DrO
 
Join Date: Sep 2003
Posts: 27,873
between the 82.130.102.46 and 59.120.53.127 IP addresses, i think something funky is going on as i've just found a few more reports of issues relating to those IP addresses. trying to track down what i can though might be easier if either of you two could enable all debug logging options as that will make it easier for me (hopefully) to better pin-point the rough function which is failing (current logs are sadly a bit too vague other than it's all v1 related). if only there was a simple way to remote debug code issues at times like this...

-daz
DrO is offline   Reply With Quote
Old 23rd November 2011, 15:57   #32
DrO
 
Join Date: Sep 2003
Posts: 27,873
ToonRadio / jeffy777: as a follow up, i'm re-working some of the source connection handling to improve the error handling (incase of any bad strings, etc being received) if the issues are related to that (and to better block any dodgy connection attempts which get routed to the v1 source handling).

will try to run off a newer test build (will be win32 so it's just one build that i'm working against) with the changes in it before my day is done and pm you. if you can both run the DNAS will full debug logging on then that would be appreciated. obviously not ideal as things shouldn't be crashing but we'll get there (have to!).

-daz
DrO is offline   Reply With Quote
Old 23rd November 2011, 17:41   #33
DrO
 
Join Date: Sep 2003
Posts: 27,873
pm sent with a link to a newer test build so will see what happens this time around... will check back in the morning to see what issues have happened.

-daz
DrO is offline   Reply With Quote
Old 23rd November 2011, 23:08   #34
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
Thanks daz. New build is up and running with all debug logging enabled. Fingers crossed.
jeffy777 is offline   Reply With Quote
Old 24th November 2011, 02:35   #35
ToonRadio
Junior Member
 
Join Date: Nov 2011
Posts: 7
The new build is up and in debug mode here as well.
ToonRadio is offline   Reply With Quote
Old 24th November 2011, 15:49   #36
jeffy777
Major Dude
 
jeffy777's Avatar
 
Join Date: Jan 2006
Posts: 632
Woke up a few minutes ago to find it crashed again. Daz, I'll pm you the logs later today.
jeffy777 is offline   Reply With Quote
Old 24th November 2011, 15:53   #37
DrO
 
Join Date: Sep 2003
Posts: 27,873
k, had a feeling that was going to be likely case again. oh well fingers crossed the full logs let me pin-point it as it's strange you're one of a few who've reported / experience any sort of crash issue especially on the Windows version as it's mainly linux which has issues and that's from the metadata processing (unless no one else is bothering and just going straight back to the v1 DNAS - wouldn't be surprised based on some of the lovely user feedback i've seen at times).

-daz
DrO is offline   Reply With Quote
Old 24th November 2011, 17:36   #38
ToonRadio
Junior Member
 
Join Date: Nov 2011
Posts: 7
I'll PM my own logs as well. I may temporarily go back to v1 myself soon.

Quote:
Originally Posted by DrO View Post
the v2 DNAS will accept v2 and v1 clients and will take v2 and v1 sources (as long as v1 source support hasn't been disabled
Since the problem seems to be coming from a v1 source, maybe disabling v1 support for sources would be a good thing.

It won't fix the problem, but it would isolate it a bit.
ToonRadio is offline   Reply With Quote
Old 24th November 2011, 18:10   #39
DrO
 
Join Date: Sep 2003
Posts: 27,873
ToonRadio: got the log and that's helped a lot thanks. will get a fixed build up in the next few hours for the issue (which is a client handling issue and doesn't affect source handling despite the other quirks which have been going on - but that handling has all been working fine based on your log).

if you do want to disable v1 source connections completely, you'd need to set portlegacy=0 in the config file when used with the test builds (won't work with the public v2 DNAS).

also hoping jeffy777 will be able to do that as well so i can try to kill 2 birds with one build.

-daz
DrO is offline   Reply With Quote
Old 24th November 2011, 23:05   #40
DrO
 
Join Date: Sep 2003
Posts: 27,873
ToonRadio / jeffy777: pm sent with link to another test build (win32 and win64 respectively) which will at least resolve the issue ToonRadio is seeing from the log provided, not sure for jeffy777 (seeing the logs from the last crash would still be useful though i've a feeling it's going to be the same issue as ToonRadio's log showed).

-daz
DrO is offline   Reply With Quote
Reply
Go Back   Winamp & Shoutcast Forums > Shoutcast > Shoutcast Technical Support

Thread Tools Search this Thread
Search this Thread:

Advanced Search
Display Modes

Posting Rules
You may not post new threads
You may not post replies
You may not post attachments
You may not edit your posts

BB code is On
Smilies are On
[IMG] code is On
HTML code is Off

Forum Jump