xpra icon
Bug tracker and wiki

This bug tracker and wiki are being discontinued
please use https://github.com/Xpra-org/xpra instead.


Ticket #379: xpraticket379_clientlogs32_edited.txt

File xpraticket379_clientlogs32_edited.txt, 41.0 KB (added by alas, 8 years ago)

osx r3869 ran for many minutes before speaker re-set

Line 
1** Message: pygobject_register_sinkfunc is deprecated (GstObject)
2xpra client version 0.10.0
3
4** (Xpra:4434): WARNING **: Trying to register gtype 'GMountMountFlags' as enum when in fact it is of type 'GFlags'
5
6** (Xpra:4434): WARNING **: Trying to register gtype 'GDriveStartFlags' as enum when in fact it is of type 'GFlags'
7
8** (Xpra:4434): WARNING **: Trying to register gtype 'GSocketMsgFlags' as enum when in fact it is of type 'GFlags'
92013-07-16 13:03:38,837 found rencode version 1.0.2
102013-07-16 13:03:38,851 importing ('Keyboard',) from xpra.platform.darwin.keyboard (required=True)
112013-07-16 13:03:38,851 Keyboard=<class 'xpra.platform.darwin.keyboard.Keyboard'>
12
13
14
15
162013-07-16 13:03:58,322 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 16692244897L}, level=130
172013-07-16 13:03:58,323 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
182013-07-16 13:03:58,342 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 16718367346L}, level=156
192013-07-16 13:03:58,344 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
202013-07-16 13:03:58,383 handle_key_action(<ClientWindow object at 0x4fc350 (xpra+client+gtk2+client_window+ClientWindow at 0xa6a8b8)>, <GTKKeyEvent object, contents: {'modifiers': [], 'group': 0, 'string': '\r', 'keyname': 'Return', 'pressed': False, 'keyval': 65293, 'keycode': 36}>) wid=4
212013-07-16 13:03:58,383 send_key_action(4, <GTKKeyEvent object, contents: {'modifiers': [], 'group': 0, 'string': '\r', 'keyname': 'Return', 'pressed': False, 'keyval': 65293, 'keycode': 36}>)
222013-07-16 13:03:58,384 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 16744489795L}, level=130
232013-07-16 13:03:58,385 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
242013-07-16 13:03:58,393 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 16770612244L}, level=156
252013-07-16 13:03:58,394 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
262013-07-16 13:03:58,401 update_metadata({'title': 'Spikes Browser 1.999.08 - '})
272013-07-16 13:03:58,402 set_modal(False) swallowed
282013-07-16 13:03:58,402 set_alpha()
292013-07-16 13:03:58,402 set_fullscreen(False)
302013-07-16 13:03:58,404 update_metadata({'title': 'Spikes Browser 1.999.08 - '})
312013-07-16 13:03:58,405 set_modal(False) swallowed
322013-07-16 13:03:58,405 set_alpha()
332013-07-16 13:03:58,405 set_fullscreen(False)
342013-07-16 13:03:58,406 update_metadata({'title': "Spikes Browser 1.999.08 - professor elemental i'm british - YouTube"})
352013-07-16 13:03:58,407 set_modal(False) swallowed
362013-07-16 13:03:58,407 set_alpha()
372013-07-16 13:03:58,407 set_fullscreen(False)
382013-07-16 13:03:58,408 update_metadata({'title': "Spikes Browser 1.999.08 - professor elemental i'm british - YouTube"})
392013-07-16 13:03:58,408 set_modal(False) swallowed
402013-07-16 13:03:58,409 set_alpha()
412013-07-16 13:03:58,409 set_fullscreen(False)
422013-07-16 13:03:58,424 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 16796734693L}, level=156
432013-07-16 13:03:58,434 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
442013-07-16 13:03:58,452 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 16822857142L}, level=130
452013-07-16 13:03:58,453 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
462013-07-16 13:03:58,472 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 16848979591L}, level=156
472013-07-16 13:03:58,473 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
482013-07-16 13:03:58,503 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 16875102040L}, level=156
492013-07-16 13:03:58,504 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
502013-07-16 13:03:58,537 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 16901224489L}, level=130
512013-07-16 13:03:58,538 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
522013-07-16 13:03:58,552 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 16927346938L}, level=156
532013-07-16 13:03:58,563 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
542013-07-16 13:03:58,582 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 16953469387L}, level=156
552013-07-16 13:03:58,583 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
562013-07-16 13:03:58,602 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 16979591836L}, level=156
572013-07-16 13:03:58,603 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
582013-07-16 13:03:58,607 poll_UI_loop() last_UI_thread_time was 1.0 seconds ago, UI_blocked_sent=False
592013-07-16 13:03:58,614 UI_thread_wakeup()
602013-07-16 13:03:58,631 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 17005714285L}, level=156
612013-07-16 13:03:58,632 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
62
63
64
65
662013-07-16 13:12:43,176 sound sink: adding 522 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 541831836734L}, level=417
672013-07-16 13:12:43,178 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
682013-07-16 13:12:43,216 sound sink: adding 522 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 541857959183L}, level=417
692013-07-16 13:12:43,216 sound sink: adding 417 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 541884081632L}, level=417
702013-07-16 13:12:43,216 on_enough_data((<__main__.GstAppSrc object (src) at 0x166e3918>,))
712013-07-16 13:12:43,218 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
722013-07-16 13:12:43,239 sound sink: adding 626 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 541910204081L}, level=444
732013-07-16 13:12:43,240 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
742013-07-16 13:12:43,271 sound sink: adding 522 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 541936326530L}, level=444
752013-07-16 13:12:43,272 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
76
77
78
79
80
812013-07-16 13:13:00,830 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 559333877551L}, level=287
822013-07-16 13:13:00,831 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
832013-07-16 13:13:00,860 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122448, 'timestamp': 559360000000L}, level=287
842013-07-16 13:13:00,861 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
852013-07-16 13:13:00,880 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 559386122448L}, level=261
862013-07-16 13:13:00,882 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
872013-07-16 13:13:00,912 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 559412244897L}, level=287
882013-07-16 13:13:00,914 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
892013-07-16 13:13:00,939 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 559438367346L}, level=287
902013-07-16 13:13:00,940 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
912013-07-16 13:13:00,959 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 559464489795L}, level=261
922013-07-16 13:13:00,961 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
932013-07-16 13:13:00,987 update_metadata({'title': 'Spikes Browser 1.999.08 - '})
942013-07-16 13:13:00,988 set_modal(False) swallowed
952013-07-16 13:13:00,988 set_alpha()
962013-07-16 13:13:00,989 set_fullscreen(False)
972013-07-16 13:13:00,990 update_metadata({'title': 'Spikes Browser 1.999.08 - '})
982013-07-16 13:13:00,991 set_modal(False) swallowed
992013-07-16 13:13:00,991 set_alpha()
1002013-07-16 13:13:00,991 set_fullscreen(False)
1012013-07-16 13:13:00,991 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 559490612244L}, level=287
1022013-07-16 13:13:00,992 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
1032013-07-16 13:13:00,993 update_metadata({'title': 'Spikes Browser 1.999.08 - The Tweed Album - 03 - Nicknackatory - YouTube'})
1042013-07-16 13:13:00,994 set_modal(False) swallowed
1052013-07-16 13:13:00,994 set_alpha()
1062013-07-16 13:13:00,994 set_fullscreen(False)
1072013-07-16 13:13:00,996 update_metadata({'title': 'Spikes Browser 1.999.08 - The Tweed Album - 03 - Nicknackatory - YouTube'})
1082013-07-16 13:13:00,996 set_modal(False) swallowed
1092013-07-16 13:13:00,996 set_alpha()
1102013-07-16 13:13:00,996 set_fullscreen(False)
1112013-07-16 13:13:01,012 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 559516734693L}, level=287
1122013-07-16 13:13:01,013 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
1132013-07-16 13:13:01,039 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 559542857142L}, level=287
1142013-07-16 13:13:01,041 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
1152013-07-16 13:13:01,071 sound sink: adding 104 bytes to /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 559568979591L}, level=287
1162013-07-16 13:13:01,072 need_data: 4096 bytes in /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc)
117
118
119
120
121
1222013-07-16 13:13:35,066 sound sink: adding 417 bytes to /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 593763265306L, 'sequence': 23}, level=261
1232013-07-16 13:13:35,067 sound sink queue overrun: level=261
1242013-07-16 13:13:35,068 need_data: 4096 bytes in /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc)
1252013-07-16 13:13:35,087 focus-out-event for wid=4
1262013-07-16 13:13:35,088 _focus_change((<ClientWindow object at 0x4fc350 (xpra+client+gtk2+client_window+ClientWindow at 0xa6a8b8)>, <GParamBoolean 'has-toplevel-focus'>)) wid=4, has-toplevel-focus=False, _been_mapped=True
1272013-07-16 13:13:35,088 update_focus(4, False) _focused=4
1282013-07-16 13:13:35,088 send_focus(0)
1292013-07-16 13:13:35,122 sound sink: adding 417 bytes to /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 593789387755L, 'sequence': 23}, level=261
1302013-07-16 13:13:35,124 sound sink queue overrun: level=261
1312013-07-16 13:13:35,124 need_data: 4096 bytes in /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc)
1322013-07-16 13:13:35,145 sound sink: adding 417 bytes to /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 593815510204L, 'sequence': 23}, level=287
1332013-07-16 13:13:35,146 sound sink: adding 417 bytes to /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 593841632653L, 'sequence': 23}, level=287
1342013-07-16 13:13:35,147 need_data: 4096 bytes in /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc)
1352013-07-16 13:13:35,180 sound sink: adding 417 bytes to /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 593867755102L, 'sequence': 23}, level=339
1362013-07-16 13:13:35,181 need_data: 4096 bytes in /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc)
1372013-07-16 13:13:35,190 sound sink: adding 522 bytes to /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 593893877551L, 'sequence': 23}, level=365
1382013-07-16 13:13:35,191 need_data: 4096 bytes in /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc)
1392013-07-16 13:13:35,225 sound sink: adding 417 bytes to /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122448, 'timestamp': 593920000000L, 'sequence': 23}, level=391
1402013-07-16 13:13:35,226 need_data: 4096 bytes in /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc)
1412013-07-16 13:13:35,238 sound sink: adding 417 bytes to /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 593946122448L, 'sequence': 23}, level=417
1422013-07-16 13:13:35,240 need_data: 4096 bytes in /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc)
1432013-07-16 13:13:35,266 sound sink: adding 417 bytes to /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 593972244897L, 'sequence': 23}, level=444
1442013-07-16 13:13:35,268 need_data: 4096 bytes in /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc)
1452013-07-16 13:13:35,302 sound sink: adding 417 bytes to /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 593998367346L, 'sequence': 23}, level=470
1462013-07-16 13:13:35,303 sound sink queue overrun: level=470
1472013-07-16 13:13:35,304 re-starting speaker because of overrun
1482013-07-16 13:13:35,304 need_data: 4096 bytes in /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc)
1492013-07-16 13:13:35,304 sink_clean() sound_sink=<xpra.sound.sink.SoundSink object at 0x506a10>, server_sound_sequence=True
1502013-07-16 13:13:35,305 SoundPipeline.cleanup()
1512013-07-16 13:13:35,305 SoundPipeline.stop()
1522013-07-16 13:13:35,305 need_data: 4096 bytes in /GstPipeline:pipeline14/GstAppSrc:src (__main__.GstAppSrc)
1532013-07-16 13:13:35,333 SoundPipeline.stop() done
1542013-07-16 13:13:35,334 SoundPipeline.cleanup() done
1552013-07-16 13:13:35,334 ignoring sound data with old sequence number 23
1562013-07-16 13:13:35,348 starting mp3 sound sink
1572013-07-16 13:13:35,349 pipeline elements=['appsrc name=src max-bytes=512', 'mp3parse', 'mad', 'volume name=volume', 'audioconvert', 'audioresample', 'queue name=queue min-threshold-time=50000000 max-size-time=250000000 leaky=2', 'osxaudiosink']
1582013-07-16 13:13:35,349 pipeline=appsrc name=src max-bytes=512 ! mp3parse ! mad ! volume name=volume ! audioconvert ! audioresample ! queue name=queue min-threshold-time=50000000 max-size-time=250000000 leaky=2 ! osxaudiosink
1592013-07-16 13:13:35,379 SoundPipeline.start()
1602013-07-16 13:13:35,382 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
1612013-07-16 13:13:35,382 SoundPipeline.start() done
1622013-07-16 13:13:35,383 sound sink queue underrun: level=0
1632013-07-16 13:13:35,383 mp3 sound sink started
1642013-07-16 13:13:35,383 sound sink: adding 417 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594050612244L, 'sequence': 24}, level=0
1652013-07-16 13:13:35,383 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
1662013-07-16 13:13:35,383 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from osxaudiosink15 at 0xb0f118>
1672013-07-16 13:13:35,384 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from queue at 0xb0e950>
1682013-07-16 13:13:35,384 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioresample15 at 0x40c8b00>
1692013-07-16 13:13:35,384 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioconvert15 at 0xaf1cd8>
1702013-07-16 13:13:35,384 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from volume at 0xaf1ed8>
1712013-07-16 13:13:35,385 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from mad15 at 0xae9148>
1722013-07-16 13:13:35,385 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from mpegaudioparse15 at 0xb53ec8>
1732013-07-16 13:13:35,385 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from src at 0xae9248>
1742013-07-16 13:13:35,385 new-state=READY
1752013-07-16 13:13:35,386 sound: client_signalled_change(<XpraClient object at 0x3e92210 (xpra+client+gtk2+client+XpraClient at 0x9c7820)>) is_on=True
1762013-07-16 13:13:35,386 stream status: <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_CREATE, owner=(GstElement)"\(GstQueue\)\ queue", object=(GstTask)"\(GstTask\)\ queue:src"; from src at 0xc51048>
1772013-07-16 13:13:35,386 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from queue at 0xae9388>
1782013-07-16 13:13:35,386 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioresample15 at 0x40836a0>
1792013-07-16 13:13:35,387 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioconvert15 at 0x40836e0>
1802013-07-16 13:13:35,387 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from volume at 0xc51008>
1812013-07-16 13:13:35,387 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from mad15 at 0xc46f80>
1822013-07-16 13:13:35,387 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from mpegaudioparse15 at 0xc46f40>
1832013-07-16 13:13:35,388 stream status: <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_CREATE, owner=(GstElement)"\(GstAppSrc\)\ src", object=(GstTask)"\(GstTask\)\ src:src"; from src at 0xc46f00>
1842013-07-16 13:13:35,388 stream status: <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstAppSrc\)\ src", object=(GstTask)"\(GstTask\)\ src:src"; from src at 0xb53d88>
1852013-07-16 13:13:35,388 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from src at 0xc46ec0>
1862013-07-16 13:13:35,388 new-state=PAUSED
1872013-07-16 13:13:35,388 sound: client_signalled_change(<XpraClient object at 0x3e92210 (xpra+client+gtk2+client+XpraClient at 0x9c7820)>) is_on=True
1882013-07-16 13:13:35,389 <gst.Message GstMessageNewClock, clock=(GstClock)"\(GstSystemClock\)\ GstSystemClock"; from pipeline15 at 0xc46e80>
1892013-07-16 13:13:35,389 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from queue at 0xc46d40>
1902013-07-16 13:13:35,389 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioresample15 at 0xc46d80>
1912013-07-16 13:13:35,389 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioconvert15 at 0xc46dc0>
1922013-07-16 13:13:35,390 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from volume at 0xc46e00>
1932013-07-16 13:13:35,390 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from mad15 at 0xaf1c98>
1942013-07-16 13:13:35,390 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from mpegaudioparse15 at 0xae9288>
1952013-07-16 13:13:35,390 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from src at 0xb0e910>
1962013-07-16 13:13:35,391 stream status: <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstQueue\)\ queue", object=(GstTask)"\(GstTask\)\ queue:src"; from src at 0xb53d08>
1972013-07-16 13:13:35,391 sound sink: adding 417 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594076734693L, 'sequence': 24}, level=0
1982013-07-16 13:13:35,391 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
1992013-07-16 13:13:35,397 sound sink: adding 417 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594102857142L, 'sequence': 24}, level=0
2002013-07-16 13:13:35,397 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2012013-07-16 13:13:35,398 using audio codec: MPEG 1 Audio, Layer 3 (MP3)
2022013-07-16 13:13:35,398 duration changed: (<enum GST_FORMAT_TIME of type GstFormat>, -1L)
2032013-07-16 13:13:35,399 new bitrate: 128000
2042013-07-16 13:13:35,427 sound sink: adding 417 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594128979591L, 'sequence': 24}, level=0
2052013-07-16 13:13:35,434 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2062013-07-16 13:13:35,435 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from osxaudiosink15 at 0x40835a0>
2072013-07-16 13:13:35,435 <gst.Message (none) from pipeline15 at 0xb0f318>
2082013-07-16 13:13:35,435 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from osxaudiosink15 at 0xae9348>
2092013-07-16 13:13:35,436 new-state=PLAYING
2102013-07-16 13:13:35,436 sound: client_signalled_change(<XpraClient object at 0x3e92210 (xpra+client+gtk2+client+XpraClient at 0x9c7820)>) is_on=True
2112013-07-16 13:13:35,446 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594155102040L, 'sequence': 24}, level=0
2122013-07-16 13:13:35,448 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2132013-07-16 13:13:35,454 UI_thread_wakeup()
2142013-07-16 13:13:35,504 sound sink: adding 417 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594181224489L, 'sequence': 24}, level=78
2152013-07-16 13:13:35,506 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2162013-07-16 13:13:35,507 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594207346938L, 'sequence': 24}, level=104
2172013-07-16 13:13:35,508 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2182013-07-16 13:13:35,542 poll_UI_loop() last_UI_thread_time was 0.1 seconds ago, UI_blocked_sent=False
2192013-07-16 13:13:35,545 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594233469387L, 'sequence': 24}, level=130
2202013-07-16 13:13:35,546 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
221
222
223
224
2252013-07-16 13:13:35,580 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594285714285L, 'sequence': 24}, level=182
2262013-07-16 13:13:35,581 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2272013-07-16 13:13:35,608 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594311836734L, 'sequence': 24}, level=208
2282013-07-16 13:13:35,609 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2292013-07-16 13:13:35,610 duration changed: (<enum GST_FORMAT_TIME of type GstFormat>, -1L)
2302013-07-16 13:13:35,610 new bitrate: 119000
2312013-07-16 13:13:35,637 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594337959183L, 'sequence': 24}, level=235
2322013-07-16 13:13:35,638 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2332013-07-16 13:13:35,656 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594364081632L, 'sequence': 24}, level=261
2342013-07-16 13:13:35,657 sound sink queue overrun: level=261
2352013-07-16 13:13:35,658 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2362013-07-16 13:13:35,687 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594390204081L, 'sequence': 24}, level=208
2372013-07-16 13:13:35,688 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2382013-07-16 13:13:35,727 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594416326530L, 'sequence': 24}, level=235
2392013-07-16 13:13:35,728 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2402013-07-16 13:13:35,744 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 594442448979L, 'sequence': 24}, level=261
2412013-07-16 13:13:35,745 sound sink queue overrun: level=261
2422013-07-16 13:13:35,745 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
243
244
245
246
2472013-07-16 13:13:37,234 sound sink queue overrun: level=261
2482013-07-16 13:13:37,235 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2492013-07-16 13:13:37,263 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 595957551020L, 'sequence': 24}, level=261
2502013-07-16 13:13:37,264 sound sink queue overrun: level=261
2512013-07-16 13:13:37,264 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2522013-07-16 13:13:37,299 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 595983673469L, 'sequence': 24}, level=261
2532013-07-16 13:13:37,300 sound sink queue overrun: level=261
2542013-07-16 13:13:37,300 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2552013-07-16 13:13:37,302 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 596009795918L, 'sequence': 24}, level=261
2562013-07-16 13:13:37,303 sound sink queue overrun: level=261
2572013-07-16 13:13:37,303 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2582013-07-16 13:13:37,333 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 596035918367L, 'sequence': 24}, level=261
2592013-07-16 13:13:37,334 sound sink queue overrun: level=261
2602013-07-16 13:13:37,334 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2612013-07-16 13:13:37,361 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 596062040816L, 'sequence': 24}, level=261
2622013-07-16 13:13:37,363 sound sink queue overrun: level=261
2632013-07-16 13:13:37,363 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2642013-07-16 13:13:37,385 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 596088163265L, 'sequence': 24}, level=287
2652013-07-16 13:13:37,386 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
266
267
268
269
2702013-07-16 13:13:37,450 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 596140408163L, 'sequence': 24}, level=339
2712013-07-16 13:13:37,451 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2722013-07-16 13:13:37,460 UI_thread_wakeup()
2732013-07-16 13:13:37,462 sound sink: adding 365 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 596166530612L, 'sequence': 24}, level=365
2742013-07-16 13:13:37,464 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2752013-07-16 13:13:37,499 sound sink: adding 313 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 596192653061L, 'sequence': 24}, level=391
2762013-07-16 13:13:37,511 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2772013-07-16 13:13:37,523 sound sink: adding 417 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 596218775510L, 'sequence': 24}, level=417
2782013-07-16 13:13:37,524 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2792013-07-16 13:13:37,543 sound sink: adding 313 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 596244897959L, 'sequence': 24}, level=444
2802013-07-16 13:13:37,543 poll_UI_loop() last_UI_thread_time was 0.1 seconds ago, UI_blocked_sent=False
2812013-07-16 13:13:37,545 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2822013-07-16 13:13:37,564 sound sink: adding 417 bytes to /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 596271020408L, 'sequence': 24}, level=470
2832013-07-16 13:13:37,566 sound sink queue overrun: level=470
2842013-07-16 13:13:37,566 re-starting speaker because of overrun
2852013-07-16 13:13:37,566 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2862013-07-16 13:13:37,567 sink_clean() sound_sink=<xpra.sound.sink.SoundSink object at 0x506690>, server_sound_sequence=True
2872013-07-16 13:13:37,568 SoundPipeline.cleanup()
2882013-07-16 13:13:37,568 SoundPipeline.stop()
2892013-07-16 13:13:37,568 need_data: 4096 bytes in /GstPipeline:pipeline15/GstAppSrc:src (__main__.GstAppSrc)
2902013-07-16 13:13:37,596 SoundPipeline.stop() done
2912013-07-16 13:13:37,597 SoundPipeline.cleanup() done
2922013-07-16 13:13:37,613 starting mp3 sound sink
2932013-07-16 13:13:37,613 pipeline elements=['appsrc name=src max-bytes=512', 'mp3parse', 'mad', 'volume name=volume', 'audioconvert', 'audioresample', 'queue name=queue min-threshold-time=50000000 max-size-time=250000000 leaky=2', 'osxaudiosink']
2942013-07-16 13:13:37,613 pipeline=appsrc name=src max-bytes=512 ! mp3parse ! mad ! volume name=volume ! audioconvert ! audioresample ! queue name=queue min-threshold-time=50000000 max-size-time=250000000 leaky=2 ! osxaudiosink
2952013-07-16 13:13:37,640 SoundPipeline.start()
2962013-07-16 13:13:37,643 sound sink queue underrun: level=0
2972013-07-16 13:13:37,644 SoundPipeline.start() done
2982013-07-16 13:13:37,644 mp3 sound sink started
2992013-07-16 13:13:37,645 sound sink: adding 313 bytes to /GstPipeline:pipeline16/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 596297142857L, 'sequence': 25}, level=0
3002013-07-16 13:13:37,645 need_data: 4096 bytes in /GstPipeline:pipeline16/GstAppSrc:src (__main__.GstAppSrc)
3012013-07-16 13:13:37,645 need_data: 4096 bytes in /GstPipeline:pipeline16/GstAppSrc:src (__main__.GstAppSrc)
3022013-07-16 13:13:37,645 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from osxaudiosink16 at 0xc46ec0>
3032013-07-16 13:13:37,646 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from queue at 0x4083560>
3042013-07-16 13:13:37,646 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioresample16 at 0xc46e80>
3052013-07-16 13:13:37,646 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioconvert16 at 0xc46d40>
3062013-07-16 13:13:37,646 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from volume at 0xc46d80>
3072013-07-16 13:13:37,647 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from mad16 at 0xc46dc0>
3082013-07-16 13:13:37,647 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from mpegaudioparse16 at 0xb53d88>
3092013-07-16 13:13:37,647 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; from src at 0xc46f00>
3102013-07-16 13:13:37,647 new-state=READY
3112013-07-16 13:13:37,648 sound: client_signalled_change(<XpraClient object at 0x3e92210 (xpra+client+gtk2+client+XpraClient at 0x9c7820)>) is_on=True
3122013-07-16 13:13:37,648 stream status: <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_CREATE, owner=(GstElement)"\(GstQueue\)\ queue", object=(GstTask)"\(GstTask\)\ queue:src"; from src at 0x40836e0>
3132013-07-16 13:13:37,648 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from queue at 0x40836a0>
3142013-07-16 13:13:37,648 stream status: <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstQueue\)\ queue", object=(GstTask)"\(GstTask\)\ queue:src"; from src at 0xae8a00>
3152013-07-16 13:13:37,649 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioresample16 at 0xaf1dd8>
3162013-07-16 13:13:37,649 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioconvert16 at 0x946b60>
3172013-07-16 13:13:37,649 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from volume at 0xc51148>
3182013-07-16 13:13:37,649 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from mad16 at 0xc51108>
3192013-07-16 13:13:37,650 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from mpegaudioparse16 at 0xae90c8>
3202013-07-16 13:13:37,650 stream status: <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_CREATE, owner=(GstElement)"\(GstAppSrc\)\ src", object=(GstTask)"\(GstTask\)\ src:src"; from src at 0xaf1e58>
3212013-07-16 13:13:37,650 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; from src at 0x40c8b40>
3222013-07-16 13:13:37,650 new-state=PAUSED
3232013-07-16 13:13:37,651 sound: client_signalled_change(<XpraClient object at 0x3e92210 (xpra+client+gtk2+client+XpraClient at 0x9c7820)>) is_on=True
3242013-07-16 13:13:37,651 stream status: <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstAppSrc\)\ src", object=(GstTask)"\(GstTask\)\ src:src"; from src at 0xb53e88>
3252013-07-16 13:13:37,651 <gst.Message GstMessageNewClock, clock=(GstClock)"\(GstSystemClock\)\ GstSystemClock"; from pipeline16 at 0xb0e950>
3262013-07-16 13:13:37,651 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from queue at 0xb0f118>
3272013-07-16 13:13:37,651 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioresample16 at 0x40834a0>
3282013-07-16 13:13:37,652 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from audioconvert16 at 0xc46f80>
3292013-07-16 13:13:37,652 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from volume at 0xb0eb10>
3302013-07-16 13:13:37,652 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from mad16 at 0xb53dc8>
3312013-07-16 13:13:37,653 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from mpegaudioparse16 at 0xae8b00>
3322013-07-16 13:13:37,653 state changed: <gst.Message GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; from src at 0xae8a40>
3332013-07-16 13:13:37,659 sound sink: adding 417 bytes to /GstPipeline:pipeline16/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 596323265306L, 'sequence': 25}, level=0
3342013-07-16 13:13:37,660 need_data: 4096 bytes in /GstPipeline:pipeline16/GstAppSrc:src (__main__.GstAppSrc)
3352013-07-16 13:13:37,660 sound sink: adding 365 bytes to /GstPipeline:pipeline16/GstAppSrc:src (__main__.GstAppSrc), metadata: {'duration': 26122449, 'timestamp': 596349387755L, 'sequence': 25}, level=0
3362013-07-16 13:13:37,661 need_data: 4096 bytes in /GstPipeline:pipeline16/GstAppSrc:src (__main__.GstAppSrc)
3372013-07-16 13:13:37,661 using audio codec: MPEG 1 Audio, Layer 3 (MP3)
3382013-07-16 13:13:37,662 duration changed: (<enum GST_FORMAT_TIME of type GstFormat>, -1L)
3392013-07-16 13:13:37,662 new bitrate: 96000
3402013-07-16 13:13:37,662 duration changed: (<enum GST_FORMAT_TIME of type GstFormat>, -1L)
3412013-07-16 13:13:37,662 new bitrate: 112000
342
343got signal SIGINT, exiting