Opened 8 years ago

Closed 8 years ago

#444 closed defect (cant-reproduce)

Cloudfiles: «TypeError: object of type 'NoneType' has no len()» for video uploads

Reported by: Jorge Araya Navarro Owned by: Jorge Araya Navarro
Priority: major Milestone:
Component: programming Keywords: webm, cloudfiles, rackspace, storage
Cc: jorgean@… Parent Tickets:

Description

I updated my public instance of GNU MediaGoblin but when I tried to upload a video I got an exception, here is the complete output:

[2012-04-30 22:11:48,438: INFO/MainProcess] Got task from broker: mediagoblin.processing.task.ProcessMedia[dfea6d26-a3ef-4021-aac0-b303e1b1ffae]
[2012-04-30 22:11:48,471: DEBUG/MainProcess] Mediator: Running callback for task: mediagoblin.processing.task.ProcessMedia[dfea6d26-a3ef-4021-aac0-b303e1b1ffae]
[2012-04-30 22:11:48,471: DEBUG/MainProcess] TaskPool: Apply <function execute_and_trace at 0x2e415f0> (args:('mediagoblin.processing.task.ProcessMedia', u'dfea6d26-a3ef-4021-aac0-b303e1b1ffae', [u'63'], {}) kwargs:{'hostname': 'goblinartists.net', 'request': {'retries': 0, 'task': 'mediagoblin.processing.task.ProcessMedia', 'utc': False, 'loglevel': 10, 'delivery_info': {'routing_key': u'celery', 'exchange': u'celery'}, 'args': [u'63'], 'expires': None, 'is_eager': False, 'eta': None, 'hostname': 'goblinartists.net', 'kwargs': {}, 'logfile': 'celeryd.log', 'id': u'dfea6d26-a3ef-4021-aac0-b303e1b1ffae'}})
[2012-04-30 22:12:22,403: DEBUG/MainProcess] Task accepted: mediagoblin.processing.task.ProcessMedia[dfea6d26-a3ef-4021-aac0-b303e1b1ffae] pid:9408
[2012-04-30 22:13:48,936: DEBUG/PoolWorker-1] Processing <mediagoblin.db.sql.models.MediaEntry object at 0x2e3ffd0>
[2012-04-30 22:14:10,224: INFO/PoolWorker-1] Initializing VideoTranscoder...
[2012-04-30 22:14:12,075: DEBUG/PoolWorker-1] Setting up discoverer
[2012-04-30 22:14:16,795: DEBUG/PoolWorker-1] Setting up transcoding pipeline
[2012-04-30 22:14:22,361: INFO/PoolWorker-1] Discovering...
[2012-04-30 22:14:23,527: INFO/PoolWorker-1] Done
[2012-04-30 22:14:23,528: DEBUG/PoolWorker-1] Initializing MainLoop()
[2012-04-30 22:14:25,059: DEBUG/PoolWorker-1] __discovered, data: {'_timeoutid': 0, 'audiocaps': <GstCaps at 0x7fb958118700>, 'audiodepth': 0, 'videowidth': 480, 'audiofloat': True, 'dbin': <__main__.GstDecodeBin object (decodebin1) at 0x2e31c80>, '_nomorepads': True, 'audiorate': 44100, 'audiochannels': 2, 'audiowidth': 32, 'videoheight': 360, 'tags': {'encoder-version': 0, 'application-name': u'ffmpeg2theora-0.27', 'audio-codec': u'Vorbis', 'nominal-bitrate': 80000, 'video-codec': u'Theora', 'encoder': u'Xiph.Org libVorbis I 20100325 (Everywhere)', 'extended-comment': u'SOURCE_OSHASH=9eee0b38ea94d567', 'bitrate': 80000, 'container-format': u'Ogg'}, 'bus': <gst.Bus object (bus4) at 0x2e45410>, 'finished': True, 'typefind': <__main__.GstTypeFindElement object (typefind) at 0x2e31cd0>, 'videorate': <gst.Fraction 2994/125>, 'src': <__main__.GstFileSrc object (filesrc1) at 0x2e31140>, 'mimetype': 'application/ogg', 'audiolength': 84001336005L, 'videolength': 84001336005L, 'otherstreams': [], 'sinknumber': 2, '_max_interleave': 1.0, '_success': True, 'videocaps': <GstCaps at 0x7fb95c030b80>, '_timeout': 3000, 'is_audio': True, 'is_video': True}
[2012-04-30 22:14:25,087: DEBUG/PoolWorker-1] linking elements
[2012-04-30 22:14:25,319: INFO/PoolWorker-1] Transcoding...
[2012-04-30 22:14:25,319: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from filesink at 0x321f700>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,349: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from progressreport at 0x321f680>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,349: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from webmmux at 0x321f990>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,349: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from vorbisenc at 0x321fa10>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,349: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from vp8enc at 0x321fa90>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,349: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from audiocapsfilter at 0x321fb10>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,349: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from capsfilter at 0x321fb90>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,350: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioconvert at 0x321fc10>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,372: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from videoscale at 0x321fc90>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,372: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from audiorate at 0x321fd10>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,373: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from ffmpegcolorspace at 0x321fd90>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,373: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioqueue at 0x321fe10>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,373: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from videorate at 0x321fe90>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,373: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from videoqueue at 0x321ff10>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,373: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from typefind at 0x322e830>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,373: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from decoder at 0x322e8b0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,373: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from filesrc at 0x322e930>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,374: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_PLAYING; from VideoTranscoderPipeline at 0x322e9b0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,374: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from progressreport at 0x322eab0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,374: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from webmmux at 0x322eb30>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,374: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from vorbisenc at 0x322ebb0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,374: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from vp8enc at 0x322ec30>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,374: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from audiocapsfilter at 0x322ecb0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,374: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from capsfilter at 0x322ed30>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,374: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioconvert at 0x322edb0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,374: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from videoscale at 0x322ee30>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,375: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from audiorate at 0x322eeb0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,375: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from ffmpegcolorspace at 0x322ef30>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,375: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_CREATE, owner=(GstElement)"\(GstQueue\)\ audioqueue", object=(GstTask)"\(GstTask\)\ audioqueue:src"; from src at 0x322f820>, <flags GST_MESSAGE_STREAM_STATUS of type GstMessageType>)
[2012-04-30 22:14:25,375: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioqueue at 0x322f8a0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,375: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from videorate at 0x322f920>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,375: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_CREATE, owner=(GstElement)"\(GstQueue\)\ videoqueue", object=(GstTask)"\(GstTask\)\ videoqueue:src"; from src at 0x322f9a0>, <flags GST_MESSAGE_STREAM_STATUS of type GstMessageType>)
[2012-04-30 22:14:25,375: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstQueue\)\ audioqueue", object=(GstTask)"\(GstTask\)\ audioqueue:src"; from src at 0x7fb95c029680>, <flags GST_MESSAGE_STREAM_STATUS of type GstMessageType>)
[2012-04-30 22:14:25,375: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from videoqueue at 0x322fa20>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,376: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstQueue\)\ videoqueue", object=(GstTask)"\(GstTask\)\ videoqueue:src"; from src at 0x7fb964003940>, <flags GST_MESSAGE_STREAM_STATUS of type GstMessageType>)
[2012-04-30 22:14:25,376: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from oggdemux1 at 0x322fe20>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,376: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_CREATE, owner=(GstElement)"\(GstOggDemux\)\ oggdemux1", object=(GstTask)"\(GstTask\)\ oggdemux1:sink"; from sink at 0x322fda0>, <flags GST_MESSAGE_STREAM_STATUS of type GstMessageType>)
[2012-04-30 22:14:25,376: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from oggdemux1 at 0x322fd20>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,376: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from typefind at 0x322fca0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,376: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from filesrc at 0x322fc20>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,376: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstOggDemux\)\ oggdemux1", object=(GstTask)"\(GstTask\)\ oggdemux1:sink"; from sink at 0x7fb95c001880>, <flags GST_MESSAGE_STREAM_STATUS of type GstMessageType>)
[2012-04-30 22:14:25,406: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_PAUSED; from multiqueue0 at 0x7fb95c001900>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,406: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from multiqueue0 at 0x7fb964001c20>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,414: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstMultiQueue\)\ multiqueue0", object=(GstTask)"\(GstTask\)\ multiqueue0:src0"; from src0 at 0x7fb964001e20>, <flags GST_MESSAGE_STREAM_STATUS of type GstMessageType>)
[2012-04-30 22:14:25,414: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from theoradec1 at 0x322fba0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,414: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from theoradec1 at 0x321fe90>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,414: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from vorbisdec1 at 0x321fd90>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,415: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from vorbisdec1 at 0x322e930>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,415: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message taglist, container-format=(string)Ogg; from oggdemux1 at 0x322eab0>, <flags GST_MESSAGE_TAG of type GstMessageType>)
[2012-04-30 22:14:25,444: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message taglist, application-name=(string)ffmpeg2theora-0.27, extended-comment=(string)"SOURCE_OSHASH\=9eee0b38ea94d567", encoder=(string)"Xiph.Org\ libtheora\ 1.1\ 20090822\ \(Thusnelda\)", source-pad=(GstPad)"\(GstOggPad\)\ serial_11b3f52e"; from oggdemux1 at 0x322eb30>, <flags GST_MESSAGE_TAG of type GstMessageType>)
[2012-04-30 22:14:25,445: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message taglist, application-name=(string)ffmpeg2theora-0.27, extended-comment=(string)"SOURCE_OSHASH\=9eee0b38ea94d567", encoder=(string)"Xiph.Org\ libVorbis\ I\ 20100325\ \(Everywhere\)", encoder-version=(uint)0, nominal-bitrate=(uint)80000, bitrate=(uint)80000, source-pad=(GstPad)"\(GstOggPad\)\ serial_1f55b629"; from oggdemux1 at 0x322ebb0>, <flags GST_MESSAGE_TAG of type GstMessageType>)
[2012-04-30 22:14:25,445: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstMultiQueue\)\ multiqueue0", object=(GstTask)"\(GstTask\)\ multiqueue0:src1"; from src1 at 0x7fb950002540>, <flags GST_MESSAGE_STREAM_STATUS of type GstMessageType>)
[2012-04-30 22:14:25,496: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from decoder at 0x7fb964001da0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:25,496: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message taglist, application-name=(string)ffmpeg2theora-0.27, extended-comment=(string)"SOURCE_OSHASH\=9eee0b38ea94d567", encoder=(string)"Xiph.Org\ libtheora\ 1.1\ 20090822\ \(Thusnelda\)", encoder-version=(uint)3, video-codec=(string)Theora, source-pad=(GstPad)"\(GstPad\)\ src"; from theoradec1 at 0x7fb964001d20>, <flags GST_MESSAGE_TAG of type GstMessageType>)
[2012-04-30 22:14:25,496: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message taglist, application-name=(string)ffmpeg2theora-0.27, extended-comment=(string)"SOURCE_OSHASH\=9eee0b38ea94d567", encoder=(string)"Xiph.Org\ libVorbis\ I\ 20100325\ \(Everywhere\)", encoder-version=(uint)0, audio-codec=(string)Vorbis, nominal-bitrate=(uint)80000, bitrate=(uint)80000; from vorbisdec1 at 0x7fb9500025c0>, <flags GST_MESSAGE_TAG of type GstMessageType>)
[2012-04-30 22:14:25,496: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message taglist, audio-codec=(string)Vorbis; from vorbisdec1 at 0x7fb950002640>, <flags GST_MESSAGE_TAG of type GstMessageType>)
[2012-04-30 22:14:25,892: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message (none) from vorbisenc at 0x7fb95c029700>, <flags GST_MESSAGE_LATENCY of type GstMessageType>)
[2012-04-30 22:14:25,910: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message taglist, codec=(string)Vorbis, audio-codec=(string)Vorbis, source-pad=(GstPad)"\(GstPad\)\ src"; from vorbisenc at 0x7fb950002040>, <flags GST_MESSAGE_TAG of type GstMessageType>)
[2012-04-30 22:14:25,958: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message (none) from vp8enc at 0x7fb9640039c0>, <flags GST_MESSAGE_LATENCY of type GstMessageType>)
[2012-04-30 22:14:26,280: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)0, percent-double=(double)0, current=(gint64)0, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964003a40>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:26,328: INFO/PoolWorker-1] 0% done...
[2012-04-30 22:14:26,328: DEBUG/PoolWorker-1] {'current': 0, 'total': 84, 'percent': 0, 'percent-double': 0.0, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:26,351: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from filesink at 0x7fb964003ac0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,352: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_PLAYING; from VideoTranscoderPipeline at 0x7fb964003c40>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,352: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message (none) from VideoTranscoderPipeline at 0x7fb964003bc0>, <flags GST_MESSAGE_ASYNC_DONE of type GstMessageType>)
[2012-04-30 22:14:26,352: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageNewClock, clock=(GstClock)"\(GstSystemClock\)\ GstSystemClock"; from VideoTranscoderPipeline at 0x322fba0>, <flags GST_MESSAGE_NEW_CLOCK of type GstMessageType>)
[2012-04-30 22:14:26,352: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from filesink at 0x7fb964001e20>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,353: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from progressreport at 0x7fb964001c20>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,353: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from webmmux at 0x7fb95c001900>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,353: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from vorbisenc at 0x7fb95c001880>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,354: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from vp8enc at 0x322fc20>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,354: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from audiocapsfilter at 0x322fca0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,354: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from capsfilter at 0x322fd20>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,354: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioconvert at 0x322fda0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,355: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from videoscale at 0x322fe20>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,355: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from audiorate at 0x7fb964003940>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,355: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from ffmpegcolorspace at 0x322fa20>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,355: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioqueue at 0x7fb95c029680>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,356: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from videorate at 0x322f9a0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,356: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from videoqueue at 0x322f920>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,356: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from vorbisdec1 at 0x322f8a0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,356: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from theoradec1 at 0x322f820>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,356: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from multiqueue0 at 0x322ef30>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,357: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from oggdemux1 at 0x322eeb0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,357: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from typefind at 0x322ee30>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,357: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from decoder at 0x322edb0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,357: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from filesrc at 0x322ed30>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:26,358: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from VideoTranscoderPipeline at 0x322ecb0>, <flags GST_MESSAGE_STATE_CHANGED of type GstMessageType>)
[2012-04-30 22:14:27,005: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)0, percent-double=(double)0, current=(gint64)0, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964003b40>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:27,006: INFO/PoolWorker-1] 0% done...
[2012-04-30 22:14:27,006: DEBUG/PoolWorker-1] {'current': 0, 'total': 84, 'percent': 0, 'percent-double': 0.0, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:28,043: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)1, percent-double=(double)1.1904761904761905, current=(gint64)1, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x2f6e590>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:28,043: INFO/PoolWorker-1] 1% done...
[2012-04-30 22:14:28,044: DEBUG/PoolWorker-1] {'current': 1, 'total': 84, 'percent': 1, 'percent-double': 1.1904761904761905, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:29,005: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)1, percent-double=(double)1.1904761904761905, current=(gint64)1, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322ea30>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:29,006: INFO/PoolWorker-1] 1% done...
[2012-04-30 22:14:29,006: DEBUG/PoolWorker-1] {'current': 1, 'total': 84, 'percent': 1, 'percent-double': 1.1904761904761905, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:30,001: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)2, percent-double=(double)2.3809523809523809, current=(gint64)2, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb95c001e80>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:30,002: INFO/PoolWorker-1] 2% done...
[2012-04-30 22:14:30,002: DEBUG/PoolWorker-1] {'current': 2, 'total': 84, 'percent': 2, 'percent-double': 2.380952380952381, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:31,045: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)3, percent-double=(double)3.5714285714285716, current=(gint64)3, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322fc20>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:31,046: INFO/PoolWorker-1] 3% done...
[2012-04-30 22:14:31,046: DEBUG/PoolWorker-1] {'current': 3, 'total': 84, 'percent': 3, 'percent-double': 3.5714285714285716, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:32,010: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)3, percent-double=(double)3.5714285714285716, current=(gint64)3, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb95c001880>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:32,011: INFO/PoolWorker-1] 3% done...
[2012-04-30 22:14:32,011: DEBUG/PoolWorker-1] {'current': 3, 'total': 84, 'percent': 3, 'percent-double': 3.5714285714285716, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:33,004: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)4, percent-double=(double)4.7619047619047619, current=(gint64)4, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb95c001900>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:33,004: INFO/PoolWorker-1] 4% done...
[2012-04-30 22:14:33,004: DEBUG/PoolWorker-1] {'current': 4, 'total': 84, 'percent': 4, 'percent-double': 4.761904761904762, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:34,008: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)7, percent-double=(double)7.1428571428571432, current=(gint64)6, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964001c20>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:34,009: INFO/PoolWorker-1] 7% done...
[2012-04-30 22:14:34,009: DEBUG/PoolWorker-1] {'current': 6, 'total': 84, 'percent': 7, 'percent-double': 7.142857142857143, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:35,020: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)8, percent-double=(double)8.3333333333333339, current=(gint64)7, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964001e20>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:35,021: INFO/PoolWorker-1] 8% done...
[2012-04-30 22:14:35,021: DEBUG/PoolWorker-1] {'current': 7, 'total': 84, 'percent': 8, 'percent-double': 8.333333333333334, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:36,018: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)10, percent-double=(double)10.714285714285714, current=(gint64)9, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322fba0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:36,018: INFO/PoolWorker-1] 10% done...
[2012-04-30 22:14:36,018: DEBUG/PoolWorker-1] {'current': 9, 'total': 84, 'percent': 10, 'percent-double': 10.714285714285714, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:37,010: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)11, percent-double=(double)11.904761904761905, current=(gint64)10, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964003bc0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:37,010: INFO/PoolWorker-1] 11% done...
[2012-04-30 22:14:37,010: DEBUG/PoolWorker-1] {'current': 10, 'total': 84, 'percent': 11, 'percent-double': 11.904761904761905, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:38,046: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)13, percent-double=(double)13.095238095238095, current=(gint64)11, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964003c40>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:38,046: INFO/PoolWorker-1] 13% done...
[2012-04-30 22:14:38,046: DEBUG/PoolWorker-1] {'current': 11, 'total': 84, 'percent': 13, 'percent-double': 13.095238095238095, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:39,013: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)14, percent-double=(double)14.285714285714286, current=(gint64)12, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964003ac0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:39,014: INFO/PoolWorker-1] 14% done...
[2012-04-30 22:14:39,014: DEBUG/PoolWorker-1] {'current': 12, 'total': 84, 'percent': 14, 'percent-double': 14.285714285714286, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:40,047: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)15, percent-double=(double)15.476190476190476, current=(gint64)13, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964003a40>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:40,047: INFO/PoolWorker-1] 15% done...
[2012-04-30 22:14:40,048: DEBUG/PoolWorker-1] {'current': 13, 'total': 84, 'percent': 15, 'percent-double': 15.476190476190476, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:41,063: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)15, percent-double=(double)15.476190476190476, current=(gint64)13, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb9640039c0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:41,063: INFO/PoolWorker-1] 15% done...
[2012-04-30 22:14:41,063: DEBUG/PoolWorker-1] {'current': 13, 'total': 84, 'percent': 15, 'percent-double': 15.476190476190476, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:42,054: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)16, percent-double=(double)16.666666666666668, current=(gint64)14, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb950002040>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:42,054: INFO/PoolWorker-1] 16% done...
[2012-04-30 22:14:42,054: DEBUG/PoolWorker-1] {'current': 14, 'total': 84, 'percent': 16, 'percent-double': 16.666666666666668, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:43,004: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)17, percent-double=(double)17.857142857142858, current=(gint64)15, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322ef30>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:43,005: INFO/PoolWorker-1] 17% done...
[2012-04-30 22:14:43,005: DEBUG/PoolWorker-1] {'current': 15, 'total': 84, 'percent': 17, 'percent-double': 17.857142857142858, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:44,059: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)19, percent-double=(double)19.047619047619047, current=(gint64)16, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322f820>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:44,059: INFO/PoolWorker-1] 19% done...
[2012-04-30 22:14:44,059: DEBUG/PoolWorker-1] {'current': 16, 'total': 84, 'percent': 19, 'percent-double': 19.047619047619047, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:45,053: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)19, percent-double=(double)19.047619047619047, current=(gint64)16, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322f8a0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:45,054: INFO/PoolWorker-1] 19% done...
[2012-04-30 22:14:45,054: DEBUG/PoolWorker-1] {'current': 16, 'total': 84, 'percent': 19, 'percent-double': 19.047619047619047, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:46,044: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)20, percent-double=(double)20.238095238095237, current=(gint64)17, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322f920>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:46,044: INFO/PoolWorker-1] 20% done...
[2012-04-30 22:14:46,044: DEBUG/PoolWorker-1] {'current': 17, 'total': 84, 'percent': 20, 'percent-double': 20.238095238095237, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:47,026: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)20, percent-double=(double)20.238095238095237, current=(gint64)17, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322f9a0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:47,026: INFO/PoolWorker-1] 20% done...
[2012-04-30 22:14:47,026: DEBUG/PoolWorker-1] {'current': 17, 'total': 84, 'percent': 20, 'percent-double': 20.238095238095237, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:48,001: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)21, percent-double=(double)21.428571428571427, current=(gint64)18, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb95c029680>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:48,001: INFO/PoolWorker-1] 21% done...
[2012-04-30 22:14:48,002: DEBUG/PoolWorker-1] {'current': 18, 'total': 84, 'percent': 21, 'percent-double': 21.428571428571427, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:49,028: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)22, percent-double=(double)22.61904761904762, current=(gint64)19, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322fa20>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:49,028: INFO/PoolWorker-1] 22% done...
[2012-04-30 22:14:49,029: DEBUG/PoolWorker-1] {'current': 19, 'total': 84, 'percent': 22, 'percent-double': 22.61904761904762, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:50,018: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)23, percent-double=(double)23.80952380952381, current=(gint64)20, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964003940>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:50,019: INFO/PoolWorker-1] 23% done...
[2012-04-30 22:14:50,019: DEBUG/PoolWorker-1] {'current': 20, 'total': 84, 'percent': 23, 'percent-double': 23.80952380952381, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:51,028: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)25, percent-double=(double)25, current=(gint64)21, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322fe20>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:51,028: INFO/PoolWorker-1] 25% done...
[2012-04-30 22:14:51,029: DEBUG/PoolWorker-1] {'current': 21, 'total': 84, 'percent': 25, 'percent-double': 25.0, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:52,010: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)26, percent-double=(double)26.19047619047619, current=(gint64)22, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322fda0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:52,010: INFO/PoolWorker-1] 26% done...
[2012-04-30 22:14:52,010: DEBUG/PoolWorker-1] {'current': 22, 'total': 84, 'percent': 26, 'percent-double': 26.19047619047619, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:53,007: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)27, percent-double=(double)27.38095238095238, current=(gint64)23, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322fd20>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:53,007: INFO/PoolWorker-1] 27% done...
[2012-04-30 22:14:53,007: DEBUG/PoolWorker-1] {'current': 23, 'total': 84, 'percent': 27, 'percent-double': 27.38095238095238, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:54,005: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)28, percent-double=(double)28.571428571428573, current=(gint64)24, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322fca0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:54,006: INFO/PoolWorker-1] 28% done...
[2012-04-30 22:14:54,006: DEBUG/PoolWorker-1] {'current': 24, 'total': 84, 'percent': 28, 'percent-double': 28.571428571428573, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:55,038: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)29, percent-double=(double)29.761904761904763, current=(gint64)25, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb95c001900>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:55,039: INFO/PoolWorker-1] 29% done...
[2012-04-30 22:14:55,039: DEBUG/PoolWorker-1] {'current': 25, 'total': 84, 'percent': 29, 'percent-double': 29.761904761904763, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:56,042: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)30, percent-double=(double)30.952380952380953, current=(gint64)26, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb95c001880>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:56,042: INFO/PoolWorker-1] 30% done...
[2012-04-30 22:14:56,042: DEBUG/PoolWorker-1] {'current': 26, 'total': 84, 'percent': 30, 'percent-double': 30.952380952380953, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:57,060: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)32, percent-double=(double)32.142857142857146, current=(gint64)27, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322fc20>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:57,061: INFO/PoolWorker-1] 32% done...
[2012-04-30 22:14:57,061: DEBUG/PoolWorker-1] {'current': 27, 'total': 84, 'percent': 32, 'percent-double': 32.142857142857146, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:58,001: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)33, percent-double=(double)33.333333333333336, current=(gint64)28, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb95c001e80>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:58,002: INFO/PoolWorker-1] 33% done...
[2012-04-30 22:14:58,002: DEBUG/PoolWorker-1] {'current': 28, 'total': 84, 'percent': 33, 'percent-double': 33.333333333333336, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:14:59,024: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)34, percent-double=(double)34.523809523809526, current=(gint64)29, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322ea30>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:14:59,024: INFO/PoolWorker-1] 34% done...
[2012-04-30 22:14:59,024: DEBUG/PoolWorker-1] {'current': 29, 'total': 84, 'percent': 34, 'percent-double': 34.523809523809526, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:00,068: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)34, percent-double=(double)34.523809523809526, current=(gint64)29, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x2f6e590>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:00,068: INFO/PoolWorker-1] 34% done...
[2012-04-30 22:15:00,068: DEBUG/PoolWorker-1] {'current': 29, 'total': 84, 'percent': 34, 'percent-double': 34.523809523809526, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:01,025: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)35, percent-double=(double)35.714285714285715, current=(gint64)30, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964003b40>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:01,026: INFO/PoolWorker-1] 35% done...
[2012-04-30 22:15:01,026: DEBUG/PoolWorker-1] {'current': 30, 'total': 84, 'percent': 35, 'percent-double': 35.714285714285715, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:02,008: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)36, percent-double=(double)36.904761904761905, current=(gint64)31, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322ecb0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:02,008: INFO/PoolWorker-1] 36% done...
[2012-04-30 22:15:02,008: DEBUG/PoolWorker-1] {'current': 31, 'total': 84, 'percent': 36, 'percent-double': 36.904761904761905, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:03,032: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)39, percent-double=(double)39.285714285714285, current=(gint64)33, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322ed30>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:03,032: INFO/PoolWorker-1] 39% done...
[2012-04-30 22:15:03,033: DEBUG/PoolWorker-1] {'current': 33, 'total': 84, 'percent': 39, 'percent-double': 39.285714285714285, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:04,004: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)39, percent-double=(double)39.285714285714285, current=(gint64)33, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322edb0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:04,004: INFO/PoolWorker-1] 39% done...
[2012-04-30 22:15:04,005: DEBUG/PoolWorker-1] {'current': 33, 'total': 84, 'percent': 39, 'percent-double': 39.285714285714285, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:05,017: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)40, percent-double=(double)40.476190476190474, current=(gint64)34, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322ee30>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:05,017: INFO/PoolWorker-1] 40% done...
[2012-04-30 22:15:05,017: DEBUG/PoolWorker-1] {'current': 34, 'total': 84, 'percent': 40, 'percent-double': 40.476190476190474, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:06,027: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)40, percent-double=(double)40.476190476190474, current=(gint64)34, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322eeb0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:06,028: INFO/PoolWorker-1] 40% done...
[2012-04-30 22:15:06,028: DEBUG/PoolWorker-1] {'current': 34, 'total': 84, 'percent': 40, 'percent-double': 40.476190476190474, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:07,107: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)41, percent-double=(double)41.666666666666664, current=(gint64)35, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322f8a0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:07,107: INFO/PoolWorker-1] 41% done...
[2012-04-30 22:15:07,107: DEBUG/PoolWorker-1] {'current': 35, 'total': 84, 'percent': 41, 'percent-double': 41.666666666666664, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:08,062: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)42, percent-double=(double)42.857142857142854, current=(gint64)36, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322f820>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:08,063: INFO/PoolWorker-1] 42% done...
[2012-04-30 22:15:08,064: DEBUG/PoolWorker-1] {'current': 36, 'total': 84, 'percent': 42, 'percent-double': 42.857142857142854, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:09,008: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)42, percent-double=(double)42.857142857142854, current=(gint64)36, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322ef30>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:09,009: INFO/PoolWorker-1] 42% done...
[2012-04-30 22:15:09,009: DEBUG/PoolWorker-1] {'current': 36, 'total': 84, 'percent': 42, 'percent-double': 42.857142857142854, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:10,012: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)44, percent-double=(double)44.047619047619051, current=(gint64)37, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb950002040>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:10,012: INFO/PoolWorker-1] 44% done...
[2012-04-30 22:15:10,013: DEBUG/PoolWorker-1] {'current': 37, 'total': 84, 'percent': 44, 'percent-double': 44.04761904761905, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:11,015: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)45, percent-double=(double)45.238095238095241, current=(gint64)38, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb9640039c0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:11,016: INFO/PoolWorker-1] 45% done...
[2012-04-30 22:15:11,016: DEBUG/PoolWorker-1] {'current': 38, 'total': 84, 'percent': 45, 'percent-double': 45.23809523809524, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:12,009: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)47, percent-double=(double)47.61904761904762, current=(gint64)40, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964003a40>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:12,009: INFO/PoolWorker-1] 47% done...
[2012-04-30 22:15:12,009: DEBUG/PoolWorker-1] {'current': 40, 'total': 84, 'percent': 47, 'percent-double': 47.61904761904762, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:13,042: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)48, percent-double=(double)48.80952380952381, current=(gint64)41, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964003ac0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:13,042: INFO/PoolWorker-1] 48% done...
[2012-04-30 22:15:13,042: DEBUG/PoolWorker-1] {'current': 41, 'total': 84, 'percent': 48, 'percent-double': 48.80952380952381, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:14,061: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)50, percent-double=(double)50, current=(gint64)42, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964003c40>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:14,061: INFO/PoolWorker-1] 50% done...
[2012-04-30 22:15:14,062: DEBUG/PoolWorker-1] {'current': 42, 'total': 84, 'percent': 50, 'percent-double': 50.0, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:15,054: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)51, percent-double=(double)51.19047619047619, current=(gint64)43, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964003bc0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:15,055: INFO/PoolWorker-1] 51% done...
[2012-04-30 22:15:15,055: DEBUG/PoolWorker-1] {'current': 43, 'total': 84, 'percent': 51, 'percent-double': 51.19047619047619, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:16,097: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)51, percent-double=(double)51.19047619047619, current=(gint64)43, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322fba0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:16,098: INFO/PoolWorker-1] 51% done...
[2012-04-30 22:15:16,098: DEBUG/PoolWorker-1] {'current': 43, 'total': 84, 'percent': 51, 'percent-double': 51.19047619047619, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:17,045: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)52, percent-double=(double)52.38095238095238, current=(gint64)44, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964001e20>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:17,046: INFO/PoolWorker-1] 52% done...
[2012-04-30 22:15:17,046: DEBUG/PoolWorker-1] {'current': 44, 'total': 84, 'percent': 52, 'percent-double': 52.38095238095238, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:18,000: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)53, percent-double=(double)53.571428571428569, current=(gint64)45, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb9500020c0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:18,001: INFO/PoolWorker-1] 53% done...
[2012-04-30 22:15:18,001: DEBUG/PoolWorker-1] {'current': 45, 'total': 84, 'percent': 53, 'percent-double': 53.57142857142857, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:19,000: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)55, percent-double=(double)55.952380952380949, current=(gint64)47, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb950002140>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:19,001: INFO/PoolWorker-1] 55% done...
[2012-04-30 22:15:19,001: DEBUG/PoolWorker-1] {'current': 47, 'total': 84, 'percent': 55, 'percent-double': 55.95238095238095, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:20,003: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)58, percent-double=(double)58.333333333333336, current=(gint64)49, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb964001c20>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:20,003: INFO/PoolWorker-1] 58% done...
[2012-04-30 22:15:20,003: DEBUG/PoolWorker-1] {'current': 49, 'total': 84, 'percent': 58, 'percent-double': 58.333333333333336, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:21,035: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)59, percent-double=(double)59.523809523809526, current=(gint64)50, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322fc20>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:21,035: INFO/PoolWorker-1] 59% done...
[2012-04-30 22:15:21,035: DEBUG/PoolWorker-1] {'current': 50, 'total': 84, 'percent': 59, 'percent-double': 59.523809523809526, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:22,005: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)60, percent-double=(double)60.714285714285715, current=(gint64)51, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb95c001880>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:22,006: INFO/PoolWorker-1] 60% done...
[2012-04-30 22:15:22,006: DEBUG/PoolWorker-1] {'current': 51, 'total': 84, 'percent': 60, 'percent-double': 60.714285714285715, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:23,033: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)61, percent-double=(double)61.904761904761905, current=(gint64)52, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb95c001900>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:23,033: INFO/PoolWorker-1] 61% done...
[2012-04-30 22:15:23,034: DEBUG/PoolWorker-1] {'current': 52, 'total': 84, 'percent': 61, 'percent-double': 61.904761904761905, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:24,002: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)63, percent-double=(double)63.095238095238095, current=(gint64)53, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322fca0>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:24,002: INFO/PoolWorker-1] 63% done...
[2012-04-30 22:15:24,003: DEBUG/PoolWorker-1] {'current': 53, 'total': 84, 'percent': 63, 'percent-double': 63.095238095238095, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:25,000: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)64, percent-double=(double)64.285714285714292, current=(gint64)54, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x7fb95c001d80>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:25,000: INFO/PoolWorker-1] 64% done...
[2012-04-30 22:15:25,001: DEBUG/PoolWorker-1] {'current': 54, 'total': 84, 'percent': 64, 'percent-double': 64.28571428571429, 'format': <enum GST_FORMAT_TIME of type GstFormat>}
[2012-04-30 22:15:26,027: DEBUG/PoolWorker-1] (<gst.Bus object (bus7) at 0x2e455a0>, <gst.Message progress, percent=(int)65, percent-double=(double)65.476190476190482, current=(gint64)55, total=(gint64)84, format=(GstFormat)GST_FORMAT_TIME; from progressreport at 0x322fd20>, <flags GST_MESSAGE_ELEMENT of type GstMessageType>)
[2012-04-30 22:15:26,027: INFO/PoolWorker-1] 65% done...
[2012-04-30 22:15:26,027: DEBUG/PoolWorker-1] {'current': 55, 'total': 84, 'percent': 

Subtickets

Attachments (1)

bug444.log (79.7 KB) - added by Jorge Araya Navarro 8 years ago.
the complete log for this bug

Download all attachments as: .zip

Change History (3)

Changed 8 years ago by Jorge Araya Navarro

Attachment: bug444.log added

the complete log for this bug

comment:1 Changed 8 years ago by Jorge Araya Navarro

Owner: set to Jorge Araya Navarro
Status: newaccepted

I'm going to take this one!

comment:2 Changed 8 years ago by Jorge Araya Navarro

Resolution: worksforme
Status: acceptedclosed

weird, but, in 0.3.1 this bugs doesn't appears anymore!
and I didn't nothing...

Note: See TracTickets for help on using tickets.