sc_serv XML output stops after running for a while

[Cabloo]

I don't know what the link between sc_trans and sc_serv is, but I'd assume it was v2 by default (or is it the other way around?).

I added configuration files earlier so if you notice something fishy in there please let me know, but we've been connecting to through sc_trans v2 when DJing for a while now.

[Cabloo]

The XML broke again. I was online at the time watching the song list and XML file. Here's what I noticed that was very weird.

First, I skipped a song ("Sam B - Who do you voodoo, Bitch") using the transcoder (I don't think that had anything to do with it though, as it typically just fails randomly and skipping songs w/ the transcoder has always worked). The XML file didn't update (it stayed the same as the previous song) all through the next song (which I let play through in full). Then a jingle played. Then another song started and the XML still did not update (stayed the same as the song I initially skipped). I skipped another song with the transcoder and then I went to /admin.cgi?mode=viewxml&page=5&sid=1 which was now fully broken:

<SHOUTCASTSERVER>
<SONGMETADATA/>
</SHOUTCASTSERVER>

The logs at the time:

2012-04-12 05:26:48 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:28:57 I msg:[DST 66.190.160.26:49379 sid=1] SHOUTcast 1 client connection accepted. Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.15 Safari/536.5'
2012-04-12 05:28:57 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:29:02 E msg:[DST 66.190.160.26:49379 sid=1] Socket error while waiting to send data. err=Broken pipe(32)
2012-04-12 05:29:02 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:29:02 I msg:[DST 66.190.160.26:49379 sid=1] SHOUTcast 1 client connection closed (5 seconds) [Bytes: 410167] Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.15 Safari/536.5'
2012-04-12 05:29:02 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:32:19 I msg:[SRC 127.0.0.1:50123 sid=1] Title update [Sam B - Who Do You Voodoo, Bitch]
2012-04-12 05:32:19 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:34:07 E msg:[DST 98.82.118.79:49214 sid=1] Socket error while waiting to send data. err=Broken pipe(32)
2012-04-12 05:34:07 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:34:07 I msg:[DST 98.82.118.79:49214 sid=1] SHOUTcast 1 client connection closed (1104 seconds) [Bytes: 44377323] Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:34:07 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:34:11 I msg:[DST 98.82.118.79:49559 sid=1] SHOUTcast 1 client connection accepted. Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:34:11 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:34:16 I msg:[SRC 127.0.0.1:50123 sid=1] Title update [Enrique Iglesias ft. Pitbull - Tonight (Official Remix) (NoShout) (2010) [www.RnB4U.in]]
2012-04-12 05:34:16 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:36 E msg:[DST 98.82.118.79:49559 sid=1] Socket error while waiting to send data. err=Broken pipe(32)
2012-04-12 05:35:36 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:36 I msg:[DST 98.82.118.79:49559 sid=1] SHOUTcast 1 client connection closed (85 seconds) [Bytes: 3628854] Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:35:36 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:38 I msg:[DST 98.82.118.79:49628 sid=1] SHOUTcast 1 client connection accepted. Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:35:38 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:42 E msg:[DST 98.82.118.79:49628 sid=1] Socket error while waiting to send data. err=Broken pipe(32)
2012-04-12 05:35:42 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:42 I msg:[DST 98.82.118.79:49628 sid=1] SHOUTcast 1 client connection closed (4 seconds) [Bytes: 410087] Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:35:42 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:44 I msg:[DST 98.82.118.79:49631 sid=1] SHOUTcast 1 client connection accepted. Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:35:44 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:47 E msg:[DST 98.82.118.79:49631 sid=1] Socket error while waiting to send data. err=Broken pipe(32)
2012-04-12 05:35:47 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:47 I msg:[DST 98.82.118.79:49631 sid=1] SHOUTcast 1 client connection closed (3 seconds) [Bytes: 382711] Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:35:47 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:47 I msg:[DST 98.82.118.79:49634 sid=1] SHOUTcast 1 client connection accepted. Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:35:47 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:36:13 E msg:[SRC 98.82.118.79:49656] Remote socket closed while waiting for data.
2012-04-12 05:36:13 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:36:13 E msg:[SRC 98.82.118.79:49654] Remote socket closed while waiting for data.
2012-04-12 05:36:13 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:36:13 E msg:[SRC 98.82.118.79:49655] Remote socket closed while waiting for data.
2012-04-12 05:36:13 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:38:15 I msg:[SRC 127.0.0.1:50123 sid=1] Title update [ad-2]
2012-04-12 05:38:15 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:38:43 I msg:[SRC 127.0.0.1:50123 sid=1] Title update [Royal Republic - Tommy-Gun]
2012-04-12 05:38:43 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:39:12 E msg:[DST 98.82.118.79:49634 sid=1] Socket error while waiting to send data. err=Broken pipe(32)
2012-04-12 05:39:12 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:39:12 I msg:[DST 98.82.118.79:49634 sid=1] SHOUTcast 1 client connection closed (205 seconds) [Bytes: 8411854] Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:39:12 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:36 I msg:[DST 98.82.118.79:49559 sid=1] SHOUTcast 1 client connection closed (85 seconds) [Bytes: 3628854] Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:35:36 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:38 I msg:[DST 98.82.118.79:49628 sid=1] SHOUTcast 1 client connection accepted. Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:35:38 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:42 E msg:[DST 98.82.118.79:49628 sid=1] Socket error while waiting to send data. err=Broken pipe(32)
2012-04-12 05:35:42 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:42 I msg:[DST 98.82.118.79:49628 sid=1] SHOUTcast 1 client connection closed (4 seconds) [Bytes: 410087] Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:35:42 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:44 I msg:[DST 98.82.118.79:49631 sid=1] SHOUTcast 1 client connection accepted. Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:35:44 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:47 E msg:[DST 98.82.118.79:49631 sid=1] Socket error while waiting to send data. err=Broken pipe(32)
2012-04-12 05:35:47 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:47 I msg:[DST 98.82.118.79:49631 sid=1] SHOUTcast 1 client connection closed (3 seconds) [Bytes: 382711] Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:35:47 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:35:47 I msg:[DST 98.82.118.79:49634 sid=1] SHOUTcast 1 client connection accepted. Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:35:47 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:36:13 E msg:[SRC 98.82.118.79:49656] Remote socket closed while waiting for data.
2012-04-12 05:36:13 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:36:13 E msg:[SRC 98.82.118.79:49654] Remote socket closed while waiting for data.
2012-04-12 05:36:13 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:36:13 E msg:[SRC 98.82.118.79:49655] Remote socket closed while waiting for data.
2012-04-12 05:36:13 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:38:15 I msg:[SRC 127.0.0.1:50123 sid=1] Title update [ad-2]
2012-04-12 05:38:15 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:38:43 I msg:[SRC 127.0.0.1:50123 sid=1] Title update [Royal Republic - Tommy-Gun]
2012-04-12 05:38:43 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:39:12 E msg:[DST 98.82.118.79:49634 sid=1] Socket error while waiting to send data. err=Broken pipe(32)
2012-04-12 05:39:12 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:39:12 I msg:[DST 98.82.118.79:49634 sid=1] SHOUTcast 1 client connection closed (205 seconds) [Bytes: 8411854] Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:39:12 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:39:13 I msg:[DST 98.82.118.79:49697 sid=1] SHOUTcast 1 client connection accepted. Agent: `Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/535.19 (KHTML, like Gecko) Chrome/18.0.1025.152 Safari/535.19'
2012-04-12 05:39:13 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:39:17 I msg:[SRC 127.0.0.1:50123 sid=1] Title update [Muse - Resistance (Dj Tiesto Remix)]
2012-04-12 05:39:17 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue
2012-04-12 05:39:23 E msg:[SRC 98.82.118.79:49698] Remote socket closed while waiting for data.
2012-04-12 05:39:23 E msg:virtual void AOL_logger::consoleLogger_element::log(const AOL_logger::message&) Error writing to console - write issue

Note: After skipping "Tommy Gun" is when the XML failed completely. After "Who do you voodoo, bitch" is when the XML paused and produced the same result a few times in a row.

I'm enabling full debug mode now and resetting the server.

[Cabloo]

Actually, after looking at the logs, it looks like the XML froze right at

2012-04-12 05:34:16 I msg:[SRC 127.0.0.1:50123 sid=1] Title update [Enrique Iglesias ft. Pitbull - Tonight (Official Remix) (NoShout) (2010) [www.RnB4U.in]]

Note the [www.RnB4u.in]]. I wonder if it's not liking the URL or the [] in the title?

[DrO]

Originally Posted by Cabloo

I don't know what the link between sc_trans and sc_serv is, but I'd assume it was v2 by default (or is it the other way around?).

it's coming through in v2 mode based on the log message prefix and because things like the next title are available, though the default of sc_trans is to run in v1 mode and you then have to set outprotocol=3 (though that will be made simpler to just be protocol=1|2 for the next drop.


as for the failure, there's got to be something in the metadata being sent over for it to lock on the previous update or just not show anything - as the minimal response is typical for a cleared metadata cache.

will see if i can replicate anything with that part of the title though am hoping the full title response from the logs will make things easier to determine the cause. does seem weird how it takes so long to appear - are you working with a fixed / periodic playlist order of the same files?

-daz

[Cabloo]

I simply did

ls /root/transcoder/music/*.mp3 > playlists/playlist.lst

Also, I just played that song again (using sc_trans to skip through songs) and nothing happened (altho I only played it for a split second).

[edit]: that song being the one with [www.rnb4u.in]
[reedit]: Also, I noticed one of the listings in one of the XML files for a song had

<TCON v1="13"/>

Could that mean that some are being transmitted through v1? I'm not sure what TCON is but it sounds to me like something to do with connection.

[DrO]

<TCON v1="13"/>

is the ID3v2.x genre field and if it's in an xml metadata update then it's a v2 thing since that's the format used.

i've not been able to get a failure so far with [www.rnb4u.in] added into a song title.

-daz

[Cabloo]

http://localhostr.com/files/kubGcpJ/sc_serv.7z

There is the main log file. It's very long (385,000 lines).

The lines you're looking for are around 119,000, specifically:

2012-04-13 11:52:33 I msg:[SRC 127.0.0.1:46945 sid=1] Got complete metadata message type=3902 [656 bytes] id=1 span=1 content=
<?xml version="1.0" encoding="UTF-8" ?>
<metadata>
<TCON>Blues</TCON>
<TDRC><year>2007</year></TDRC>
<TENC>TRANSCast DNAS/posix(linux x64) v2.0.0.54</TENC>
<TIT2>No Good (Chase &amp; Status and Benni G Remix)</TIT2>
<TPE1>Plan B</TPE1>
<TRCK>16</TRCK>
<TYER>2007</TYER>
<WXXX>http://www.ctpax-x.ru</WXXX>
<extension>
<title seq="1">Plan B - No Good (Chase &amp; Status and Benni G Remix)</title>
<title seq="2">Noisia - Split The Atom (Kito Remix)</title>
<title seq="3">Royal Republic - Tommy-Gun</title>
<title seq="4">Avicii - Levels (Cazzettes NYC Mode Radio Mix)</title>
<soon>Noisia - Split The Atom (Kito Remix)</soon>
</extension>
</metadata>

This is the song that the XML failed on. I'm not sure if it has anything to do with the http://www.ctpax-x.ru or the &amp; or what. I looked through the nearby lines in the log file and nothing stuck out to me - perhaps you can shed some light on that matter.

Thanks again for your help.

[DrO]

i've just been trying for the last hour to replicate it with the complete responses from the log file and it's just not failing whatever i try. could you email me the actual audio file please.

and the &amp; stuff is correct since that is how & is output in xml.

-daz

[Cabloo]

Yes, I'll send the music in a PM.

[DrO]

thanks, getting it now...

-daz

[Cabloo]

Any luck, daz?

[DrO]

sorry, haven't had a chance to try things out with the files and other things are going on now which are taking up my time. when i get a chance then i'll try things out.

-daz

[Cabloo]

That's fine, I understand.

[DrO]

as it's been 2 months, can i assume that the test build resolved the issue(s) mentioned above?

-daz

[Cabloo]

yes, it seems to be working.

[DrO]

rightio, thanks for the confirmation.

-daz