Sound and library problems
  • Vote Up0Vote Down johngoughjohngough
    Posts: 113Member
    Hi guys,

    As I've written in other posts - sometimes Airtime plays nice but more times it does not. This has happened in numerous different scenarios and I can't do anything to repair or control it. The log files for pypo and liquidsoap are attached. If anyone can shed some light on what it might be that would be great. This is a clean 10.04 ubuntu install and a clean 1.6.1 install.

    I'm also having difficulty with the library. When linking to files on another server, Airtime adds the files to the library but not the metadata. Everything happens correctly as below, except metadata.

    johder@AirtimePC[/email]:/var/lib/airtime/utils$ sudo ./airtime-import -l /media/server/AirtimeAudio
    ========================
    Airtime Import Script
    ========================
    Importing: [00001] /media/server/AirtimeAudio/On the Verge.mp3
    Importing: [00002] /media/server/AirtimeAudio/On 93.9 This is DSFM.mp3
    Importing: [00003] /media/server/AirtimeAudio/Everythings Fine on93.9.mp3
    Importing: [00004] /media/server/AirtimeAudio/Broadcasting.mp3
    Importing: [00005] /media/server/AirtimeAudio/News.mp3
    Importing: [00006] /media/server/AirtimeAudio/Akkiko.mp3
    Importing: [00007] /media/server/AirtimeAudio/93.9 Access Radio.mp3
    Importing: [00008] /media/server/AirtimeAudio/03 KYON KISI KO {TERE NAAM}.mp3
    Importing: [00009] /media/server/AirtimeAudio/005 MAIN YAHAN HOON {VEER ZARA}.mp3
    Importing: [00010] /media/server/AirtimeAudio/Music Circus.mp3
    Importing: [00011] /media/server/AirtimeAudio/chinharocks20100818.mp3
    Importing: [00012] /media/server/AirtimeAudio/Ship in full Sail.mp3
    Importing: [00013] /media/server/AirtimeAudio/01 TUNE SAATH JO MERA CHHODA {TERE NAAM}.mp3
    Importing: [00014] /media/server/AirtimeAudio/Mike at the Mike.mp3
    ============================================================ ==============
    *** Import mode: link
    *** Files imported: 14
    *** Duplicate files (not imported):
    *** Total: 14 files in 10 seconds = 1.4 files/second.


    Finally, right clicking on a file and selecting delete is not removing the files from my library. Any thoughts? Is there a script to permanently delete files from airtime?

    Much appreciated
    John



  • 26 Comments sorted by
  • Vote Up0Vote Down Martin KonecnyMartin Konecny
    Posts: 1,860Member
    John,

    Could you quickly summarize the exact problems you're having? The problem you have with deleting files is a known bug that will be fixed in the next release.
    Airtime Pro Hosting: http://airtime.pro
  • Vote Up0Vote Down Daniel JamesDaniel James
    Posts: 844Member, Sourcefabric Team
    Hi John,

    > When linking to files on
    > another server, Airtime adds the files to the library but not the
    > metadata.

    Have you examined the files that you are importing to make sure that the
    tags contain the metadata you're expecting? Try tagtool (it's in Ubuntu)
    which will enable you to inspect or edit the id3 tags.

    Tagtool also has a feature which generates correct tags from data in
    filenames, which you may find useful.

    > *johder@AirtimePC <mailto:johder@AirtimePC>:/var/lib/airtime/utils$ sudo
    > ./airtime-import -l /media/server/AirtimeAudio

    I tested linking with 1.6.1 and got the metadata shown correctly in the
    database, which is why I suspect that there's something up with the tags
    in your import files.

    ps. it's better to post log files in plain text, they can be shown in
    the forum.

    Cheers!

    Daniel
  • Vote Up0Vote Down johngoughjohngough
    Posts: 113Member
    Hi guys,

    Sorry for my slow response.

    Some problems I've encountered are -

    If I reboot my Airtime machine, reload Airtime then schedule a few shows, the first show generally won't play.
    But, it will usually sync again at the next playlist(not next file). I believe this is an airtime setting but it means we have to be very very careful about what we schedule because if starts playing we can't stop it and have to wait until the end of scheduled show before being able to do anything.

    Also a new problem I'm having is that OnAir goes red and Icecast is showing the mountpoints as expected but VLC is telling me Airtime is offline, see image, and there is nothing coming from the soundcard either.

    http://i229.photobucket.com/albums/ee31/johngough1/atscreen.jpg


    Airtime has also stopped playing audio between shows a few times. I'll upload the logs later when I'm back in the station.The mp3 files we are using also seem fine and play on various other media.

    Also file uploading will only upload some files e.g if I upload using the Airtime 'ADD AUDIO' function only some files will upload to the library. If I use the script they upload fine.

    Daniel - you were correct about the metadata and I've started using tagtool for this now.


    John

  • Vote Up0Vote Down Ofir GalOfir Gal
    Posts: 130Member
    Hi John,
    I've tested the VLC playlist of Airtime and I couldn't reproduce the issue you are experiencing.

    Can you please provide us with more information?
    Can you reproduce is every time? Have you tried it on different environments? version of VLC/Airtime?



    The ID tags is a version issue.
    Files should have the latest version ID3v2



    Thanks,
    Ofir
  • Vote Up0Vote Down VerheekVerheek
    Posts: 158Member
    Regarding:
    Quote:
    If I reboot my Airtime machine, reload Airtime then schedule a few shows, the first show generally won't play.
    But, it will usually sync again at the next playlist(not next file). I believe this is an airtime setting but it means we have to be very very careful about what we schedule because if starts playing we can't stop it and have to wait until the end of scheduled show before being able to do anything.


    The only way I've got around this was to re-install airtime or to wait for the next playlist start. In 1.7 I can cancel the show and get around it this way but I went back to 1.6.1 because the playout would randomly stop. This seemed to start sometime after the Thursday commits last week.

    I wish there was some way to cause the playlist to be reprocessed and the playout to jump into the correct song and time position.

    Also it would be great to be able to schedule before the current time and fill that with playlists and then have the system jump into the correct spot with the recue feature. That way you don't have to guess when how much time it takes to complete the show and playlist scheduling before the wall clock grabs the show.

    Ubuntu 10.04. Lucid, latest updates, airtime 1.6.1-GA

    Daniel: Thanks for the Audio Tag Tool tip. What's odd is that the ID3 tags showed blank in the tool but the Airtime audio import found all the information.

    Jeremy
  • Vote Up0Vote Down Martin KonecnyMartin Konecny
    Posts: 1,860Member
    The Problem with playout randomly stopping should be fixed in the latest GIT revision (Airtime 1.7).

    I am curious why a radio station would need to be restarted during a show? We never foresaw this as a use-case scenario, in the future we might make it so that we "re-synchronize" at the beginning of each song.
    Airtime Pro Hosting: http://airtime.pro
  • Vote Up0Vote Down VerheekVerheek
    Posts: 158Member
    It's not so much that that it has to be restarted at the beginning of a show but the ability to jump into the proper program and time within that program should the server have to be rebooted, the show contents be changed a late program be added to an already created play-list, etc. I'm sure the recue could be automatically achieved after modifying the schedule.

    I guess what I was really looking for was a way to get Pypo playing when it stopped or didn't start after a re-install. Perhaps this might not get playout going if the source is a bug.

    I updated to the latest git pull origin master and scheduled but it shows offline and reboot didn't start audio playback. The interface is playing. I guess it's been running for three hours now but no sound and always shows offline.

    My pypo-liquidsoap log shows:

    Quote:
    @400000004d87c09507fcedf4 2011/03/21 17:18:03 [server:3] New client: localhost
    @400000004d87c096132567c4 2011/03/21 17:18:04 [single_10732:3] Finished with "/opt/pypo/files/basic/silence.mp3".
    @400000004d87c09618ae4a6c 2011/03/21 17:18:04 [single_10732:3] Prepared "/opt/pypo/files/basic/silence.mp3" (RID 0).
    @400000004d87c0970e68e56c ./notify.sh --data='0' --media-id=
    @400000004d87c09a09002d24
    @400000004d87c09a090034f4 #########################################
    @400000004d87c09a090034f4 # *** pypo *** #
    @400000004d87c09a090038dc # pypo notification gateway #
    @400000004d87c09a090038dc #########################################
    @400000004d87c09a09003cc4 NOTICE: 'media_id' command-line argument not given.
    @400000004d87c09a1897b52c 2011/03/21 17:18:08 [clock.wallclock_main:2] We must catchup 4.01 seconds!
    @400000004d87c09a2b61b48c 2011/03/21 17:18:08 [server:3] New client: localhost


    The rest of the time it shows the "client: localhost" lines.

    My pypo log shows:

    Quote:
    @400000004d87c28033927de4 2011-03-21 17:26:14,865 INFO - [pypofetch.py : prepare_playlists() : line 129] - found playlist at 2011-03-21-22-25-00
    @400000004d87c2803393d5a4 2011-03-21 17:26:14,865 DEBUG - [pypofetch.py : prepare_playlists() : line 138] - *****************************************
    @400000004d87c2803394eee4 2011-03-21 17:26:14,865 DEBUG - [pypofetch.py : prepare_playlists() : line 139] - pkey: 2011-03-21-22-25-00
    @400000004d87c28033960c0c 2011-03-21 17:26:14,865 DEBUG - [pypofetch.py : prepare_playlists() : line 140] - cached at : /opt/pypo/cache/scheduler/2011-03-21-22-25-00
    @400000004d87c28033972934 2011-03-21 17:26:14,865 DEBUG - [pypofetch.py : prepare_playlists() : line 141] - subtype: 1
    @400000004d87c28033985dcc 2011-03-21 17:26:14,865 DEBUG - [pypofetch.py : prepare_playlists() : line 142] - played: 0
    @400000004d87c28033996f3c 2011-03-21 17:26:14,865 DEBUG - [pypofetch.py : prepare_playlists() : line 143] - schedule id: 13
    @400000004d87c280339a80ac 2011-03-21 17:26:14,865 DEBUG - [pypofetch.py : prepare_playlists() : line 144] - duration: 01:00:00
    @400000004d87c280339b9dd4 2011-03-21 17:26:14,865 DEBUG - [pypofetch.py : prepare_playlists() : line 145] - source id: 1
    @400000004d87c280339caf44 2011-03-21 17:26:14,865 DEBUG - [pypofetch.py : prepare_playlists() : line 146] - *****************************************
    @400000004d87c280339e1e74 2011-03-21 17:26:14,865 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/27247fec1f67d5c223ee2958 7cb1d756.mp3
    @400000004d87c280339f5adc 2011-03-21 17:26:14,866 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033a0ca0c 2011-03-21 17:26:14,866 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/27247fec1f67d5 c223ee29587cb1d756.mp3
    @400000004d87c28033a26fec 2011-03-21 17:26:14,866 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="27247fec1f67d5c223ee29587cb1d756 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="169" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/27247fec1f67d 5c223ee29587cb1d756.mp3
    @400000004d87c28033a3bbf4 2011-03-21 17:26:14,866 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="27247fec1f67d5c223ee29587cb1d756 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="169" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/27247fec1f67d 5c223ee29587cb1d756.mp3 to playlist
    @400000004d87c28033a4f85c 2011-03-21 17:26:14,866 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/e8c93edc9852d3ecb633cc1d 1e867310.mp3
    @400000004d87c28033a62cf4 2011-03-21 17:26:14,866 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033a7bb64 2011-03-21 17:26:14,866 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/e8c93edc9852d3 ecb633cc1d1e867310.mp3
    @400000004d87c28033a99fc4 2011-03-21 17:26:14,866 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="e8c93edc9852d3ecb633cc1d1e867310 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="170" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/e8c93edc9852d 3ecb633cc1d1e867310.mp3
    @400000004d87c28033aae7e4 2011-03-21 17:26:14,866 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="e8c93edc9852d3ecb633cc1d1e867310 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="170" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/e8c93edc9852d 3ecb633cc1d1e867310.mp3 to playlist
    @400000004d87c28033ac2834 2011-03-21 17:26:14,866 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/053e072f3bcc9b9834135fe9 a830d5c4.mp3
    @400000004d87c28033ad5ccc 2011-03-21 17:26:14,866 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033aecfe4 2011-03-21 17:26:14,867 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/053e072f3bcc9b 9834135fe9a830d5c4.mp3
    @400000004d87c28033b079ac 2011-03-21 17:26:14,867 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="053e072f3bcc9b9834135fe9a830d5c4 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="171" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/053e072f3bcc9 b9834135fe9a830d5c4.mp3
    @400000004d87c28033b1e4f4 2011-03-21 17:26:14,867 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="053e072f3bcc9b9834135fe9a830d5c4 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="171" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/053e072f3bcc9 b9834135fe9a830d5c4.mp3 to playlist
    @400000004d87c28033b32544 2011-03-21 17:26:14,867 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/d09417e750b2cd31c46e1e34 457135a7.mp3
    @400000004d87c28033b459dc 2011-03-21 17:26:14,867 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033b5ccf4 2011-03-21 17:26:14,867 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/d09417e750b2cd 31c46e1e34457135a7.mp3
    @400000004d87c28033b776bc 2011-03-21 17:26:14,867 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="d09417e750b2cd31c46e1e34457135a7 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="172" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/d09417e750b2c d31c46e1e34457135a7.mp3
    @400000004d87c28033b8baf4 2011-03-21 17:26:14,867 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="d09417e750b2cd31c46e1e34457135a7 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="172" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/d09417e750b2c d31c46e1e34457135a7.mp3 to playlist
    @400000004d87c28033b9fb44 2011-03-21 17:26:14,867 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/78bb062b3dca9f42183e002f 53634006.mp3
    @400000004d87c28033bb2fdc 2011-03-21 17:26:14,867 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033bca2f4 2011-03-21 17:26:14,867 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/78bb062b3dca9f 42183e002f53634006.mp3
    @400000004d87c28033be48d4 2011-03-21 17:26:14,868 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="78bb062b3dca9f42183e002f53634006 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="173" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/78bb062b3dca9 f42183e002f53634006.mp3
    @400000004d87c28033bf90f4 2011-03-21 17:26:14,868 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="78bb062b3dca9f42183e002f53634006 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="173" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/78bb062b3dca9 f42183e002f53634006.mp3 to playlist
    @400000004d87c28033c0cd5c 2011-03-21 17:26:14,868 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/2e8797b53cb95fc55b94a0b8 3a52bb0e.mp3
    @400000004d87c28033c201f4 2011-03-21 17:26:14,868 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033c3750c 2011-03-21 17:26:14,868 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/2e8797b53cb95f c55b94a0b83a52bb0e.mp3
    @400000004d87c28033c51aec 2011-03-21 17:26:14,868 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="2e8797b53cb95fc55b94a0b83a52bb0e ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="174" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/2e8797b53cb95 fc55b94a0b83a52bb0e.mp3
    @400000004d87c28033c6630c 2011-03-21 17:26:14,868 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="2e8797b53cb95fc55b94a0b83a52bb0e ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="174" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/2e8797b53cb95 fc55b94a0b83a52bb0e.mp3 to playlist
    @400000004d87c28033c7b6e4 2011-03-21 17:26:14,868 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/4a9218f5259e4f3224e8dcfb 97a97671.mp3
    @400000004d87c28033c8eb7c 2011-03-21 17:26:14,868 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033ca5e94 2011-03-21 17:26:14,868 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/4a9218f5259e4f 3224e8dcfb97a97671.mp3
    @400000004d87c28033cc008c 2011-03-21 17:26:14,868 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="4a9218f5259e4f3224e8dcfb97a97671 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="175" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/4a9218f5259e4 f3224e8dcfb97a97671.mp3
    @400000004d87c28033cd601c 2011-03-21 17:26:14,869 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="4a9218f5259e4f3224e8dcfb97a97671 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="175" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/4a9218f5259e4 f3224e8dcfb97a97671.mp3 to playlist
    @400000004d87c28033ce9c84 2011-03-21 17:26:14,869 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/69bcdb0046b03d1195852f6f 690fea33.mp3
    @400000004d87c28033cfe88c 2011-03-21 17:26:14,869 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033d15f8c 2011-03-21 17:26:14,869 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/69bcdb0046b03d 1195852f6f690fea33.mp3
    @400000004d87c28033d3056c 2011-03-21 17:26:14,869 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="69bcdb0046b03d1195852f6f690fea33 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="176" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/69bcdb0046b03 d1195852f6f690fea33.mp3
    @400000004d87c28033d468e4 2011-03-21 17:26:14,869 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="69bcdb0046b03d1195852f6f690fea33 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="176" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/69bcdb0046b03 d1195852f6f690fea33.mp3 to playlist
    @400000004d87c28033d591c4 2011-03-21 17:26:14,869 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/859edc02866e64c9f63f8a74 3b34eae9.mp3
    @400000004d87c28033d6c274 2011-03-21 17:26:14,869 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033d83d5c 2011-03-21 17:26:14,869 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/859edc02866e64 c9f63f8a743b34eae9.mp3
    @400000004d87c28033d9df54 2011-03-21 17:26:14,869 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="859edc02866e64c9f63f8a743b34eae9 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="177" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/859edc02866e6 4c9f63f8a743b34eae9.mp3
    @400000004d87c28033db2774 2011-03-21 17:26:14,869 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="859edc02866e64c9f63f8a743b34eae9 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="177" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/859edc02866e6 4c9f63f8a743b34eae9.mp3 to playlist
    @400000004d87c28033dd36cc 2011-03-21 17:26:14,870 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/a9c65ac0e9bcb8d778058372 8d9c4086.mp3
    @400000004d87c28033dda044 2011-03-21 17:26:14,870 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033df0f74 2011-03-21 17:26:14,870 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/a9c65ac0e9bcb8 d7780583728d9c4086.mp3
    @400000004d87c28033e0b16c 2011-03-21 17:26:14,870 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="a9c65ac0e9bcb8d7780583728d9c4086 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="178" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/a9c65ac0e9bcb 8d7780583728d9c4086.mp3
    @400000004d87c28033e23bf4 2011-03-21 17:26:14,870 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="a9c65ac0e9bcb8d7780583728d9c4086 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="178" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/a9c65ac0e9bcb 8d7780583728d9c4086.mp3 to playlist
    @400000004d87c28033e3320c 2011-03-21 17:26:14,870 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/e2cc0271b2691e5e1865ba07 c3a93697.mp3
    @400000004d87c28033e462bc 2011-03-21 17:26:14,870 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033e623f4 2011-03-21 17:26:14,870 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/e2cc0271b2691e 5e1865ba07c3a93697.mp3
    @400000004d87c28033e7cdbc 2011-03-21 17:26:14,870 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="e2cc0271b2691e5e1865ba07c3a93697 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="179" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/e2cc0271b2691 e5e1865ba07c3a93697.mp3
    @400000004d87c28033e940d4 2011-03-21 17:26:14,870 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="e2cc0271b2691e5e1865ba07c3a93697 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="179" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/e2cc0271b2691 e5e1865ba07c3a93697.mp3 to playlist
    @400000004d87c28033ea88f4 2011-03-21 17:26:14,870 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/7ef996a0f8f0f7fdf38ceec9 dd1b3a4f.mp3
    @400000004d87c28033ebbd8c 2011-03-21 17:26:14,871 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033ed30a4 2011-03-21 17:26:14,871 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/7ef996a0f8f0f7 fdf38ceec9dd1b3a4f.mp3
    @400000004d87c28033eeedf4 2011-03-21 17:26:14,871 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="7ef996a0f8f0f7fdf38ceec9dd1b3a4f ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="180" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/7ef996a0f8f0f 7fdf38ceec9dd1b3a4f.mp3
    @400000004d87c28033f03614 2011-03-21 17:26:14,871 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="7ef996a0f8f0f7fdf38ceec9dd1b3a4f ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="180" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/7ef996a0f8f0f 7fdf38ceec9dd1b3a4f.mp3 to playlist
    @400000004d87c28033f18dd4 2011-03-21 17:26:14,871 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/4e881cf81f30027d0681d053 a2779937.mp3
    @400000004d87c28033f2aee4 2011-03-21 17:26:14,871 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033f421fc 2011-03-21 17:26:14,871 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/4e881cf81f3002 7d0681d053a2779937.mp3
    @400000004d87c28033f5c7dc 2011-03-21 17:26:14,871 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="4e881cf81f30027d0681d053a2779937 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="181" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/4e881cf81f300 27d0681d053a2779937.mp3
    @400000004d87c28033f713e4 2011-03-21 17:26:14,871 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="4e881cf81f30027d0681d053a2779937 ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="181" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/4e881cf81f300 27d0681d053a2779937.mp3 to playlist
    @400000004d87c28033f8504c 2011-03-21 17:26:14,871 DEBUG - [pypofetch.py : handle_media_file() : line 168] - Processing track http://localhost/api/get-media/file/968b50ec1281f7ab95dead3b cd6ea90d.mp3
    @400000004d87c28033f984e4 2011-03-21 17:26:14,871 DEBUG - [pypofetch.py : handle_media_file() : line 173] - No cue in/out detected for this file
    @400000004d87c28033faf7fc 2011-03-21 17:26:14,872 DEBUG - [pypofetch.py : handle_remote_file() : line 232] - file already in cache: /opt/pypo/cache/scheduler/2011-03-21-22-25-00/968b50ec1281f7 ab95dead3bcd6ea90d.mp3
    @400000004d87c28033fc960c 2011-03-21 17:26:14,872 DEBUG - [pypofetch.py : handle_media_file() : line 202] - annotate:export_source="scheduler",media_id="968b50ec1281f7ab95dead3bcd6ea90d ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="182" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/968b50ec1281f 7ab95dead3bcd6ea90d.mp3
    @400000004d87c28033fde214 2011-03-21 17:26:14,872 DEBUG - [pypofetch.py : handle_media_file() : line 216] - everything ok, adding annotate:export_source="scheduler",media_id="968b50ec1281f7ab95dead3bcd6ea90d ",liq_start_next="0",liq_fade_in="5.0",liq_fade_out="5.0 ",schedule_table_id="182" :/opt/pypo/cache/scheduler/2011-03-21-22-25-00/968b50ec1281f 7ab95dead3bcd6ea90d.mp3 to playlist
    @400000004d87c28536655c94 2011-03-21 17:26:19,912 INFO - [api_client.py : get_schedule() : line 216] - Fetching schedule from http://localhost/api/schedule/api_key/%%api_key%%/from/2011- 03-20-17-26/to/2011-03-22-17-26


    I kept my 1.6.1 folders so I can switch back if needed after a re-install.

    Jeremy
  • Vote Up0Vote Down johngoughjohngough
    Posts: 113Member
    Ofir Gal wrote on Tue, 15 March 2011 22:19
    Hi John,
    I've tested the VLC playlist of Airtime and I couldn't reproduce the issue you are experiencing.

    Can you please provide us with more information?
    Can you reproduce is every time? Have you tried it on different environments? version of VLC/Airtime?



    Hi Ofir,

    Yeah I can reproduce every time.

    Airtime displays 'ON AIR',

    Icecast displays 'Current Song: Airtime - offline'

    therefore not a VLC problem. I've only tried on Airtime 1.6.1

    My audio is also becoming distorted after a few minutes of playback. This only happens with Airtime. movieplayer & vlc are fine. This happens on any soundcard I try

    What logs should I post and what are the commands? Maybe I should do another re-install?

    My system is -
    Airtime 1.6.1

    Ubuntu 10.04.2 LTS \n \l
    kernel 2.6.32-28-generic
    processor : 0
    vendor_id : AuthenticAMD
    cpu family : 16
    model : 5
    model name : AMD Athlon(tm) II X3 435 Processor
    stepping : 2
    cpu MHz : 800.000
    cache size : 512 KB
    physical id : 0
    siblings : 3
    core id : 0
    cpu cores : 3
    apicid : 0
    initial apicid : 0
    fdiv_bug : no
    hlt_bug : no
    f00f_bug : no
    coma_bug : no
    fpu : yes
    fpu_exception : yes
    cpuid level : 5
    wp : yes

    **** List of PLAYBACK Hardware Devices ****
    card 0: tm [Audiophile USB (tm)], device 0: USB Audio [USB Audio]
    Subdevices: 0/1
    Subdevice #0: subdevice #0
    card 0: tm [Audiophile USB (tm)], device 1: USB Audio [USB Audio #1]
    Subdevices: 1/1
    Subdevice #0: subdevice #0
    card 0: tm [Audiophile USB (tm)], device 2: USB Audio [USB Audio #2]
    Subdevices: 1/1
    Subdevice #0: subdevice #0
    card 1: NVidia [HDA NVidia], device 0: ALC888 Analog [ALC888 Analog]
    Subdevices: 1/1
    Subdevice #0: subdevice #0
    card 1: NVidia [HDA NVidia], device 1: ALC888 Digital [ALC888 Digital]
    Subdevices: 1/1
    Subdevice #0: subdevice #0

    thanks
    John


  • Vote Up0Vote Down Ofir GalOfir Gal
    Posts: 130Member
    Hi John,
    can you please write me your hardware setup?
    (You left out RAM)
    What else is running on your machine beside Airtime?

    Can you better describe the distortion?
    Is it choppy audio?
    Is it constant stream with amplified volume that cause distortion?
    If it possible for you to upload a sample of that distortion happening it would be great as well.

    Does Airtime time = Machine time?

    Please do the following:
    1) Restart your machine.
    2) Once you've exp. the distortion or no audio
    do "sudo gedit /etc/service/pypo-liquidsoap/log/main/current"
    save it and send us the log file.
    3) Do "sudo gedit /etc/service/pypo/log/main/current"
    and send it as well.

  • Vote Up0Vote Down johngoughjohngough
    Posts: 113Member
    Hi Ofir thanks for your reply. I'm gonna do a fresh install later as I'm suspect of the Ubuntu installation dvd used on that machine.
    Will update soon.

  • Vote Up0Vote Down Martin KonecnyMartin Konecny
    Posts: 1,860Member
    Verheek,

    At the start of every playlist, we push it out to liquidsoap. If your machine is restarted, the fact that this playlist was pushed is lost, and you will need to wait until the beginning of the next playlist before you start hearing playout.
    Airtime Pro Hosting: http://airtime.pro
  • Vote Up0Vote Down Martin KonecnyMartin Konecny
    Posts: 1,860Member
    John,

    In 1.7 (roughly April 5th) you will have the ability to cancel a currently playing show by clicking on it in the calendar Smile

    In regards to your distorted audio, please try disabling the vorbis stream (if you are only using the mp3 stream or vice-versa). Liquidsoap does a lot of work transcoding your files into mp3 and vorbis streams, and it looks like your CPU is having trouble keeping up.

    Please let us know if this improves anything.
    Airtime Pro Hosting: http://airtime.pro
  • Vote Up0Vote Down Ofir GalOfir Gal
    Posts: 130Member
    Disable vorbis stream:

    $ sudo gedit [Airtime folder]/pypo/scripts/ls_config.liq

    (Change the value to false)
    output_icecast_vorbis = false
  • Vote Up0Vote Down Daniel JamesDaniel James
    Posts: 844Member, Sourcefabric Team
    Hi John, I think your performance problem (choppy audio) may be related to the fact that your cpuinfo shows your CPU is running at only 800MHz. It should be running at 2.9GHz. Possibly you have some power saving option enabled, or a hardware fault - please check the BIOS and see what CPU speed it reports.

    Also, you should be running the amd64 version of Ubuntu to get the most out of this CPU - you can check which version you are running with the uname -a command. On an amd64 Lucid system, the output of this command should report something like:

    Linux AirtimePC 2.6.32-29-generic #58-Ubuntu SMP Fri Feb 11 20:52:10 UTC 2011 x86_64 GNU/Linux

    What you're looking for is that 'x86_64' near the end of the line. If it isn't there, you're probably running the 32-bit version of Ubuntu, and so CPU intensive tasks like stream encoding won't be running as efficiently as they should be.


  • Vote Up0Vote Down johngoughjohngough
    Posts: 113Member
    thank you thank you thank you.

    Daniel - indeed there was a setting enabled in bios that was problematic.
    Thank you I now have a clean sounding Airtime doing what is told on both machines Smile

    >In 1.7 (roughly April 5th) you will have the ability to cancel a currently playing show by clicking on it in the calendar

    this is a welcome addition. I look forward to it. I think it will benefit users as if someone make scheduling mistake we can ammend quickly - thanks for this.

    Now I just gotta find a way to update ubuntu to 64bit without affecting my settings..

    thanks for all the support guys
    J
    J
  • Vote Up0Vote Down Daniel JamesDaniel James
    Posts: 844Member, Sourcefabric Team
    Hi John,

    On a GNU/Linux system all server settings should be in the /etc directory - they are kept completely separate from program binaries, data and so on. So if you keep a backup of the /etc directory plus your audio files you should be fine to upgrade the distro to 64 bit Ubuntu.

    In this particular case I would also recommend backing up your Airtime installation directory, in case you have made any manual tweaks to the files there. (Airtime does not yet put all its settings in /etc but I expect that to be fixed soon). I would suggest doing a clean install of Airtime 1.7 when that is released next week, then migrating your settings and data across.

    One option for a production machine (if you don't have a spare server) is to shrink the existing partitions on the server disk to make yourself a new empty partition of 20GB or so. Install 64-bit Ubuntu there, and keep the audio data in the existing partition. That way, should the 64-bit install or new Airtime setup go wrong for any reason, you can reboot into the existing 32-bit install and have that running with Airtime while you figure out the problem.

    After that, you can mount the old partition under /mnt/audio/ or whatever path you choose. You will then be able to wipe or reformat the 20GB system partition as many times as you want, without touching the partition containing your precious audio.

    Cheers!

    Daniel
  • Vote Up0Vote Down VerheekVerheek
    Posts: 158Member
    Hi Martin,

    A re-push automatic (on startup) or manual option would be very useful because you don't want to have to wait, especially if the playlist is 60 minutes, for the next sync and in the meantime there is silence because something disrupted pypo like hardware failure, power outages, corrupt audio files, etc.

    Also supposing you want to start mid-playlist because you have a bunch created and you want to run them from xx:00:00 to xx+1:00:00 and you don't want to wait with dead air until the next playlist is pushed. You might want to change out the current playlist for another because of a change in programming.

    This experience comes from using automation software in radio. I know airtime will be written in a very robust manner but still there need to be ways to jump-to-time if there are schedule changes, reboots mid-stream, bad MPEG encoded files that lock up playback, PSUs that die, etc. Of course a datacenter or commercial station does not have (all) these hardware issues (hopefully!) but I suspect a lot of community radio out there will not be so well off.

    Also during experimenting in development this could be helpful too.

    Keep up the good work!

    Jeremy
  • Vote Up0Vote Down johngoughjohngough
    Posts: 113Member
    Hi guys after almost 24 hours of uninterrupted scheduling Airtime has stopped working again. I added a show and filled with a play list which had been playing fine earlier in the day but when the file started Airtime stays offline even though the show appears to be playing on the time line.

    This is why it will be handy to able to stop our cancel a show. I have to wait two hours now before I can test further Sad

    Do ye know why this happens?

    My full computer spec is attached

    Best
    J



    [IMG]http://i229.photobucket.com/albums/ee31/johngough1/airtime.png[/IMG]


    [IMG]http://i229.photobucket.com/albums/ee31/johngough1/Screenshot.png[/IMG]

    pypo log

    @400000004d920373369f3914 2011-03-29 17:06:01,897 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d920373369f48b4 2011-03-29 17:06:01,897 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d920373369f5084 2011-03-29 17:06:01,897 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d920373369f5854 2011-03-29 17:06:01,897 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/106/schedule_id/11
    @400000004d920374014c1d7c 2011/03/29 17:06:02 [output(dot)alsa:2] Underrun!
    @400000004d920374014c2934 2011/03/29 17:06:02 [output(dot)alsa:2] Trying to recover..
    @400000004d92037404e92de4 2011-03-29 17:06:02,082 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d92037404ec5e4c 2011-03-29 17:06:02,082 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d92037404efb9ac 2011-03-29 17:06:02,082 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d9204b539b3b4a4 2011/03/29 17:11:23 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/8183ba1c1a099d 9bdc5f8e7f78b8ad0f.mp3 ".
    @400000004d9204b539ba500c 2011/03/29 17:11:23 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/96b23bf9ad1085 a6a84466bb02ad6a0f.mp3 " (RID 6).
    @400000004d9204b53b45ba9c 2011/03/29 17:11:23 [decoder:3] Method "MP3/LIBMAD" accepted " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/3be9ea74a78b3f 87d3be0b8b15e0d27b.mp3 ".
    @400000004d9204b6006f4654 ./notify.sh --data='11' --media-id=107
    @400000004d9204b607d88d74
    @400000004d9204b607d8992c #########################################
    @400000004d9204b607d8a0fc # *** pypo *** #
    @400000004d9204b607d8a8cc # pypo notification gateway #
    @400000004d9204b607d8b09c #########################################
    @400000004d9204b607d8b86c 2011-03-29 17:11:24,131 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9204b607db3524 2011-03-29 17:11:24,131 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d9204b607dd6f74 2011-03-29 17:11:24,131 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d9204b607dfa9c4 2011-03-29 17:11:24,132 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d9204b607e28824 2011-03-29 17:11:24,132 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/107/schedule_id/11
    @400000004d9204b60aef202c 2011/03/29 17:11:24 [output(dot)alsa:2] Underrun!
    @400000004d9204b60aef2fcc 2011/03/29 17:11:24 [output(dot)alsa:2] Trying to recover..
    @400000004d9204b6136b8114 2011-03-29 17:11:24,325 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9204b6136fc2ec 2011-03-29 17:11:24,325 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9204b6137404c4 2011-03-29 17:11:24,326 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d920587260763c4 2011/03/29 17:14:53 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/96b23bf9ad1085 a6a84466bb02ad6a0f.mp3 ".
    @400000004d920587260b20cc 2011/03/29 17:14:53 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/b61a44ed05fab1 c942ffdebdb805c359.mp3 " (RID 7).
    @400000004d920587286ca3f4 2011/03/29 17:14:53 [decoder:3] Method "MP3/LIBMAD" accepted " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/b8382fc36c88f2 21f9d560308ab2150c.mp3 ".
    @400000004d92058728b3cacc ./notify.sh --data='11' --media-id=108
    @400000004d9205872f0a231c
    @400000004d9205872f0a2ed4 #########################################
    @400000004d9205872f0a36a4 # *** pypo *** #
    @400000004d9205872f0a3e74 # pypo notification gateway #
    @400000004d9205872f0a4644 #########################################
    @400000004d9205872f0a4a2c 2011-03-29 17:14:53,789 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9205872f0cfd94 2011-03-29 17:14:53,789 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d9205872f0f5b0c 2011-03-29 17:14:53,789 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d9205872f121644 2011-03-29 17:14:53,789 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d9205872f152f3c 2011-03-29 17:14:53,789 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/108/schedule_id/11
    @400000004d92058734c4a0fc 2011/03/29 17:14:53 [output(dot)alsa:2] Underrun!
    @400000004d92058734c5450c 2011/03/29 17:14:53 [output(dot)alsa:2] Trying to recover..
    @400000004d9205873a07fec4 2011-03-29 17:14:53,973 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9205873a0b6dac 2011-03-29 17:14:53,973 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9205873a0ec90c 2011-03-29 17:14:53,973 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d92062124473564 2011/03/29 17:17:27 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/b61a44ed05fab1 c942ffdebdb805c359.mp3 ".
    @400000004d920621244750bc 2011/03/29 17:17:27 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/447cd4a1c2aae4 1e86b0b21890faf50e.mp3 " (RID Cool.
    @400000004d9206212892296c 2011/03/29 17:17:27 [decoder:3] Method "MP3/LIBMAD" accepted " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/be96824dd8aa70 16e7efd7326e9e7045.mp3 ".
    @400000004d920621289244c4 TagLib: ID3v2.4 no longer supports the frame type RVAD. It will be discarded from the tag.
    @400000004d9206212948bbb4 ./notify.sh --data='11' --media-id=109
    @400000004d92062132ee6de4
    @400000004d92062132ee799c #########################################
    @400000004d92062132ee816c # *** pypo *** #
    @400000004d92062132ee893c # pypo notification gateway #
    @400000004d92062132ee910c #########################################
    @400000004d92062132ee94f4 2011-03-29 17:17:27,854 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d92062132f1214c 2011-03-29 17:17:27,854 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d92062132f357b4 2011-03-29 17:17:27,854 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d92062132f5dc3c 2011-03-29 17:17:27,854 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d92062132f8be84 2011-03-29 17:17:27,855 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/109/schedule_id/11
    @400000004d9206213ad21524 2011/03/29 17:17:27 [output(dot)alsa:2] Underrun!
    @400000004d9206213ad224c4 2011/03/29 17:17:27 [output(dot)alsa:2] Trying to recover..
    @400000004d9206220264d0dc 2011-03-29 17:17:28,039 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d92062202691e6c 2011-03-29 17:17:28,040 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d920622026d642c 2011-03-29 17:17:28,040 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d9206a126d2974c 2011/03/29 17:19:35 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/447cd4a1c2aae4 1e86b0b21890faf50e.mp3 ".
    @400000004d9206a126d8d10c 2011/03/29 17:19:35 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/9e7f95978bfd05 d30a816726cb527879.mp3 " (RID 9).
    @400000004d9206a1289912f4 2011/03/29 17:19:35 [decoder:3] Method "MP3/LIBMAD" accepted " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/18ce4401fc4c11 7d3a589c4bd4fd6d8c.mp3 ".
    @400000004d9206a128a44a0c TagLib: ID3v2.4 no longer supports the frame type RVAD. It will be discarded from the tag.
    @400000004d9206a129b36464 ./notify.sh --data='11' --media-id=110
    @400000004d9206a13278a58c 2011/03/29 17:19:35 [output(dot)alsa:2] Underrun!
    @400000004d9206a13278b144 2011/03/29 17:19:35 [output(dot)alsa:2] Trying to recover..
    @400000004d9206a134696584
    @400000004d9206a13469713c #########################################
    @400000004d9206a13469790c # *** pypo *** #
    @400000004d9206a1346980dc # pypo notification gateway #
    @400000004d9206a1346984c4 #########################################
    @400000004d9206a134698c94 2011-03-29 17:19:35,879 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9206a1346c1cd4 2011-03-29 17:19:35,879 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d9206a1346e5ef4 2011-03-29 17:19:35,879 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d9206a134709944 2011-03-29 17:19:35,879 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d9206a134737b8c 2011-03-29 17:19:35,879 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/110/schedule_id/11
    @400000004d9206a202d92a04 2011-03-29 17:19:36,047 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9206a202dc3f14 2011-03-29 17:19:36,047 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9206a202dfc954 2011-03-29 17:19:36,048 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d9207762063c944 2011/03/29 17:23:08 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/9e7f95978bfd05 d30a816726cb527879.mp3 ".
    @400000004d92077620683de4 2011/03/29 17:23:08 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/5b5ef92d45ba9f f578014392598da7d8.mp3 " (RID 10).
    @400000004d920776243fcaf4 2011/03/29 17:23:08 [decoder:3] Method "MP3/LIBMAD" accepted " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/ccf7422707fa2f 8c77924eb27963200f.mp3 ".
    @400000004d920776244ab7d4 TagLib: ID3v2.4 no longer supports the frame type RVAD. It will be discarded from the tag.
    @400000004d9207762526102c ./notify.sh --data='11' --media-id=111
    @400000004d9207762bc740a4
    @400000004d9207762bc74c5c #########################################
    @400000004d9207762bc7542c # *** pypo *** #
    @400000004d9207762bc75bfc # pypo notification gateway #
    @400000004d9207762bc763cc #########################################
    @400000004d9207762bc76b9c 2011-03-29 17:23:08,734 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9207762bca0f64 2011-03-29 17:23:08,734 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d9207762bcc5184 2011-03-29 17:23:08,734 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d9207762bce978c 2011-03-29 17:23:08,734 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d9207762bd1858c 2011-03-29 17:23:08,735 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/111/schedule_id/11
    @400000004d92077636049b0c 2011-03-29 17:23:08,906 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9207763607a464 2011-03-29 17:23:08,906 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d920776360afbdc 2011-03-29 17:23:08,906 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d92077638ec26b4 2011/03/29 17:23:08 [output(dot)alsa:2] Underrun!
    @400000004d92077638ec326c 2011/03/29 17:23:08 [output(dot)alsa:2] Trying to recover..
    @400000004d9208b52d934a2c 2011/03/29 17:28:27 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/5b5ef92d45ba9f f578014392598da7d8.mp3 ".
    @400000004d9208b52d935db4 2011/03/29 17:28:27 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/f55df4861d993a 1885806a19e126ea6e.mp3 " (RID 11).
    @400000004d9208b532dfb074 ./notify.sh --data='11' --media-id=112
    @400000004d9208b6043dea24
    @400000004d9208b6043df5dc #########################################
    @400000004d9208b6043dfdac # *** pypo *** #
    @400000004d9208b6043e057c # pypo notification gateway #
    @400000004d9208b6043e0d4c #########################################
    @400000004d9208b6043e151c 2011-03-29 17:28:28,070 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9208b60440a944 2011-03-29 17:28:28,071 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d9208b60442e394 2011-03-29 17:28:28,071 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d9208b6044519fc 2011-03-29 17:28:28,071 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d9208b604487174 2011-03-29 17:28:28,071 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/112/schedule_id/11
    @400000004d9208b608a0deb4 2011/03/29 17:28:28 [output(dot)alsa:2] Underrun!
    @400000004d9208b608a2e254 2011/03/29 17:28:28 [output(dot)alsa:2] Trying to recover..
    @400000004d9208b611431eec 2011-03-29 17:28:28,283 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9208b6114889a4 2011-03-29 17:28:28,289 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9208b6114c5a34 2011-03-29 17:28:28,290 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d9209aa2ea9f604 2011/03/29 17:32:32 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/f55df4861d993a 1885806a19e126ea6e.mp3 ".
    @400000004d9209aa2eb25a74 2011/03/29 17:32:32 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/7b8cf8f82c68d4 6a0c1a4f7d6735fa73.mp3 " (RID 12).
    @400000004d9209aa3303f1b4 ./notify.sh --data='11' --media-id=113
    @400000004d9209ab021005d4
    @400000004d9209ab0210118c #########################################
    @400000004d9209ab0210195c # *** pypo *** #
    @400000004d9209ab02101d44 # pypo notification gateway #
    @400000004d9209ab02102514 #########################################
    @400000004d9209ab02102ce4 2011-03-29 17:32:33,034 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9209ab02139fb4 2011-03-29 17:32:33,034 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d9209ab02163bac 2011-03-29 17:32:33,034 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d9209ab02192d94 2011-03-29 17:32:33,035 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d9209ab021c7184 2011-03-29 17:32:33,035 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/113/schedule_id/11
    @400000004d9209ab0379f22c 2011/03/29 17:32:33 [output(dot)alsa:2] Underrun!
    @400000004d9209ab0379fde4 2011/03/29 17:32:33 [output(dot)alsa:2] Trying to recover..
    @400000004d9209ab0cd1651c 2011-03-29 17:32:33,214 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9209ab0cd46e74 2011-03-29 17:32:33,215 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9209ab0cd7c5ec 2011-03-29 17:32:33,215 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d920a350c4ef67c 2011/03/29 17:34:51 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/7b8cf8f82c68d4 6a0c1a4f7d6735fa73.mp3 ".
    @400000004d920a350c5664d4 2011/03/29 17:34:51 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/f55df4861d993a 1885806a19e126ea6e.mp3 " (RID 13).
    @400000004d920a3510d699ac ./notify.sh --data='11' --media-id=114
    @400000004d920a351851652c
    @400000004d920a35185170e4 #########################################
    @400000004d920a35185178b4 # *** pypo *** #
    @400000004d920a3518518084 # pypo notification gateway #
    @400000004d920a3518518854 #########################################
    @400000004d920a3518519024 2011-03-29 17:34:51,407 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d920a3518543004 2011-03-29 17:34:51,408 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d920a3518566e3c 2011-03-29 17:34:51,408 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d920a351858a88c 2011-03-29 17:34:51,408 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d920a35185c0fa4 2011-03-29 17:34:51,408 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/114/schedule_id/11
    @400000004d920a351fc6e534 2011/03/29 17:34:51 [output(dot)alsa:2] Underrun!
    @400000004d920a351fc8f874 2011/03/29 17:34:51 [output(dot)alsa:2] Trying to recover..
    @400000004d920a35239b0744 2011-03-29 17:34:51,597 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d920a35239e762c 2011-03-29 17:34:51,597 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d920a3523a2a864 2011-03-29 17:34:51,597 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d920b2a0bfb5454 2011/03/29 17:38:56 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/f55df4861d993a 1885806a19e126ea6e.mp3 ".
    @400000004d920b2a0bfdd4f4 2011/03/29 17:38:56 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/094ffcfddbd7d8 111188e5177b14e92d.mp3 " (RID 14).
    @400000004d920b2a0cd58b9c ./notify.sh --data='11' --media-id=115
    @400000004d920b2a13fc728c
    @400000004d920b2a13fc7e44 #########################################
    @400000004d920b2a13fc8614 # *** pypo *** #
    @400000004d920b2a13fc8de4 # pypo notification gateway #
    @400000004d920b2a13fc95b4 #########################################
    @400000004d920b2a13fc9d84 2011-03-29 17:38:56,335 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d920b2a13ff220c 2011-03-29 17:38:56,335 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d920b2a14016044 2011-03-29 17:38:56,335 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d920b2a14039a94 2011-03-29 17:38:56,335 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d920b2a140680c4 2011-03-29 17:38:56,335 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/115/schedule_id/11
    @400000004d920b2a1e2ab994 2011/03/29 17:38:56 [output(dot)alsa:2] Underrun!
    @400000004d920b2a1e2ac934 2011/03/29 17:38:56 [output(dot)alsa:2] Trying to recover..
    @400000004d920b2a1fc29f74 2011-03-29 17:38:56,532 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d920b2a1fc5c03c 2011-03-29 17:38:56,532 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d920b2a1fc91b9c 2011-03-29 17:38:56,533 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d920bcd0444fea4 2011/03/29 17:41:39 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/094ffcfddbd7d8 111188e5177b14e92d.mp3 ".
    @400000004d920bcd044957ec 2011/03/29 17:41:39 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/328a605ddeed7f 1ce2dd3f298f200563.mp3 " (RID 15).
    @400000004d920bcd06d6639c ./notify.sh --data='11' --media-id=116
    @400000004d920bcd105cdd9c
    @400000004d920bcd105ce954 #########################################
    @400000004d920bcd105cf124 # *** pypo *** #
    @400000004d920bcd105cf8f4 # pypo notification gateway #
    @400000004d920bcd105d00c4 #########################################
    @400000004d920bcd105d04ac 2011-03-29 17:41:39,274 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d920bcd105f98d4 2011-03-29 17:41:39,274 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d920bcd1061daf4 2011-03-29 17:41:39,274 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d920bcd10641544 2011-03-29 17:41:39,274 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d920bcd1066f78c 2011-03-29 17:41:39,275 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/116/schedule_id/11
    @400000004d920bcd18203f24 2011/03/29 17:41:39 [output(dot)alsa:2] Underrun!
    @400000004d920bcd18204adc 2011/03/29 17:41:39 [output(dot)alsa:2] Trying to recover..
    @400000004d920bcd1c83d7c4 2011-03-29 17:41:39,478 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d920bcd1c872f3c 2011-03-29 17:41:39,478 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d920bcd1c8b03b4 2011-03-29 17:41:39,478 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d920cbd2fc3a74c 2011/03/29 17:45:39 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/328a605ddeed7f 1ce2dd3f298f200563.mp3 ".
    @400000004d920cbd2fccf234 2011/03/29 17:45:39 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/d468cce6a35a20 cfc3e7026d4d756724.mp3 " (RID 16).
    @400000004d920cbd33b97674 ./notify.sh --data='11' --media-id=117
    @400000004d920cbe037231cc
    @400000004d920cbe03723d84 #########################################
    @400000004d920cbe03724554 # *** pypo *** #
    @400000004d920cbe0372493c # pypo notification gateway #
    @400000004d920cbe0372510c #########################################
    @400000004d920cbe037258dc 2011-03-29 17:45:40,057 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d920cbe0376313c 2011-03-29 17:45:40,057 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d920cbe0378cd34 2011-03-29 17:45:40,058 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d920cbe037b598c 2011-03-29 17:45:40,058 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d920cbe037e95ac 2011-03-29 17:45:40,058 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/117/schedule_id/11
    @400000004d920cbe080b806c 2011/03/29 17:45:40 [output(dot)alsa:2] Underrun!
    @400000004d920cbe080f0aac 2011/03/29 17:45:40 [output(dot)alsa:2] Trying to recover..
    @400000004d920cbe0e16f0f4 2011-03-29 17:45:40,236 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d920cbe0e19eaac 2011-03-29 17:45:40,236 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d920cbe0e1d3e3c 2011-03-29 17:45:40,236 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d920d041e3ffafc 2011/03/29 17:46:50 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/d468cce6a35a20 cfc3e7026d4d756724.mp3 ".
    @400000004d920d041e495584 2011/03/29 17:46:50 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/ecc6d3abbcb959 ffb03ce3d5298057e7.mp3 " (RID 17).
    @400000004d920d042277bcf4 ./notify.sh --data='11' --media-id=118
    @400000004d920d042c5144ac
    @400000004d920d042c515064 #########################################
    @400000004d920d042c515834 # *** pypo *** #
    @400000004d920d042c516004 # pypo notification gateway #
    @400000004d920d042c5167d4 #########################################
    @400000004d920d042c516fa4 2011-03-29 17:46:50,743 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d920d042c541754 2011-03-29 17:46:50,743 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d920d042c5651a4 2011-03-29 17:46:50,743 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d920d042c588bf4 2011-03-29 17:46:50,743 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d920d042c5b6e3c 2011-03-29 17:46:50,744 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/118/schedule_id/11
    @400000004d920d042f39a344 2011/03/29 17:46:50 [output(dot)alsa:2] Underrun!
    @400000004d920d042f39aefc 2011/03/29 17:46:50 [output(dot)alsa:2] Trying to recover..
    @400000004d920d04373990cc 2011-03-29 17:46:50,926 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d920d04373d886c 2011-03-29 17:46:50,926 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d920d043741706c 2011-03-29 17:46:50,926 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d920df50852f564 2011/03/29 17:50:51 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/ecc6d3abbcb959 ffb03ce3d5298057e7.mp3 ".
    @400000004d920df5085746dc 2011/03/29 17:50:51 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/8db9300b07cdc3 40e95684ce708dd79a.mp3 " (RID 18).
    @400000004d920df50a33e294 ./notify.sh --data='11' --media-id=119
    @400000004d920df510622914
    @400000004d920df5106234cc #########################################
    @400000004d920df510623c9c # *** pypo *** #
    @400000004d920df51062446c # pypo notification gateway #
    @400000004d920df510624c3c #########################################
    @400000004d920df51062540c 2011-03-29 17:50:51,274 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d920df51064e064 2011-03-29 17:50:51,274 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d920df510671ab4 2011-03-29 17:50:51,275 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d920df510694d34 2011-03-29 17:50:51,275 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d920df5106c27ac 2011-03-29 17:50:51,275 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/119/schedule_id/11
    @400000004d920df51a463fec 2011-03-29 17:50:51,440 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d920df51a492a04 2011-03-29 17:50:51,440 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d920df51a4c75c4 2011-03-29 17:50:51,441 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d920df51e50d37c 2011/03/29 17:50:51 [output(dot)alsa:2] Underrun!
    @400000004d920df51e50df34 2011/03/29 17:50:51 [output(dot)alsa:2] Trying to recover..
    @400000004d920f07291f32e4 2011/03/29 17:55:25 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/8db9300b07cdc3 40e95684ce708dd79a.mp3 ".
    @400000004d920f072928f2fc 2011/03/29 17:55:25 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/d31da1f953e702 bd72ae34481b69a993.mp3 " (RID 19).
    @400000004d920f072ae8f664 ./notify.sh --data='11' --media-id=120
    @400000004d920f073377f8d4
    @400000004d920f073378048c #########################################
    @400000004d920f0733780c5c # *** pypo *** #
    @400000004d920f073378142c # pypo notification gateway #
    @400000004d920f0733781bfc #########################################
    @400000004d920f07337823cc 2011-03-29 17:55:25,863 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d920f07337b8ae4 2011-03-29 17:55:25,863 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d920f07337e26dc 2011-03-29 17:55:25,863 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d920f073380c2d4 2011-03-29 17:55:25,864 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d920f0733840aac 2011-03-29 17:55:25,864 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/120/schedule_id/11
    @400000004d920f07349e11e4 2011/03/29 17:55:25 [output(dot)alsa:2] Underrun!
    @400000004d920f07349eb9dc 2011/03/29 17:55:25 [output(dot)alsa:2] Trying to recover..
    @400000004d920f0811ae3ccc 2011-03-29 17:55:26,291 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d920f0811ae5054 2011-03-29 17:55:26,291 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d920f0811ae5824 2011-03-29 17:55:26,291 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d9210e30ce4125c 2011/03/29 18:03:21 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/d31da1f953e702 bd72ae34481b69a993.mp3 ".
    @400000004d9210e30cebbb4c 2011/03/29 18:03:21 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/ecc6d3abbcb959 ffb03ce3d5298057e7.mp3 " (RID 20).
    @400000004d9210e30e981f44 ./notify.sh --data='11' --media-id=121
    @400000004d9210e31afcaf0c
    @400000004d9210e31afcbeac #########################################
    @400000004d9210e31afcca64 # *** pypo *** #
    @400000004d9210e31afcd61c # pypo notification gateway #
    @400000004d9210e31afcddec #########################################
    @400000004d9210e31afce9a4 2011-03-29 18:03:21,452 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9210e31b043ca4 2011-03-29 18:03:21,453 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d9210e31b0ab0fc 2011-03-29 18:03:21,453 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d9210e31b103324 2011-03-29 18:03:21,453 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d9210e31b16edcc 2011-03-29 18:03:21,454 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/121/schedule_id/11
    @400000004d9210e322c9828c 2011/03/29 18:03:21 [output(dot)alsa:2] Underrun!
    @400000004d9210e322ca49c4 2011/03/29 18:03:21 [output(dot)alsa:2] Trying to recover..
    @400000004d9210e325c47ec4 2011-03-29 18:03:21,630 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9210e325c48e64 2011-03-29 18:03:21,630 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9210e325c49a1c 2011-03-29 18:03:21,630 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d9211d33a089eec 2011/03/29 18:07:21 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/ecc6d3abbcb959 ffb03ce3d5298057e7.mp3 ".
    @400000004d9211d33a08b274 2011/03/29 18:07:21 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/18ce4401fc4c11 7d3a589c4bd4fd6d8c.mp3 " (RID 21).
    @400000004d9211d400ae04d4 ./notify.sh --data='11' --media-id=122
    @400000004d9211d41244048c
    @400000004d9211d412440c5c #########################################
    @400000004d9211d41244142c # *** pypo *** #
    @400000004d9211d412441bfc # pypo notification gateway #
    @400000004d9211d412441fe4 #########################################
    @400000004d9211d4124427b4 2011-03-29 18:07:22,306 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9211d41246bbdc 2011-03-29 18:07:22,306 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d9211d412492124 2011-03-29 18:07:22,306 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d9211d4124bebfc 2011-03-29 18:07:22,306 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d9211d4124f2fec 2011-03-29 18:07:22,307 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/122/schedule_id/11
    @400000004d9211d412df890c 2011/03/29 18:07:22 [output(dot)alsa:2] Underrun!
    @400000004d9211d412df98ac 2011/03/29 18:07:22 [output(dot)alsa:2] Trying to recover..
    @400000004d9211d41ffc686c 2011-03-29 18:07:22,534 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9211d41ffc7bf4 2011-03-29 18:07:22,534 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9211d41ffc83c4 2011-03-29 18:07:22,534 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d9212ca2d22e8a4 2011/03/29 18:11:28 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/18ce4401fc4c11 7d3a589c4bd4fd6d8c.mp3 ".
    @400000004d9212ca2d2be954 2011/03/29 18:11:28 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/328a605ddeed7f 1ce2dd3f298f200563.mp3 " (RID 22).
    @400000004d9212ca32c08fb4 ./notify.sh --data='11' --media-id=123
    @400000004d9212cb0328a994
    @400000004d9212cb0328b54c #########################################
    @400000004d9212cb0328bd1c # *** pypo *** #
    @400000004d9212cb0328c4ec # pypo notification gateway #
    @400000004d9212cb0328c8d4 #########################################
    @400000004d9212cb0328d0a4 2011-03-29 18:11:29,052 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9212cb032c957c 2011-03-29 18:11:29,053 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d9212cb032f3174 2011-03-29 18:11:29,053 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d9212cb03327564 2011-03-29 18:11:29,053 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d9212cb0335bd3c 2011-03-29 18:11:29,053 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/123/schedule_id/11
    @400000004d9212cb03ef6c64 2011/03/29 18:11:29 [output(dot)alsa:2] Underrun!
    @400000004d9212cb03f02fb4 2011/03/29 18:11:29 [output(dot)alsa:2] Trying to recover..
    @400000004d9212cb0ea40a0c 2011-03-29 18:11:29,242 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9212cb0ea415c4 2011-03-29 18:11:29,242 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9212cb0ea4217c 2011-03-29 18:11:29,243 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d9213bb1b360e8c 2011/03/29 18:15:29 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/328a605ddeed7f 1ce2dd3f298f200563.mp3 ".
    @400000004d9213bb1b3da3f4 2011/03/29 18:15:29 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/77b47faae6fdbc e0bba1a987e56223b6.mp3 " (RID 23).
    @400000004d9213bb1d7c8fa4 ./notify.sh --data='11' --media-id=124
    @400000004d9213bb2604cf9c
    @400000004d9213bb2604db54 #########################################
    @400000004d9213bb2604e324 # *** pypo *** #
    @400000004d9213bb2604eaf4 # pypo notification gateway #
    @400000004d9213bb2604eedc #########################################
    @400000004d9213bb2604f6ac 2011-03-29 18:15:29,637 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9213bb26079e5c 2011-03-29 18:15:29,637 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d9213bb2609dc94 2011-03-29 18:15:29,638 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d9213bb260c1acc 2011-03-29 18:15:29,638 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d9213bb260f00fc 2011-03-29 18:15:29,638 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/124/schedule_id/11
    @400000004d9213bb2f206a3c 2011/03/29 18:15:29 [output(dot)alsa:2] Underrun!
    @400000004d9213bb2f216c0c 2011/03/29 18:15:29 [output(dot)alsa:2] Trying to recover..
    @400000004d9213bb31dc6d5c 2011-03-29 18:15:29,836 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9213bb31e045bc 2011-03-29 18:15:29,836 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9213bb31e425ec 2011-03-29 18:15:29,836 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d92140a3a163f34 2011/03/29 18:16:48 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/77b47faae6fdbc e0bba1a987e56223b6.mp3 ".
    @400000004d92140a3a1e9404 2011/03/29 18:16:48 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/f28fe820fa62d5 616d864e46bc8df7bc.mp3 " (RID 24).
    @400000004d92140a3b57ac5c ./notify.sh --data='11' --media-id=125
    @400000004d92140b05c7455c
    @400000004d92140b05c75114 #########################################
    @400000004d92140b05c758e4 # *** pypo *** #
    @400000004d92140b05c760b4 # pypo notification gateway #
    @400000004d92140b05c76884 #########################################
    @400000004d92140b05c77054 2011-03-29 18:16:49,096 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d92140b05ca1034 2011-03-29 18:16:49,097 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d92140b05cc469c 2011-03-29 18:16:49,097 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d92140b05ce80ec 2011-03-29 18:16:49,097 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d92140b05d1671c 2011-03-29 18:16:49,097 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/125/schedule_id/11
    @400000004d92140b0e095494 2011/03/29 18:16:49 [output(dot)alsa:2] Underrun!
    @400000004d92140b0e09604c 2011/03/29 18:16:49 [output(dot)alsa:2] Trying to recover..
    @400000004d92140b0fda5b74 2011-03-29 18:16:49,265 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d92140b0fdd7084 2011-03-29 18:16:49,266 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d92140b0fe0c02c 2011-03-29 18:16:49,266 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d9214163507012c 2011/03/29 18:17:00 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/f28fe820fa62d5 616d864e46bc8df7bc.mp3 ".
    @400000004d921416350f0fac 2011/03/29 18:17:00 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/d31da1f953e702 bd72ae34481b69a993.mp3 " (RID 25).
    @400000004d9214163935e1b4 ./notify.sh --data='11' --media-id=126
    @400000004d92141707923d74
    @400000004d9214170792492c #########################################
    @400000004d921417079250fc # *** pypo *** #
    @400000004d921417079258cc # pypo notification gateway #
    @400000004d9214170792609c #########################################
    @400000004d9214170792686c 2011-03-29 18:17:01,126 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9214170795007c 2011-03-29 18:17:01,127 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d921417079736e4 2011-03-29 18:17:01,127 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d92141707996d4c 2011-03-29 18:17:01,127 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d921417079c537c 2011-03-29 18:17:01,127 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/126/schedule_id/11
    @400000004d9214170e2089fc 2011/03/29 18:17:01 [output(dot)alsa:2] Underrun!
    @400000004d9214170e20999c 2011/03/29 18:17:01 [output(dot)alsa:2] Trying to recover..
    @400000004d92141713198c9c 2011-03-29 18:17:01,320 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d921417131d0f0c 2011-03-29 18:17:01,320 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9214171320f324 2011-03-29 18:17:01,320 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d9215f224a33814 2011/03/29 18:24:56 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/d31da1f953e702 bd72ae34481b69a993.mp3 ".
    @400000004d9215f224ac05fc 2011/03/29 18:24:56 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/3be9ea74a78b3f 87d3be0b8b15e0d27b.mp3 " (RID 26).
    @400000004d9215f22929ae7c ./notify.sh --data='11' --media-id=127
    @400000004d9215f2340738b4
    @400000004d9215f23407446c #########################################
    @400000004d9215f234074c3c # *** pypo *** #
    @400000004d9215f23407540c # pypo notification gateway #
    @400000004d9215f2340757f4 #########################################
    @400000004d9215f234075fc4 2011-03-29 18:24:56,872 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9215f23409f004 2011-03-29 18:24:56,872 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d9215f2340c2a54 2011-03-29 18:24:56,873 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d9215f2340e688c 2011-03-29 18:24:56,873 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d9215f234114ad4 2011-03-29 18:24:56,873 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/127/schedule_id/11
    @400000004d9215f23748c754 2011/03/29 18:24:56 [output(dot)alsa:2] Underrun!
    @400000004d9215f2374a888c 2011/03/29 18:24:56 [output(dot)alsa:2] Trying to recover..
    @400000004d9215f305429884 2011-03-29 18:24:57,088 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9215f30545bd34 2011-03-29 18:24:57,088 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9215f30549438c 2011-03-29 18:24:57,088 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d921692352adcdc 2011/03/29 18:27:36 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/3be9ea74a78b3f 87d3be0b8b15e0d27b.mp3 ".
    @400000004d9216923534a0dc 2011/03/29 18:27:36 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/b8382fc36c88f2 21f9d560308ab2150c.mp3 " (RID 27).
    @400000004d9216923781e084 ./notify.sh --data='11' --media-id=128
    @400000004d921693021dcd2c
    @400000004d921693021dd8e4 #########################################
    @400000004d921693021de0b4 # *** pypo *** #
    @400000004d921693021de884 # pypo notification gateway #
    @400000004d921693021df054 #########################################
    @400000004d921693021df824 2011-03-29 18:27:37,035 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9216930220edf4 2011-03-29 18:27:37,035 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d92169302237e34 2011-03-29 18:27:37,035 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d92169302260e74 2011-03-29 18:27:37,035 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d921693022965ec 2011-03-29 18:27:37,036 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/128/schedule_id/11
    @400000004d9216930ce69eb4 2011/03/29 18:27:37 [output(dot)alsa:2] Underrun!
    @400000004d9216930ce6aa6c 2011/03/29 18:27:37 [output(dot)alsa:2] Trying to recover..
    @400000004d9216930d9bf87c 2011-03-29 18:27:37,228 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9216930d9f4c0c 2011-03-29 18:27:37,228 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9216930da39d84 2011-03-29 18:27:37,228 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d9216cb1bab59e4 2011/03/29 18:28:33 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/b8382fc36c88f2 21f9d560308ab2150c.mp3 ".
    @400000004d9216cb1bb03fcc 2011/03/29 18:28:33 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/be96824dd8aa70 16e7efd7326e9e7045.mp3 " (RID 28).
    @400000004d9216cb1fe448a4 ./notify.sh --data='11' --media-id=129
    @400000004d9216cb27041efc
    @400000004d9216cb270426cc #########################################
    @400000004d9216cb27042e9c # *** pypo *** #
    @400000004d9216cb2704366c # pypo notification gateway #
    @400000004d9216cb27043e3c #########################################
    @400000004d9216cb2704460c 2011-03-29 18:28:33,654 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9216cb2706e5ec 2011-03-29 18:28:33,654 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d9216cb27092424 2011-03-29 18:28:33,654 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d9216cb270b625c 2011-03-29 18:28:33,654 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d9216cb270e4c74 2011-03-29 18:28:33,655 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/129/schedule_id/11
    @400000004d9216cb32285ec4 2011/03/29 18:28:33 [output(dot)alsa:2] Underrun!
    @400000004d9216cb32286e64 2011/03/29 18:28:33 [output(dot)alsa:2] Trying to recover..
    @400000004d9216cb3294b524 2011-03-29 18:28:33,848 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9216cb3298b494 2011-03-29 18:28:33,848 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9216cb329c90dc 2011-03-29 18:28:33,849 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d92179f185170e4 2011/03/29 18:32:05 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/be96824dd8aa70 16e7efd7326e9e7045.mp3 ".
    @400000004d92179f1858529c 2011/03/29 18:32:05 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/18ce4401fc4c11 7d3a589c4bd4fd6d8c.mp3 " (RID 29).
    @400000004d92179f1cc038cc ./notify.sh --data='11' --media-id=130
    @400000004d92179f242401ac 2011/03/29 18:32:05 [output(dot)alsa:2] Underrun!
    @400000004d92179f2426dc24 2011/03/29 18:32:05 [output(dot)alsa:2] Trying to recover..
    @400000004d92179f261ea8cc
    @400000004d92179f261eb484 #########################################
    @400000004d92179f261ebc54 # *** pypo *** #
    @400000004d92179f261ec80c # pypo notification gateway #
    @400000004d92179f261ecfdc #########################################
    @400000004d92179f261ed7ac 2011-03-29 18:32:05,639 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d92179f262236f4 2011-03-29 18:32:05,639 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d92179f2624d2ec 2011-03-29 18:32:05,639 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d92179f26276714 2011-03-29 18:32:05,640 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d92179f262aa334 2011-03-29 18:32:05,640 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/130/schedule_id/11
    @400000004d92179f30c056cc 2011-03-29 18:32:05,817 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d92179f30c3dd24 2011-03-29 18:32:05,818 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d92179f30c7b19c 2011-03-29 18:32:05,818 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d92189604bda944 2011/03/29 18:36:12 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/18ce4401fc4c11 7d3a589c4bd4fd6d8c.mp3 ".
    @400000004d92189604c6e48c 2011/03/29 18:36:12 [queue:3] Prepared " /opt/pypo/cache/scheduler/2011-03-29-16-40-00/ccf7422707fa2f 8c77924eb27963200f.mp3 " (RID 30).
    @400000004d92189606abf83c ./notify.sh --data='11' --media-id=131
    @400000004d9218960fc6cba4
    @400000004d9218960fc6d75c #########################################
    @400000004d9218960fc6df2c # *** pypo *** #
    @400000004d9218960fc6e6fc # pypo notification gateway #
    @400000004d9218960fc6eae4 #########################################
    @400000004d9218960fc6f2b4 2011-03-29 18:36:12,264 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 78] - #################################################
    @400000004d9218960fc986dc 2011-03-29 18:36:12,264 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 79] - # Calling server to update about what's playing #
    @400000004d9218960fcbc514 2011-03-29 18:36:12,264 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 80] - #################################################
    @400000004d9218960fce034c 2011-03-29 18:36:12,265 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 81] - data = 11
    @400000004d9218960fd0ed64 2011-03-29 18:36:12,265 DEBUG - [api_client.py : notify_media_item_start_playing() : line 308] - http://localhost/api/notify-media-item-start-play/api_key/4M UBZZYZDH9FDK5N6TT1/media_id/131/schedule_id/11
    @400000004d921896144c9dfc 2011/03/29 18:36:12 [output(dot)alsa:2] Underrun!
    @400000004d921896144cad9c 2011/03/29 18:36:12 [output(dot)alsa:2] Trying to recover..
    @400000004d9218961cf12054 2011-03-29 18:36:12,483 INFO - [api_client.py : notify_media_item_start_playing() : line 311] - API-Status 1
    @400000004d9218961cf133dc 2011-03-29 18:36:12,484 INFO - [api_client.py : notify_media_item_start_playing() : line 312] - API-Message
    @400000004d9218961cf13f94 2011-03-29 18:36:12,484 DEBUG - [pypo-notify.py : notify_media_start_playing() : line 83] - Response: {"status": 1, "message": ""}
    @400000004d92197a0105fc5c 2011/03/29 18:40:00 [server:3] New client: localhost
    @400000004d92197a01229c7c 2011/03/29 18:40:00 [queue:3] Finished with " /opt/pypo/cache/scheduler/2011
  • Vote Up0Vote Down VerheekVerheek
    Posts: 158Member
    Hmm yes I installed from the latest master from yesterday and everything worked fine except that icecast shows offline.

    pypo log:

    Quote:
    @400000004d925649051edffc 2011-03-29 17:59:27,085 INFO - [pypofetch.py : prepare_playlists() : line 156] - Playlist starting at 2011-03-29-22-00-00
    @400000004d9256490718bbfc 2011-03-29 17:59:27,118 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/a76387ac1c53e9021ac9f737 3cb19697.mp3
    @400000004d925649089d8f0c 2011-03-29 17:59:27,144 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/ed1153743cc52dafa62ad718 e73116ac.mp3
    @400000004d925649089fec84 2011-03-29 17:59:27,144 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/4c0804c903bfda6c1885b685 5d0d5885.mp3
    @400000004d92564908a255b4 2011-03-29 17:59:27,144 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/c9cac5a1fa4e92b3dfd94e69 1e3692ad.mp3
    @400000004d92564908a4a38c 2011-03-29 17:59:27,144 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/a5ff3190bfa53d3d07dbf52f 368b2eb1.mp3
    @400000004d92564908a789bc 2011-03-29 17:59:27,145 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/92c41cea404def6f1ebd1340 a33930e1.mp3
    @400000004d92564908a9c40c 2011-03-29 17:59:27,145 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/13f59e23a2c55f330efd18cb 5bfd2291.mp3
    @400000004d92564908ac256c 2011-03-29 17:59:27,145 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/903843ede411e92e32865403 a81bbe8c.mp3
    @400000004d92564908ae6b74 2011-03-29 17:59:27,145 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/d936462e618bc5841ebca365 e59843db.mp3
    @400000004d92564908b0a5c4 2011-03-29 17:59:27,145 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/2092bf3ea7ea9de2b990adee 32136e04.mp3
    @400000004d92564908b2dc2c 2011-03-29 17:59:27,145 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/041294070fb94bc36b432255 980b4736.mp3
    @400000004d92564908b51e4c 2011-03-29 17:59:27,146 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/6383bf5c4e6f3bc56a8e09ad db71ab5c.mp3
    @400000004d92564908b76454 2011-03-29 17:59:27,146 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/69c420d889a38abf1946d187 8af368af.mp3
    @400000004d92564908b996d4 2011-03-29 17:59:27,146 DEBUG - [pypofetch.py : handle_media_file() : line 197] - Processing track http://localhost/api/get-media/file/95ecab90929829cf10b0592e 15b32d88.mp3
    @400000004d9256490e3e4b2c 2011-03-29 17:59:27,238 INFO - [pypofetch.py : run() : line 354] - Loop #13


    pypo-liquidsoap:

    Quote:
    @400000004d9244a0047fcd54 2011/03/29 16:44:06 >>> LOG START
    @400000004d9244a0048088d4 2011/03/29 16:43:59 [protocols.external:3] Found "/usr/bin/ufetch".
    @400000004d9244a00480ea7c 2011/03/29 16:43:59 [protocols.external:3] Found "/usr/bin/wget".
    @400000004d9244a004814454 2011/03/29 16:44:05 [main:3] Liquidsoap 1.0.0-beta+svn (trunk/liquidsoap@rev8214)
    @400000004d9244a004819e2c 2011/03/29 16:44:05 [main:3] Using: pcre=6.0.1 dtools=0.2.2 duppy=0.4.0
    @400000004d9244a00481a214 0.4.0 duppy.syntax=0.4.0
    @400000004d9244a00481a5fc 0.4.0 cry=0.1.6 mm=0.1.0 xmlplaylist=0.1.2 lastfm=0.3.0 ogg=0.4.1 vorbis=0.5.1 mad=0.4.0 flac=0.1.0 flac.ogg=0.1.0 lame=0.2.4 aacplus=0.2.0 gavl=0.1.2 bjack=0.1.3 alsa=0.2.1 samplerate=0.1.1 taglib=0.1.4 magic=0.7.3 camomile=0.7.1 faad=0.2.0 faac=0.1.2 soundtouch=0.1.5 portaudio=@VERSION ladspa=0.1.2 dssi=[unknown version]
    @400000004d9244a00481ffd4 2011/03/29 16:44:05 [main:2]
    @400000004d9244a0048259ac 2011/03/29 16:44:05 [main:2] DISCLAIMER: This version of Liquidsoap has been
    @400000004d9244a00482af9c 2011/03/29 16:44:05 [main:2] compiled from a snapshot of the development code.
    @400000004d9244a004830974 2011/03/29 16:44:05 [main:2] As such, it should not be used in production
    @400000004d9244a004835f64 2011/03/29 16:44:05 [main:2] unless you know what you are doing!
    @400000004d9244a00483b93c 2011/03/29 16:44:05 [main:2]
    @400000004d9244a004840f2c 2011/03/29 16:44:05 [main:2] We are, however, very interested in any feedback
    @400000004d9244a0048785e4 2011/03/29 16:44:05 [main:2] about our development code and committed to fix
    @400000004d9244a00487f344 2011/03/29 16:44:05 [main:2] issues as soon as possible.
    @400000004d9244a004884d1c 2011/03/29 16:44:05 [main:2]
    @400000004d9244a00488a30c 2011/03/29 16:44:05 [main:2] If you are interested in collaborating to
    @400000004d9244a00488f8fc 2011/03/29 16:44:05 [main:2] the development of Liquidsoap, feel free to
    @400000004d9244a004894eec 2011/03/29 16:44:05 [main:2] drop us a mail at <savonet-devl@lists.sf.net>
    @400000004d9244a00489a8c4 2011/03/29 16:44:05 [main:2] or to join the #savonet IRC channel on Freenode.
    @400000004d9244a0048a21dc 2011/03/29 16:44:05 [main:2]
    @400000004d9244a0048a8384 2011/03/29 16:44:05 [main:2] Please send any bug report or feature request
    @400000004d9244a0048add5c 2011/03/29 16:44:05 [main:2] using our trac <http://savonet.rastageeks.org>.
    @400000004d9244a0048b3734 2011/03/29 16:44:05 [main:2]
    @400000004d9244a0048b910c 2011/03/29 16:44:05 [main:2] We hope you enjoy this snapshot build of Liquidsoap!
    @400000004d9244a0048beae4 2011/03/29 16:44:05 [main:2]
    @400000004d9244a0048c40d4 2011/03/29 16:44:06 [lang:3] Found flac binary: enabling flac external decoder.
    @400000004d9244a0048c96c4 2011/03/29 16:44:06 [lang:3] Found metaflac binary: enabling flac external metadata resolver.
    @400000004d9244a0048cf09c 2011/03/29 16:44:06 [lang:3] Found faad binary: enabling external faad decoder and metadata resolver.
    @400000004d9244a0048d468c 2011/03/29 16:44:06 [lang:3] Found flac binary: enabling flac external decoder.
    @400000004d9244a0048d9c7c 2011/03/29 16:44:06 [lang:3] Found metaflac binary: enabling flac external metadata resolver.
    @400000004d9244a0048df654 2011/03/29 16:44:06 [lang:3] Found faad binary: enabling external faad decoder and metadata resolver.
    @400000004d9244a0048e4c44 2011/03/29 16:44:06 [frame:3] Using 44100Hz audio, 25Hz video, 44100Hz master.
    @400000004d9244a0048ea234 2011/03/29 16:44:06 [frame:3] Frame size must be a multiple of 1764 ticks = 1764 audio samples = 1 video samples.
    @400000004d9244a0048efc0c 2011/03/29 16:44:06 [frame:3] Targetting 'frame.duration': 0.04s = 1764 audio samples = 1764 ticks.
    @400000004d9244a0048f51fc 2011/03/29 16:44:06 [frame:3] Frames last 0.04s = 1764 audio samples = 1 video samples = 1764 ticks.
    @400000004d9244a00491edf4 2011/03/29 16:44:06 [threads:3] Created thread "generic queue #1".
    @400000004d9244a00494e3c4 2011/03/29 16:44:06 [threads:3] Created thread "non-blocking queue #1".
    @400000004d9244a004cd2a04 2011/03/29 16:44:06 [twrradio(dot)mp3:3] Connecting mount twrradio.mp3 for source@127.0.0.1...
    @400000004d9244a01ad22c3c 2011/03/29 16:44:06 [twrradio(dot)mp3:3] Connection setup was successful.
    @400000004d9244a01d3ce6c4 2011/03/29 16:44:06 [twrradio(dot)aacp:3] Connecting mount twrradio.aacp for source@127.0.0.1...
    @400000004d9244a026c0ad5c 2011/03/29 16:44:06 [twrradio(dot)aacp:3] Connection setup was successful.
    @400000004d9244a026c27e34 2011/03/29 16:44:06 [threads:3] Created thread "wallclock_main" (1 total).
    @400000004d9244a026c37064 2011/03/29 16:44:06 [clock.wallclock_main:3] Streaming loop starts, synchronized with wallclock.
    @400000004d9244a02927aadc 2011/03/29 16:44:06 [fallback_10738:3] Switch to src_10736.
    @400000004d9244a0292baa4c 2011/03/29 16:44:06 [src_10736:3] Inserting missing metadata.
    @400000004d9244a02c35312c /opt/pypo/bin/scripts/notify.sh --data='0' --media-id=
    @400000004d9244a02ff03974
    @400000004d9244a02ff03d5c #########################################
    @400000004d9244a02ff04144 # *** pypo *** #
    @400000004d9244a02ff04144 # pypo notification gateway #
    @400000004d9244a02ff0452c #########################################
    @400000004d9244a02ff0452c NOTICE: 'media_id' command-line argument not given.
    @400000004d92450306c1aed4 2011/03/29 16:45:45 [server:3] New client: localhost.localdomain
    @400000004d92475311dd0944 2011/03/29 16:55:37 [server:3] New client: localhost.localdomain
    @400000004d92479c1a1dad34 2011/03/29 16:56:50 [server:3] New client: localhost.localdomain
    @400000004d9247b7253250e4 2011/03/29 16:57:17 [server:3] New client: localhost.localdomain
    @400000004d9247c21bc8e84c 2011/03/29 16:57:28 [server:3] New client: localhost.localdomain
    @400000004d9247c72d344dc4 2011/03/29 16:57:33 [server:3] New client: localhost.localdomain
    @400000004d9247ca25997724 2011/03/29 16:57:36 [server:3] New client: localhost.localdomain
    @400000004d9247cf0964bf3c 2011/03/29 16:57:41 [server:3] New client: localhost.localdomain
    @400000004d9247d131eec4ac 2011/03/29 16:57:43 [server:3] New client: localhost.localdomain
    @400000004d9247d4169d351c 2011/03/29 16:57:46 [server:3] New client: localhost.localdomain
    @400000004d92482d22e53c34 2011/03/29 16:59:15 [server:3] New client: localhost.localdomain
    @400000004d9248a111fde36c 2011/03/29 17:01:09 [clock.wallclock_main:2] We must catchup 1.23 seconds!
    @400000004d9248a1263ddd14 2011/03/29 17:01:11 [clock.wallclock_main:2] We must catchup 2.59 seconds!
    @400000004d9248a213239304 2011/03/29 17:01:12 [clock.wallclock_main:2] We must catchup 1.71 seconds!
    @400000004d9248a92ec8c0d4 2011/03/29 17:01:19 [clock.wallclock_main:2] We must catchup 1.93 seconds!
    @400000004d92490a1e793754 2011/03/29 17:02:56 [clock.wallclock_main:2] We must catchup 1.33 seconds!
    @400000004d92491e0088d54c 2011/03/29 17:03:15 [clock.wallclock_main:2] We must catchup 1.03 seconds!
    @400000004d92491e3a6a5a74 2011/03/29 17:03:16 [clock.wallclock_main:2] We must catchup 1.53 seconds!
    @400000004d92496217ce94e4 2011/03/29 17:04:23 [clock.wallclock_main:2] We must catchup 1.06 seconds!
    @400000004d92496234e275b4 2011/03/29 17:04:24 [clock.wallclock_main:2] We must catchup 1.80 seconds!
    @400000004d924963363af134 2011/03/29 17:04:25 [clock.wallclock_main:2] We must catchup 1.94 seconds!
    @400000004d92496500f5a0dc 2011/03/29 17:04:27 [clock.wallclock_main:2] We must catchup 2.25 seconds!
    @400000004d9256402941a34c 2011/03/29 17:59:18 [server:3] New client: localhost.localdomain


    Jeremy
  • Vote Up0Vote Down Ofir GalOfir Gal
    Posts: 130Member
    Hi Jeremy,
    The "We must catchup 1.93 seconds!"
    is usually due to system resource issues.

    Can you try and reboot your system and run it with the minimal processes needed in the background and see if it helps?

    Please write here your hardware specs as well.
  • Vote Up0Vote Down Martin KonecnyMartin Konecny
    Posts: 1,860Member
    Hi guys,

    In the future please save the log text in a text file and upload it with your post. It makes this forum far more readable for us Smile

    Jeremy,
    I am curious if you ever had these issues for 1.6.x? We are looking into this high cpu usage problem.

    John,
    I don't see any problem in the pypo-liquidsoap log file you posted. What about pypo-fetch and pypo-push?
    Airtime Pro Hosting: http://airtime.pro
  • Vote Up0Vote Down VerheekVerheek
    Posts: 158Member
    Hi Martin,

    Sure I can attach the logs in the future.

    I don't remember any issues in 1.6.x really. I have run that on a looping 60min playlist for a couple days and it's fine. Mind you I didn't check the log either.

    I haven't noticed any stuttering in 1.7 alpha so far because I don't get any audio. It always says offline. Could it be that airtime is not communicating to liquidsoap for some reason or is it just that the programming isn't finished?

    The catch-up notices were short lived and Quote:
    @400000004d93294c395642c4 2011/03/30 08:59:46 [server:3] New client: localhost.localdomain
    was all that was logged up to 08:59:59 when the schedule ran out.

    Ofir:

    I'm running an Ubuntu Lucid guest on VMWare vCenter Server 4.0.0

    cat /proc/cpuinfo is attached. I don't think there's much issue with the spec unless the guest is not reserved enough resources. I'd have to check that.

    Top doesn't show much over 20% peak. But the log isn't full of these catch-ups.

  • Vote Up0Vote Down Ofir GalOfir Gal
    Posts: 130Member
    I want to run some tests to mimic your environment.
    Can you get the latest version of 1.7 from our servers and install it, reboot and run a show?
    I want to make sure we both are running a similar VM and version.
  • Vote Up0Vote Down VerheekVerheek
    Posts: 158Member
    Hi Ofir,

    Ok I have uninstalled, installed the latest git and rebooted. But I can't create a playlist at the moment. I guess this part is being worked on. The playlist meta-data entry doesn't appear. It just goes blank when I click New Playlist.

    I also stopped on-demand, laptop-mode and acpi-support on Ubuntu boot-up.

    I added an ~1GHz reservation to the virtual guest which seems to run around that area.

    So once I can create a playlist, I'll try the run a show.

  • Vote Up0Vote Down Ofir GalOfir Gal
    Posts: 130Member
    Hi,
    Creating new playlist issue has been resolved.
    Please download the latest.
  • Vote Up0Vote Down VerheekVerheek
    Posts: 158Member
    Ok I have uninstalled, installed the latest git and rebooted. The playlist started but no On Air light. One thing that is different is now Icecast shows a blank for Current Song instead off offline. Likewise VLC shows a blank and just the publishing point name.

    I see about 1.36 GHz with one MP3 stream. Vorbis is disabled.

    I see in the Pypo log Quote:
    @400000004d953c84357858cc socket.error: [Errno 111] Connection refused

    Ok I had typed my soundcloud password incorrectly. Last iteration is ok in the log.

    In Pypo-Liquidsoap Quote:
    @400000004d953d7a397cb684 2011/03/31 22:50:24 [server:3] Connection reset by peer in read()
    @400000004d953d7a397dc40c 2011/03/31 22:50:24 [server:3] Broken pipe in write()


    I think this is happening each time a song starts but need to look further. The playlist started at 22:45:00 and runs for exactly 60 minutes. I have attached the pypo-liquidsoap log for the first 15+.

    As an aside, I also noticed that when I click Add Show or I drag a playlist into the show, I have to click refresh in the browser to see the changes.

    Jeremy

Add a Comment