I'm assuming that the "warning message" is to be expected...2012/12/29 00:15:01 [main:2]
2012/12/29 00:15:01 [main:2] DISCLAIMER: This version of Liquidsoap has been
2012/12/29 00:15:01 [main:2] compiled from a snapshot of the development code.
2012/12/29 00:15:01 [main:2] As such, it should not be used in production
2012/12/29 00:15:01 [main:2] unless you know what you are doing!
2012/12/29 00:15:01 [main:2]
2012/12/29 00:15:01 [main:2] We are, however, very interested in any feedback
2012/12/29 00:15:01 [main:2] about our development code and committed to fix
2012/12/29 00:15:01 [main:2] issues as soon as possible.
2012/12/29 00:15:01 [main:2]
2012/12/29 00:15:01 [main:2] If you are interested in collaborating to
2012/12/29 00:15:01 [main:2] the development of Liquidsoap, feel free to
2012/12/29 00:15:01 [main:2] drop us a mail at <savonet-devl@lists.sf.net>
2012/12/29 00:15:01 [main:2] or to join the #savonet IRC channel on Freenode.
2012/12/29 00:15:01 [main:2]
2012/12/29 00:15:01 [main:2] Please send any bug report or feature request
2012/12/29 00:15:01 [main:2] at <http://dev.sourcefabric.org/browse/LS>.
2012/12/29 00:15:01 [main:2]
2012/12/29 00:15:01 [main:2] We hope you enjoy this snapshot build of Liquidsoap!
2012/12/29 00:15:01 [main:2]
2012/12/29 00:15:01 [dynamic.loader:3] Could not find dynamic module for aacplus encoder.
Since I couldn't find anything else obvious in the log file, I created a test scenario that duplicates the problem.2012/12/29 00:15:01 [protocols.external:3] Didn't find "ufetch".
2012/12/29 00:15:01 [protocols.external:3] Found "/usr/bin/wget".
2012/12/29 00:15:01 [main:3] Liquidsoap 1.0.1+scm
KCFR - CPR
Name:KCFR - CPRLength:08:30:00Creator:adminDescription:KCFR CPR - Colorado Public Radio
pypo.log (hopefully this is of use... I left out a couple of lines that were extremely verbose, and didn't seem relevant, if you need 'em I can past 'em in too):2012/12/30 12:40:09 [server:3] New client: localhost.localdomain.
2012/12/30 12:40:09 [lang:3] streams.scheduled_play_start
2012/12/30 12:40:09 [lang:3] dynamic_source.output_start
2012/12/30 12:40:09 [lang:3] /usr/lib/airtime/pypo/bin/liquidsoap_scripts/notify.sh --data='0' --media-id=-1 &
2012/12/30 12:40:09 [server:3] Client localhost.localdomain disconnected.
so it fails, and then goes on to queue up the next web stream (LRN.fm) which also failed.2012-12-29 00:12:40,795 INFO - [api_client.py : get_bootstrap_info() : line 672] - Bootstrap info retrieved {u'transition_fade': u
'01.000', u'station_name': u'Radio Station Name', u'stream_label': u'2', u'switch_status': {u'scheduled_play': u'on', u'live_dj': u'off
', u'master_dj': u'off'}}
2012-12-29 00:12:40,795 DEBUG - [pypofetch.py : set_bootstrap_variables() : line 184] - info:{u'transition_fade': u'01.000', u'sta
tion_name': u'Radio Station Name', u'stream_label': u'2', u'switch_status': {u'scheduled_play': u'on', u'live_dj': u'off', u'master_dj'
: u'off'}}
2012-12-29 00:12:40,795 DEBUG - [pypofetch.py : switch_source() : line 148] - Switching source: scheduled_play to "on" status
2012-12-30 12:40:00,001 DEBUG - [pypofetch.py : disconnect_source() : line 128] - Disconnecting source: live_dj
2012-12-30 12:40:00,001 ERROR - [pypofetch.py : disconnect_source() : line 142] - [Errno 111] Connection refused
2012-12-30 12:40:00,001 DEBUG - [pypopush.py : get_next_schedule_chain() : line 413] - tnow 2012-12-30 19:40:00.001482, chain_star
t 2012-12-30 19:40:00
... [multiple duplicate messages]...
2012-12-30 12:40:00,002 DEBUG - [pypopush.py : main() : line 129] - Blocking 0 seconds until show start
2012-12-30 12:40:00,002 ERROR - [pypopush.py : start_web_stream() : line 514] - [Errno 111] Connection refused
2012-12-30 12:40:00,002 DEBUG - [pypopush.py : get_next_schedule_chain() : line 413] - tnow 2012-12-30 19:40:00.002608, chain_star
t 2012-12-30 19:59:59
... [multiple duplicate messages]...
2012-12-30 12:40:00,003 DEBUG - [pypopush.py : main() : line 129] - Blocking 1198.996611 seconds until show start
2012-12-30 12:40:08,911 INFO - [pypofetch.py : handle_message() : line 79] - Received event from Pypo Message Handler: {"event_typ
e":"reset_liquidsoap_bootstrap"}
2012-12-30 12:40:08,911 INFO - [pypofetch.py : handle_message() : line 83] - Handling command: reset_liquidsoap_bootstrap
2012-12-30 12:40:08,911 DEBUG - [pypofetch.py : set_bootstrap_variables() : line 178] - Getting information needed on bootstrap fr
om Airtime
2012-12-30 12:40:09,043 INFO - [api_client.py : get_bootstrap_info() : line 672] - Bootstrap info retrieved {u'transition_fade': u
'01.000', u'station_name': u'Radio Station Name', u'stream_label': u'2', u'switch_status': {u'scheduled_play': u'on', u'live_dj
': u'off', u'master_dj': u'off'}}
2012-12-30 12:40:09,043 DEBUG - [pypofetch.py : set_bootstrap_variables() : line 184] - info:{u'transition_fade': u'01.000', u'sta
tion_name': u'Radio Station Name', u'stream_label': u'2', u'switch_status': {u'scheduled_play': u'on', u'live_dj': u'off', u'ma
ster_dj': u'off'}}
2012-12-30 12:40:09,043 DEBUG - [pypofetch.py : switch_source() : line 148] - Switching source: scheduled_play to "on" status
2012-12-30 12:40:09,044 DEBUG - [pypofetch.py : switch_source() : line 148] - Switching source: live_dj to "off" status
2012-12-30 12:40:09,045 DEBUG - [pypofetch.py : switch_source() : line 148] - Switching source: master_dj to "off" status
2012-12-30 12:40:09,046 INFO - [pypofetch.py : update_liquidsoap_stream_format() : line 388] - vars.stream_metadata_type 2
2012-12-30 12:40:09,046 INFO - [pypofetch.py : update_liquidsoap_station_name() : line 417] - 127.0.0.1
2012-12-30 12:40:09,046 INFO - [pypofetch.py : update_liquidsoap_station_name() : line 418] - 1234
2012-12-30 12:40:09,046 INFO - [pypofetch.py : update_liquidsoap_station_name() : line 424] - vars.station_name Radio Station Name
-FM
2012-12-30 12:40:09,047 INFO - [pypofetch.py : update_liquidsoap_transition_fade() : line 404] - vars.default_dj_fade 01.000
2012-12-30 12:40:09,048 INFO - [pypofetch.py : handle_message() : line 118] - New timeout: 1420.86329699
2012-12-30 12:40:09,048 INFO - [pypofetch.py : main() : line 523] - Loop #2954
...[bunch of extremely verbose stuff]...
2012-12-30 12:40:09,049 DEBUG - [pypofetch.py : process_schedule() : line 474] - Pushing to pypo-push
2012-12-30 12:40:09,049 DEBUG - [pypofetch.py : cache_cleanup() : line 499] - Files to remove set([])
2012-12-30 12:40:09,049 INFO - [pypofetch.py : handle_message() : line 118] - New timeout: 1800
2012-12-30 12:40:09,049 INFO - [pypofetch.py : main() : line 523] - Loop #2955
2012-12-30 12:40:09,095 DEBUG - [pypopush.py : get_current_chain() : line 390] - tnow 2012-12-30 19:40:09.095620, chain_start 2012
-12-30 19:39:55
2012-12-30 12:40:09,097 DEBUG - [pypopush.py : handle_new_schedule() : line 236] - [{u'end': u'2012-12-30-20-00-00', u'show_name':
u'KCFR Test', u'uri': u'http://173.192.70.140:7392/stream/1/', u'start': u'2012-12-30-19-40-00', u'independent_event': True, u'ro
w_id': 214, u'type': u'stream_output_start', u'id': 9}]
2012-12-30 12:40:09,097 DEBUG - [pypopush.py : is_correct_current_item() : line 216] - Is current item correct?: False
2012-12-30 12:40:09,097 DEBUG - [pypopush.py : remove_from_liquidsoap_queue() : line 607] - source.skip
2012-12-30 12:40:09,097 DEBUG - [pypopush.py : remove_from_liquidsoap_queue() : line 629] - queue.queue
2012-12-30 12:40:09,098 DEBUG - [pypopush.py : remove_from_liquidsoap_queue() : line 633] - Not skipped
2012-12-30 12:40:09,098 DEBUG - [pypopush.py : stop_web_stream_all() : line 525] - http.stop
2012-12-30 12:40:09,098 DEBUG - [pypopush.py : stop_web_stream_all() : line 529] - dynamic_source.output_stop
2012-12-30 12:40:09,098 DEBUG - [pypopush.py : stop_web_stream_all() : line 533] - dynamic_source.id -1
2012-12-30 12:40:09,098 DEBUG - [pypopush.py : stop_web_stream_all() : line 537] - Done
2012-12-30 12:40:09,098 DEBUG - [pypopush.py : start_web_stream() : line 506] - dynamic_source.output_start
2012-12-30 12:40:31,300 DEBUG - [pypopush.py : start_web_stream() : line 510] - Done.
2012-12-30 12:40:31,300 DEBUG - [pypopush.py : get_next_schedule_chain() : line 413] tnow 2012-12-30 19:40:09.095620, chain_star t 2012-12-30 19:39:55
2012-12-30 12:40:31,301 DEBUG - [pypopush.py : get_next_schedule_chain() : line 413] - tnow 2012-12-30 19:40:09.095620, chain_star
t 2012-12-31 13:59:59
2012-12-30 12:40:31,301 DEBUG - [pypopush.py : main() : line 104] - Next schedule chain: [{u'end': u'2012-12-30-19-59-59', u'uri':
u'http://173.192.70.140:7392/stream/1/', u'start': u'2012-12-30-19-59-59', u'row_id': 214, u'type': u'stream_buffer_end', u'indep
endent_event': True}]
2012-12-30 12:40:31,302 DEBUG - [pypopush.py : main() : line 113] - Blocking 1167.697866 seconds until show start
This works MOST of the time, and when it doesn't, I give airtime-liquidsoap a kick, and it takes off...#default = amplify(id="silence_src", 0.00001, noise())
default = mksafe(input.http("http://otherfeed.net:1234/mount"))
$ sudo /usr/sbin/service airtime-liquidsoap restart
Restarting Liquidsoap: Traceback (most recent call last):
File "/usr/lib/airtime/pypo/bin/liquidsoap_scripts/liquidsoap_prepare_terminate.py", line 14, in <module>
tn.read_all()
File "/usr/lib/python2.7/telnetlib.py", line 325, in read_all
self.fill_rawq()
File "/usr/lib/python2.7/telnetlib.py", line 516, in fill_rawq
buf = self.sock.recv(50)
# [ it hangs here, to it's ctrl-C time... ]
KeyboardInterrupt
$ # [kill -QUIT {PID}
$ /usr/sbin/service airtime-liquidsoap restart
$# ...
input = mksafe(input.http("http://stream.org:1234"))
output.icecast.vorbis.cbr( samplerate=44100,
stereo=true,
user="user",
password="password",
mount="Remote",
host="myhost.org", port=8xxx,
input)
It looks like you're new here. If you want to get involved, click one of these buttons!