I: [pulseaudio] client.c: Created 34 "Native client (UNIX socket client)"
D: [pulseaudio] protocol-native.c: Protocol version: remote 26, local 26
I: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
D: [pulseaudio] protocol-native.c: SHM possible: yes
D: [pulseaudio] protocol-native.c: Negotiated SHM: yes
D: [pulseaudio] module-augment-properties.c: Looking for .desktop file for gst-launch-0.10
I: [pulseaudio] socket-server.c: TCP connection accepted by tcpwrap.
I: [pulseaudio] client.c: Created 35 "HTTP client (TCP/IP client from 127.0.0.1:35993)"
D: [pulseaudio] protocol-http.c: Request for /listen/source/upnp.monitor
D: [pulseaudio] module-stream-restore.c: Not restoring device for stream source-output-by-application-name:HTTP client (TCP/IP client from 127.0.0.1:35993), because already set
D: [pulseaudio] module-intended-roles.c: Not setting device for stream (null), because already set.
D: [pulseaudio] module-stream-restore.c: Database contains invalid data for key: source-output-by-application-name:HTTP client (TCP/IP client from 127.0.0.1:35993) (probably pre-v1.0 data)
D: [pulseaudio] module-stream-restore.c: Attempting to load legacy (pre-v1.0) data for key: source-output-by-application-name:HTTP client (TCP/IP client from 127.0.0.1:35993)
D: [pulseaudio] module-stream-restore.c: Size does not match.
D: [pulseaudio] module-stream-restore.c: Unable to load legacy (pre-v1.0) data for key: source-output-by-application-name:HTTP client (TCP/IP client from 127.0.0.1:35993). Ignoring.
D: [pulseaudio] sink.c: Suspend cause of sink upnp is 0x0000, resuming
D: [pulseaudio] module-suspend-on-idle.c: Sink upnp becomes idle, timeout in 5 seconds.
D: [pulseaudio] module-suspend-on-idle.c: Sink upnp becomes busy.
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] source-output.c: Created output 3 "(null)" on upnp.monitor with sample spec s16be 2ch 44100Hz and channel map front-left,front-right
I: [pulseaudio] source-output.c:     application.name = "HTTP client (TCP/IP client from 127.0.0.1:35993)"
I: [pulseaudio] source-output.c:     http-protocol.peer = "TCP/IP client from 127.0.0.1:35993"
I: [pulseaudio] source-output.c:     module-stream-restore.id = "source-output-by-application-name:HTTP client (TCP/IP client from 127.0.0.1:35993)"
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=882000, tlength=0, base=4, prebuf=1, minreq=0 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=882000, tlength=882000, base=4, prebuf=4, minreq=4 maxrewind=0
D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
D: [pulseaudio] module-stream-restore.c: Database contains invalid data for key: source-output-by-application-name:HTTP client (TCP/IP client from 127.0.0.1:35993) (probably pre-v1.0 data)
D: [pulseaudio] module-stream-restore.c: Attempting to load legacy (pre-v1.0) data for key: source-output-by-application-name:HTTP client (TCP/IP client from 127.0.0.1:35993)
D: [pulseaudio] module-stream-restore.c: Size does not match.
D: [pulseaudio] module-stream-restore.c: Unable to load legacy (pre-v1.0) data for key: source-output-by-application-name:HTTP client (TCP/IP client from 127.0.0.1:35993). Ignoring.
I: [pulseaudio] module-stream-restore.c: Storing volume/mute/device for stream source-output-by-application-name:HTTP client (TCP/IP client from 127.0.0.1:35993).
D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Ext.StreamRestore1.RestoreEntry added for object /org/pulseaudio/stream_restore1/entry84
D: [pulseaudio] module-stream-restore.c: Not restoring device for stream sink-input-by-application-name:gst-launch-0.10, because already set to 'alsa_output.pci-0000_00_1b.0.analog-stereo'.
D: [pulseaudio] module-intended-roles.c: Not setting device for stream Playback Stream, because already set.
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_1b.0.analog-stereo is 0x0000, resuming
D: [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio0'
I: [alsa-sink] alsa-sink.c: Trying resume...
I: [alsa-sink] alsa-util.c: Trying to disable ALSA period wakeups, using timers only
D: [alsa-sink] alsa-util.c: Maximum hw buffer size is 371 ms
D: [alsa-sink] alsa-util.c: Set buffer size first (to 16384 samples), period size second (to 8192 samples).
I: [alsa-sink] alsa-util.c: ALSA period wakeups disabled
D: [alsa-sink] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink] alsa-sink.c: setting avail_min=15061
I: [alsa-sink] alsa-sink.c: Resumed successfully...
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
I: [alsa-sink] alsa-sink.c: Starting playback.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy.
I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
I: [pulseaudio] resampler.c: Using resampler 'copy'
I: [pulseaudio] resampler.c: Using s16le as working format.
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] sink-input.c: Created input 18 "Playback Stream" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec s16be 2ch 44100Hz and channel map front-left,front-right
I: [pulseaudio] sink-input.c:     media.name = "Playback Stream"
I: [pulseaudio] sink-input.c:     application.name = "gst-launch-0.10"
I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c:     native-protocol.version = "26"
I: [pulseaudio] sink-input.c:     application.process.id = "2596"
I: [pulseaudio] sink-input.c:     application.process.user = "tangtang"
I: [pulseaudio] sink-input.c:     application.process.host = "tangtang-intel"
I: [pulseaudio] sink-input.c:     application.process.binary = "gst-launch-0.10"
I: [pulseaudio] sink-input.c:     application.language = "en_US.UTF-8"
I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "0508236a5585291e65c0de4e0000000c"
I: [pulseaudio] sink-input.c:     application.process.session_id = "0508236a5585291e65c0de4e0000000c-1373037205.532025-955619558"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:gst-launch-0.10"
I: [pulseaudio] protocol-native.c: Requested tlength=200.00 ms, minreq=10.00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=19404, base=4, prebuf=0, minreq=1764 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=19404, base=4, prebuf=0, minreq=1764 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 200.00 ms = 90.00 ms + 2*10.00 ms + 90.00 ms
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink] alsa-sink.c: Latency set to 90.00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=49660
D: [alsa-sink] alsa-sink.c: setting avail_min=15062
D: [alsa-sink] alsa-sink.c: Requesting rewind due to latency change.
D: [alsa-sink] alsa-sink.c: Requested volume: 0:  63% 1:  63%
D: [alsa-sink] alsa-sink.c:            in dB: 0: -12.00 dB 1: -12.00 dB
D: [alsa-sink] alsa-sink.c: Got hardware volume: 0:  63% 1:  63%
D: [alsa-sink] alsa-sink.c:               in dB: 0: -12.00 dB 1: -12.00 dB
D: [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes)
D: [alsa-sink] alsa-sink.c:                      in dB: 0: 0.00 dB 1: 0.00 dB
D: [alsa-sink] sink.c: Volume not changing
D: [alsa-sink] alsa-sink.c: Requested to rewind 65536 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 64768 bytes.
D: [alsa-sink] alsa-sink.c: before: 16192
D: [alsa-sink] alsa-sink.c: after: 16192
D: [alsa-sink] alsa-sink.c: Rewound 64768 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 2135
D: [alsa-sink] sink-input.c: Have to rewind 64768 bytes on render memblockq.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink] sink-input.c: Requesting rewind due to uncorking
D: [alsa-sink] alsa-sink.c: Requested to rewind 65536 bytes.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy.
D: [alsa-sink] alsa-sink.c: Limited to 14980 bytes.
D: [alsa-sink] alsa-sink.c: before: 3745
D: [alsa-sink] alsa-sink.c: after: 3745
D: [alsa-sink] alsa-sink.c: Rewound 14980 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 2281
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 12940 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 12940 bytes.
D: [alsa-sink] alsa-sink.c: before: 3235
D: [alsa-sink] alsa-sink.c: after: 3235
D: [alsa-sink] alsa-sink.c: Rewound 12940 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 13098
D: [alsa-sink] sink-input.c: Have to rewind 12940 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 12940 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 9644 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 9644 bytes.
D: [alsa-sink] alsa-sink.c: before: 2411
D: [alsa-sink] alsa-sink.c: after: 2411
D: [alsa-sink] alsa-sink.c: Rewound 9644 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 35623
D: [alsa-sink] sink-input.c: Have to rewind 9644 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 9644 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 10900 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 10900 bytes.
D: [alsa-sink] alsa-sink.c: before: 2725
D: [alsa-sink] alsa-sink.c: after: 2725
D: [alsa-sink] alsa-sink.c: Rewound 10900 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 13052
D: [alsa-sink] sink-input.c: Have to rewind 10900 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 10900 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 9524 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 9524 bytes.
D: [alsa-sink] alsa-sink.c: before: 2381
D: [alsa-sink] alsa-sink.c: after: 2381
D: [alsa-sink] alsa-sink.c: Rewound 9524 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 35674
D: [alsa-sink] sink-input.c: Have to rewind 9524 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 9524 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 10940 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 10940 bytes.
D: [alsa-sink] alsa-sink.c: before: 2735
D: [alsa-sink] alsa-sink.c: after: 2735
D: [alsa-sink] alsa-sink.c: Rewound 10940 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 12983
D: [alsa-sink] sink-input.c: Have to rewind 10940 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 10940 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 5468 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 5468 bytes.
D: [alsa-sink] alsa-sink.c: before: 1367
D: [alsa-sink] alsa-sink.c: after: 1367
D: [alsa-sink] alsa-sink.c: Rewound 5468 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 58630
D: [alsa-sink] sink-input.c: Have to rewind 5468 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 5468 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 10980 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 10980 bytes.
D: [alsa-sink] alsa-sink.c: before: 2745
D: [alsa-sink] alsa-sink.c: after: 2745
D: [alsa-sink] alsa-sink.c: Rewound 10980 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 12908
D: [alsa-sink] sink-input.c: Have to rewind 10980 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 10980 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 5508 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 5508 bytes.
D: [alsa-sink] alsa-sink.c: before: 1377
D: [alsa-sink] alsa-sink.c: after: 1377
D: [alsa-sink] alsa-sink.c: Rewound 5508 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 58564
D: [alsa-sink] sink-input.c: Have to rewind 5508 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 5508 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 1572 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 1572 bytes.
D: [alsa-sink] alsa-sink.c: before: 393
D: [alsa-sink] alsa-sink.c: after: 393
D: [alsa-sink] alsa-sink.c: Rewound 1572 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 81437
D: [alsa-sink] sink-input.c: Have to rewind 1572 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 1572 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 11020 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 11020 bytes.
D: [alsa-sink] alsa-sink.c: before: 2755
D: [alsa-sink] alsa-sink.c: after: 2755
D: [alsa-sink] alsa-sink.c: Rewound 11020 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 12853
D: [alsa-sink] sink-input.c: Have to rewind 11020 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 11020 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 9484 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 9484 bytes.
D: [alsa-sink] alsa-sink.c: before: 2371
D: [alsa-sink] alsa-sink.c: after: 2371
D: [alsa-sink] alsa-sink.c: Rewound 9484 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 35558
D: [alsa-sink] sink-input.c: Have to rewind 9484 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 9484 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 5548 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 5548 bytes.
D: [alsa-sink] alsa-sink.c: before: 1387
D: [alsa-sink] alsa-sink.c: after: 1387
D: [alsa-sink] alsa-sink.c: Rewound 5548 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 58483
D: [alsa-sink] sink-input.c: Have to rewind 5548 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 5548 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 10900 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 10900 bytes.
D: [alsa-sink] alsa-sink.c: before: 2725
D: [alsa-sink] alsa-sink.c: after: 2725
D: [alsa-sink] alsa-sink.c: Rewound 10900 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 12637
D: [alsa-sink] sink-input.c: Have to rewind 10900 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 10900 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 5428 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 5428 bytes.
D: [alsa-sink] alsa-sink.c: before: 1357
D: [alsa-sink] alsa-sink.c: after: 1357
D: [alsa-sink] alsa-sink.c: Rewound 5428 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 58376
D: [alsa-sink] sink-input.c: Have to rewind 5428 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 5428 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 10940 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 10940 bytes.
D: [alsa-sink] alsa-sink.c: before: 2735
D: [alsa-sink] alsa-sink.c: after: 2735
D: [alsa-sink] alsa-sink.c: Rewound 10940 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 12619
D: [alsa-sink] sink-input.c: Have to rewind 10940 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 10940 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'Playback Stream', 0 bytes in queue.
D: [alsa-sink] protocol-native.c: Requesting rewind due to rewrite.
D: [alsa-sink] alsa-sink.c: Requested to rewind 5468 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 5468 bytes.
D: [alsa-sink] alsa-sink.c: before: 1367
D: [alsa-sink] alsa-sink.c: after: 1367
D: [alsa-sink] alsa-sink.c: Rewound 5468 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 58474
D: [alsa-sink] sink-input.c: Have to rewind 5468 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 5468 bytes on implementor.
D: [alsa-sink] source.c: Processing rewind...
I: [pulseaudio] client.c: Created 36 "Native client (UNIX socket client)"
D: [pulseaudio] protocol-native.c: Protocol version: remote 26, local 26
I: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
D: [pulseaudio] protocol-native.c: SHM possible: yes
D: [pulseaudio] protocol-native.c: Negotiated SHM: yes
D: [pulseaudio] module-augment-properties.c: Looking for .desktop file for gst-launch-0.10
D: [pulseaudio] module-stream-restore.c: Not restoring device for stream sink-input-by-application-name:gst-launch-0.10, because already set to 'upnp'.
D: [pulseaudio] module-intended-roles.c: Not setting device for stream pulsesink probe, because already set.
D: [pulseaudio] module-suspend-on-idle.c: Sink upnp becomes busy.
I: [pulseaudio] resampler.c: Using resampler 'speex-float-1'
I: [pulseaudio] resampler.c: Using float32le as working format.
I: [pulseaudio] resampler.c: Choosing speex quality setting 1.
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] sink-input.c: Created input 19 "pulsesink probe" on upnp with sample spec s32le 2ch 48000Hz and channel map front-left,front-right
I: [pulseaudio] sink-input.c:     media.name = "pulsesink probe"
I: [pulseaudio] sink-input.c:     application.name = "gst-launch-0.10"
I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c:     native-protocol.version = "26"
I: [pulseaudio] sink-input.c:     application.process.id = "2602"
I: [pulseaudio] sink-input.c:     application.process.user = "tangtang"
I: [pulseaudio] sink-input.c:     application.process.host = "tangtang-intel"
I: [pulseaudio] sink-input.c:     application.process.binary = "gst-launch-0.10"
I: [pulseaudio] sink-input.c:     application.language = "en_US.UTF-8"
I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "0508236a5585291e65c0de4e0000000c"
I: [pulseaudio] sink-input.c:     application.process.session_id = "0508236a5585291e65c0de4e0000000c-1373037205.532025-955619558"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:gst-launch-0.10"
I: [pulseaudio] protocol-native.c: Requested tlength=125.00 ms, minreq=20.00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=31680, base=8, prebuf=24008, minreq=7680 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=31680, base=8, prebuf=24008, minreq=7680 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 125.00 ms = 42.50 ms + 2*20.00 ms + 42.50 ms
I: [pulseaudio] module-stream-restore.c: Storing volume/mute/device for stream sink-input-by-application-name:gst-launch-0.10.
D: [null-sink] module-null-sink.c: Requested to rewind 52920 bytes.
I: [pulseaudio] sink-input.c: Freeing input 19 "pulsesink probe"
D: [null-sink] sink.c: Processing rewind...
D: [null-sink] source.c: Processing rewind...
D: [null-sink] module-null-sink.c: Rewound 16876 bytes.
D: [pulseaudio] module-stream-restore.c: Not restoring device for stream sink-input-by-application-name:gst-launch-0.10, because already set to 'upnp'.
D: [pulseaudio] module-intended-roles.c: Not setting device for stream pulsesink probe, because already set.
D: [pulseaudio] module-suspend-on-idle.c: Sink upnp becomes busy.
I: [pulseaudio] resampler.c: Using resampler 'speex-float-1'
I: [pulseaudio] resampler.c: Using float32le as working format.
I: [pulseaudio] resampler.c: Choosing speex quality setting 1.
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] sink-input.c: Created input 20 "pulsesink probe" on upnp with sample spec s32le 2ch 48000Hz and channel map front-left,front-right
I: [pulseaudio] sink-input.c:     media.name = "pulsesink probe"
I: [pulseaudio] sink-input.c:     application.name = "gst-launch-0.10"
I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c:     native-protocol.version = "26"
I: [pulseaudio] sink-input.c:     application.process.id = "2602"
I: [pulseaudio] sink-input.c:     application.process.user = "tangtang"
I: [pulseaudio] sink-input.c:     application.process.host = "tangtang-intel"
I: [pulseaudio] sink-input.c:     application.process.binary = "gst-launch-0.10"
I: [pulseaudio] sink-input.c:     application.language = "en_US.UTF-8"
I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "0508236a5585291e65c0de4e0000000c"
I: [pulseaudio] sink-input.c:     application.process.session_id = "0508236a5585291e65c0de4e0000000c-1373037205.532025-955619558"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:gst-launch-0.10"
I: [pulseaudio] protocol-native.c: Requested tlength=125.00 ms, minreq=20.00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=31680, base=8, prebuf=24008, minreq=7680 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=31680, base=8, prebuf=24008, minreq=7680 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 125.00 ms = 42.50 ms + 2*20.00 ms + 42.50 ms
D: [null-sink] module-null-sink.c: Requested to rewind 52920 bytes.
I: [pulseaudio] sink-input.c: Freeing input 20 "pulsesink probe"
D: [null-sink] sink.c: Processing rewind...
D: [null-sink] source.c: Processing rewind...
D: [null-sink] module-null-sink.c: Rewound 52748 bytes.
D: [pulseaudio] module-stream-restore.c: Not restoring device for stream sink-input-by-application-name:gst-launch-0.10, because already set to 'upnp'.
D: [pulseaudio] module-intended-roles.c: Not setting device for stream Playback Stream, because already set.
D: [pulseaudio] module-suspend-on-idle.c: Sink upnp becomes busy.
I: [pulseaudio] resampler.c: Using resampler 'speex-float-1'
I: [pulseaudio] resampler.c: Using float32le as working format.
I: [pulseaudio] resampler.c: Choosing speex quality setting 1.
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] sink-input.c: Created input 21 "Playback Stream" on upnp with sample spec s32le 2ch 48000Hz and channel map front-left,front-right
I: [pulseaudio] sink-input.c:     media.name = "Playback Stream"
I: [pulseaudio] sink-input.c:     application.name = "gst-launch-0.10"
I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c:     native-protocol.version = "26"
I: [pulseaudio] sink-input.c:     application.process.id = "2602"
I: [pulseaudio] sink-input.c:     application.process.user = "tangtang"
I: [pulseaudio] sink-input.c:     application.process.host = "tangtang-intel"
I: [pulseaudio] sink-input.c:     application.process.binary = "gst-launch-0.10"
I: [pulseaudio] sink-input.c:     application.language = "en_US.UTF-8"
I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "0508236a5585291e65c0de4e0000000c"
I: [pulseaudio] sink-input.c:     application.process.session_id = "0508236a5585291e65c0de4e0000000c-1373037205.532025-955619558"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:gst-launch-0.10"
I: [pulseaudio] protocol-native.c: Requested tlength=200.00 ms, minreq=10.00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=42240, base=8, prebuf=0, minreq=3840 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=42240, base=8, prebuf=0, minreq=3840 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 200.00 ms = 90.00 ms + 2*10.00 ms + 90.00 ms
D: [null-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [null-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [null-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [null-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [null-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [null-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [null-sink] sink-input.c: Requesting rewind due to uncorking
D: [null-sink] module-null-sink.c: Requested to rewind 15876 bytes.
D: [null-sink] sink.c: Processing rewind...
D: [pulseaudio] module-suspend-on-idle.c: Sink upnp becomes busy.
D: [null-sink] source.c: Processing rewind...
D: [null-sink] module-null-sink.c: Rewound 15876 bytes.
D: [null-sink] sink-input.c: Requesting rewind due to corking
D: [null-sink] module-null-sink.c: Requested to rewind 15876 bytes.
D: [null-sink] sink.c: Processing rewind...
D: [null-sink] sink-input.c: Have to rewind 6868 bytes on render memblockq.
D: [null-sink] sink-input.c: Have to rewind 14952 bytes on implementor.
D: [null-sink] source.c: Processing rewind...
D: [null-sink] module-null-sink.c: Rewound 6868 bytes.
D: [null-sink] module-null-sink.c: Requested to rewind 52920 bytes.
I: [pulseaudio] sink-input.c: Freeing input 21 "Playback Stream"
D: [null-sink] sink.c: Processing rewind...
D: [null-sink] source.c: Processing rewind...
D: [null-sink] module-null-sink.c: Rewound 31244 bytes.
I: [pulseaudio] client.c: Freed 36 "gst-launch-0.10"
I: [pulseaudio] protocol-native.c: Connection died.
I: [pulseaudio] module-stream-restore.c: Synced.
D: [alsa-sink] sink-input.c: Requesting rewind due to corking
D: [alsa-sink] alsa-sink.c: Requested to rewind 65536 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 15620 bytes.
D: [alsa-sink] alsa-sink.c: before: 3905
D: [alsa-sink] alsa-sink.c: after: 3905
D: [alsa-sink] alsa-sink.c: Rewound 15620 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 784
D: [alsa-sink] sink-input.c: Have to rewind 15620 bytes on render memblockq.
D: [alsa-sink] sink-input.c: Have to rewind 15620 bytes on implementor.
D: [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [alsa-sink] source.c: Processing rewind...
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
I: [pulseaudio] module-stream-restore.c: Storing volume/mute/device for stream sink-input-by-application-name:gst-launch-0.10.
D: [alsa-sink] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink] alsa-sink.c: setting avail_min=15061
D: [alsa-sink] alsa-sink.c: Requested volume: 0:  63% 1:  63%
D: [alsa-sink] alsa-sink.c:            in dB: 0: -12.00 dB 1: -12.00 dB
D: [alsa-sink] alsa-sink.c: Got hardware volume: 0:  63% 1:  63%
D: [alsa-sink] alsa-sink.c:               in dB: 0: -12.00 dB 1: -12.00 dB
D: [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes)
D: [alsa-sink] alsa-sink.c:                      in dB: 0: 0.00 dB 1: 0.00 dB
D: [alsa-sink] sink.c: Volume not changing
D: [alsa-sink] alsa-sink.c: Requested to rewind 65536 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 14980 bytes.
D: [alsa-sink] alsa-sink.c: before: 3745
D: [alsa-sink] alsa-sink.c: after: 3745
D: [alsa-sink] alsa-sink.c: Rewound 14980 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink.c: latency = 1491
D: [alsa-sink] source.c: Processing rewind...
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
I: [pulseaudio] sink-input.c: Freeing input 18 "Playback Stream"
D: [pulseaudio] module-suspend-on-idle.c: Sink upnp becomes idle, timeout in 5 seconds.
D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
I: [pulseaudio] source-output.c: Freeing output 3 "(null)"
I: [pulseaudio] client.c: Freed 35 "HTTP client (TCP/IP client from 127.0.0.1:35993)"
I: [pulseaudio] client.c: Freed 34 "gst-launch-0.10"
I: [pulseaudio] protocol-native.c: Connection died.