pa_hang

backtrace

#0  0x76d51a70 in __pthread_cond_wait (cond=0x22190, mutex=0x22170) at pthread_cond_wait.c:177
#1  0x76f783a4 in pa_threaded_mainloop_wait () from /usr/lib/arm-linux-gnueabihf/libpulse.so.0
#2  0x76f9b908 in pa_simple_write () from /usr/lib/arm-linux-gnueabihf/libpulse-simple.so.0
#3  0x00010b1c in main ()

client.c

#include <pulse/simple.h>
#include <pulse/error.h>

#include <stdint.h>
#include <stdlib.h>
#include <stdio.h>
#include <string.h>
#include <unistd.h>
#include <time.h>

static uint64_t timestamp_us() {
    struct timespec ts;
    clock_gettime(CLOCK_MONOTONIC, &ts);
    return (uint64_t)ts.tv_sec * 1000000 + (uint64_t)ts.tv_nsec / 1000;
}

int main(int argc, char **argv)
{
    if (argc != 3) {
        fprintf(stderr, "usage: %s DELAY_US TIMEOUT_SEC\n", argv[0]);
        exit(1);
    }

    const char *server_name = NULL;
    const char *client_name = argv[0];
    const char *sink_name = NULL;
    const char *stream_name = argv[0];

    int delay_us = atoi(argv[1]);
    int timeout_sec = atoi(argv[2]);

    pa_sample_spec sample_spec = {};
    sample_spec.format = PA_SAMPLE_FLOAT32LE;
    sample_spec.rate = 44100;
    sample_spec.channels = 2;

    int error = 0;
    pa_simple *simple = pa_simple_new(
        server_name,
        client_name,
        PA_STREAM_PLAYBACK,
        sink_name,
        stream_name,
        &sample_spec,
        NULL,
        NULL,
        &error);

    if (simple == NULL) {
        fprintf(stderr, "pa_simple_new: %s\n", pa_strerror(error));
        exit(1);
    }

    uint64_t start_ts = timestamp_us();

    uint64_t n_bytes = 0;

    for (;;) {
        char buf[1024] = {};

        alarm(timeout_sec);

        if (pa_simple_write(simple, buf, sizeof(buf), &error) != 0) {
            fprintf(stderr, "pa_simple_write: %s\n", pa_strerror(error));
            exit(1);
        }

        alarm(0);

        fprintf(stderr, "wrote %lu bytes (%lu us), elapsed %lu us\n",
                (unsigned long)n_bytes,
                (unsigned long)pa_bytes_to_usec(n_bytes, &sample_spec),
                (unsigned long)(timestamp_us() - start_ts));

        n_bytes += sizeof(buf);

        uint64_t ts = timestamp_us();

        while (timestamp_us() - ts < delay_us) {
            // busy loop
        }
    }

    pa_simple_free(simple);

    return 0;
}

client.log

Parameters: 10000 us busy loop after writing every 1024 bytes, 300 sec (5 min) timeout for single write

$ ./a.out 10000 300
......
wrote 317440 bytes (899773 us), elapsed 3196719 us
wrote 318464 bytes (902675 us), elapsed 3207279 us
wrote 319488 bytes (905578 us), elapsed 3217427 us
wrote 320512 bytes (908480 us), elapsed 3227572 us
wrote 321536 bytes (911383 us), elapsed 3237675 us
wrote 322560 bytes (914285 us), elapsed 3247818 us
wrote 323584 bytes (917188 us), elapsed 3257967 us
wrote 324608 bytes (920090 us), elapsed 3268108 us
wrote 325632 bytes (922993 us), elapsed 3278252 us
wrote 326656 bytes (925895 us), elapsed 3288401 us
wrote 327680 bytes (928798 us), elapsed 3298568 us
wrote 328704 bytes (931700 us), elapsed 3308837 us
wrote 329728 bytes (934603 us), elapsed 3319463 us
wrote 330752 bytes (937505 us), elapsed 3329615 us
Alarm clock

pactl

$ pactl info
Server String: /run/user/1000/pulse/native
Library Protocol Version: 32
Server Protocol Version: 32
Is Local: yes
Client Index: 5
Tile Size: 65496
User Name: pi
Host Name: raspberrypi
Server Name: pulseaudio
Server Version: 10.0
Default Sample Specification: s16le 2ch 44100Hz
Default Channel Map: front-left,front-right
Default Sink: alsa_output.platform-soc_audio.analog-stereo
Default Source: alsa_output.platform-soc_audio.analog-stereo.monitor
Cookie: 4868:5948

$ pactl list sinks
Sink #0
	State: SUSPENDED
	Name: alsa_output.platform-soc_audio.analog-stereo
	Description: bcm2835 ALSA Analog Stereo
	Driver: module-alsa-card.c
	Sample Specification: s16le 2ch 44100Hz
	Channel Map: front-left,front-right
	Owner Module: 6
	Mute: no
	Volume: front-left: 59065 /  90% / -2.71 dB,   front-right: 59065 /  90% / -2.71 dB
	        balance 0.00
	Base Volume: 56210 /  86% / -4.00 dB
	Monitor Source: alsa_output.platform-soc_audio.analog-stereo.monitor
	Latency: 0 usec, configured 0 usec
	Flags: HARDWARE HW_MUTE_CTRL HW_VOLUME_CTRL DECIBEL_VOLUME LATENCY 
	Properties:
		alsa.resolution_bits = "16"
		device.api = "alsa"
		device.class = "sound"
		alsa.class = "generic"
		alsa.subclass = "generic-mix"
		alsa.name = "bcm2835 ALSA"
		alsa.id = "bcm2835 ALSA"
		alsa.subdevice = "0"
		alsa.subdevice_name = "subdevice #0"
		alsa.device = "0"
		alsa.card = "0"
		alsa.card_name = "bcm2835 ALSA"
		alsa.long_card_name = "bcm2835 ALSA"
		alsa.driver_name = "snd_bcm2835"
		device.bus_path = "platform-soc:audio"
		sysfs.path = "/devices/platform/soc/soc:audio/sound/card0"
		device.string = "hw:0"
		device.buffering.buffer_size = "131072"
		device.buffering.fragment_size = "131072"
		device.access_mode = "mmap+timer"
		device.profile.name = "analog-stereo"
		device.profile.description = "Analog Stereo"
		device.description = "bcm2835 ALSA Analog Stereo"
		alsa.mixer_name = "Broadcom Mixer"
		module-udev-detect.discovered = "1"
		device.icon_name = "audio-card"
	Ports:
		analog-output: Analog Output (priority: 9900)
	Active Port: analog-output
	Formats:
		pcm

pulseaudio.log

I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.platform-soc_audio.analog-stereo idle for too long, suspending ...
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.platform-soc_audio.analog-stereo is 0x0004, suspending
I: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Device suspended...
D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC0 is accessible: yes
D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.platform-soc_audio.
I: [pulseaudio] client.c: Created 4 "Native client (UNIX socket client)"
D: [pulseaudio] protocol-native.c: Protocol version: remote 32, local 32
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] protocol-native.c: Memfd possible: yes
D: [pulseaudio] protocol-native.c: Negotiated SHM type: shared memfd
D: [pulseaudio] memblock.c: Using shared memfd memory pool with 1024 slots of size 64.0 KiB each, total size is 64.0 MiB, maximum usable slot size is 65496
D: [pulseaudio] srbchannel.c: SHM block is 65496 bytes, ringbuffer capacity is 2 * 32724 bytes
D: [pulseaudio] protocol-native.c: Enabling srbchannel...
D: [pulseaudio] module-augment-properties.c: Looking for .desktop file for a.out
D: [pulseaudio] protocol-native.c: Client enabled srbchannel.
D: [pulseaudio] module-intended-roles.c: Not setting device for stream ./a.out, because it lacks role.
D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\""  format.rate = "44100"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
I: [pulseaudio] sink-input.c: Trying to change sample rate
I: [pulseaudio] sink-input.c: Rate changed to 44100 Hz
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.platform-soc_audio.analog-stereo becomes busy, resuming.
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.platform-soc_audio.analog-stereo is 0x0000, resuming
D: [pulseaudio] reserve-wrap.c: Unable to contact D-Bus session bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
I: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Trying resume...
I: [alsa-sink-bcm2835 ALSA] alsa-util.c: Cannot disable ALSA period wakeups
D: [alsa-sink-bcm2835 ALSA] alsa-util.c: Maximum hw buffer size is 743 ms
D: [alsa-sink-bcm2835 ALSA] alsa-util.c: Set buffer size first (to 32768 samples), period size second (to 32768 samples).
I: [alsa-sink-bcm2835 ALSA] alsa-util.c: ALSA period wakeups were not disabled
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: setting avail_min=31886
I: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Time scheduling watermark is 20.00ms
I: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Resumed successfully...
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.platform-soc_audio.analog-stereo becomes idle, timeout in 5 seconds.
I: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Starting playback.
D: [alsa-sink-bcm2835 ALSA] ratelimit.c: 213 events suppressed
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.platform-soc_audio.analog-stereo becomes idle, timeout in 5 seconds.
I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
D: [pulseaudio] resampler.c: Resampler:
D: [pulseaudio] resampler.c:   rate 44100 -> 44100 (method copy)
D: [pulseaudio] resampler.c:   format float32le -> s16le (intermediate s16le)
D: [pulseaudio] resampler.c:   channels 2 -> 2 (resampling 2)
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 0 "./a.out" on alsa_output.platform-soc_audio.analog-stereo with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
I: [pulseaudio] sink-input.c:     media.name = "./a.out"
I: [pulseaudio] sink-input.c:     application.name = "./a.out"
I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c:     native-protocol.version = "32"
I: [pulseaudio] sink-input.c:     application.process.id = "7560"
I: [pulseaudio] sink-input.c:     application.process.user = "pi"
I: [pulseaudio] sink-input.c:     application.process.host = "raspberrypi"
I: [pulseaudio] sink-input.c:     application.process.binary = "a.out"
I: [pulseaudio] sink-input.c:     application.language = "C"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "d19e97cdc3fc4a659a2d519c64917e87"
I: [pulseaudio] sink-input.c:     application.process.session_id = "c1"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:./a.out"
I: [pulseaudio] protocol-native.c: Requested tlength=250.00 ms, minreq=20.00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Requested latency=105.00 ms, Received latency=105.00 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=51160, base=8, prebuf=44112, minreq=7056 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=51160, base=8, prebuf=44112, minreq=7056 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 250.01 ms = 105.01 ms + 2*20.00 ms + 105.00 ms
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Latency set to 105.00ms
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: hwbuf_unused=112552
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: setting avail_min=31887
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Requesting rewind due to latency change.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Requested volume: front-left: 59065 /  90% / -2.71 dB,   front-right: 59065 /  90% / -2.71 dB
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Got hardware volume: front-left: 59063 /  90% / -2.71 dB,   front-right: 59063 /  90% / -2.71 dB
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Calculated software volume: front-left: 65538 / 100% / 0.00 dB,   front-right: 65538 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-bcm2835 ALSA] sink.c: Volume not changing
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Requested to rewind 131072 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Limited to 130816 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: before: 32704
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: after: 32704
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Rewound 130816 bytes.
D: [alsa-sink-bcm2835 ALSA] sink.c: Processing rewind...
D: [alsa-sink-bcm2835 ALSA] sink.c: latency = 0
D: [alsa-sink-bcm2835 ALSA] sink-input.c: Have to rewind 130816 bytes on render memblockq.
D: [alsa-sink-bcm2835 ALSA] source.c: Processing rewind...
D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
D: [alsa-sink-bcm2835 ALSA] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Requested to rewind 131072 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Limited to 18264 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: before: 4566
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: after: 4566
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Rewound 18264 bytes.
D: [alsa-sink-bcm2835 ALSA] sink.c: Processing rewind...
D: [alsa-sink-bcm2835 ALSA] sink.c: latency = 81
D: [alsa-sink-bcm2835 ALSA] sink-input.c: Have to rewind 18264 bytes on render memblockq.
D: [alsa-sink-bcm2835 ALSA] source.c: Processing rewind...
D: [alsa-sink-bcm2835 ALSA] protocol-native.c: Implicit underrun of './a.out'
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (16020 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (16020 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (16020 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (14256 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (14256 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (14256 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (14256 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (12492 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (12492 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (10724 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (10724 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (10724 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (10724 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (8952 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (8952 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (7188 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (7188 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (5424 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (5424 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (3664 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (3664 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (3664 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (3664 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (1452 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (1452 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (140 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (140 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (140 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (140 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (140 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (140 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (140 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Requested to rewind 73108 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Limited to 11212 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: before: 2803
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: after: 2803
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Rewound 11212 bytes.
D: [alsa-sink-bcm2835 ALSA] sink.c: Processing rewind...
D: [alsa-sink-bcm2835 ALSA] sink.c: latency = 10547
D: [alsa-sink-bcm2835 ALSA] sink-input.c: Have to rewind 11212 bytes on render memblockq.
D: [alsa-sink-bcm2835 ALSA] source.c: Processing rewind...
D: [alsa-sink-bcm2835 ALSA] protocol-native.c: Implicit underrun of './a.out'
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (15988 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (15988 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (15988 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (14268 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (14268 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (14268 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (14268 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (10684 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (10684 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (10684 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (10684 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (10684 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (10684 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (8996 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2532 bytes ago (8996 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (7188 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (7188 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (5424 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (5424 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (3660 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (3660 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (1900 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (1900 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Requested to rewind 81896 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Limited to 18264 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: before: 4566
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: after: 4566
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Rewound 18264 bytes.
D: [alsa-sink-bcm2835 ALSA] sink.c: Processing rewind...
D: [alsa-sink-bcm2835 ALSA] sink.c: latency = 10957
D: [alsa-sink-bcm2835 ALSA] sink-input.c: Have to rewind 18264 bytes on render memblockq.
D: [alsa-sink-bcm2835 ALSA] source.c: Processing rewind...
D: [alsa-sink-bcm2835 ALSA] protocol-native.c: Implicit underrun of './a.out'
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (16020 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (16020 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (16020 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (14256 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (14256 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (14256 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (14256 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (12492 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (12492 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (10728 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (10728 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (10728 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (10728 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (8964 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2500 bytes ago (8964 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (7188 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (7188 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (5428 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (5428 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (3664 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (3664 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (3664 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (1900 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (1900 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Requested to rewind 74828 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Limited to 11212 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: before: 2803
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: after: 2803
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Rewound 11212 bytes.
D: [alsa-sink-bcm2835 ALSA] sink.c: Processing rewind...
D: [alsa-sink-bcm2835 ALSA] sink.c: latency = 7098
D: [alsa-sink-bcm2835 ALSA] sink-input.c: Have to rewind 11212 bytes on render memblockq.
D: [alsa-sink-bcm2835 ALSA] source.c: Processing rewind...
D: [alsa-sink-bcm2835 ALSA] protocol-native.c: Implicit underrun of './a.out'
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (16004 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (16004 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (16004 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (16004 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (14240 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (14240 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (14240 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (14240 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (12476 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (12476 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (10716 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (10716 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (10716 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (10716 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (8952 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2516 bytes ago (8952 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (7188 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (7188 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (5424 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (5424 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (3664 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (3664 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (3664 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (3664 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (1900 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (1900 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11332 bytes ago (136 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Requested to rewind 73064 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Limited to 11196 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: before: 2799
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: after: 2799
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Rewound 11196 bytes.
D: [alsa-sink-bcm2835 ALSA] sink.c: Processing rewind...
D: [alsa-sink-bcm2835 ALSA] sink.c: latency = 2124
D: [alsa-sink-bcm2835 ALSA] sink-input.c: Have to rewind 11196 bytes on render memblockq.
D: [alsa-sink-bcm2835 ALSA] source.c: Processing rewind...
D: [alsa-sink-bcm2835 ALSA] protocol-native.c: Implicit underrun of './a.out'
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (16016 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (16016 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (16016 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (16016 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (14256 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (14256 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (14256 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (12492 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (12492 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (12492 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (10728 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (10728 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (8964 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 2504 bytes ago (8964 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (7200 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (7200 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (5436 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (5436 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (3664 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (3664 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (1900 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (1900 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (1900 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (1900 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (80 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (80 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (80 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (80 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (80 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (80 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (80 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (80 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] sink.c: alsa_output.platform-soc_audio.analog-stereo: Found underrun 11320 bytes ago (80 bytes ahead in playback buffer)
D: [alsa-sink-bcm2835 ALSA] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Requested to rewind 37732 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Limited to 17680 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: before: 4420
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: after: 4420
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Rewound 17680 bytes.
D: [alsa-sink-bcm2835 ALSA] sink.c: Processing rewind...
D: [alsa-sink-bcm2835 ALSA] sink.c: latency = 0
D: [alsa-sink-bcm2835 ALSA] sink-input.c: Have to rewind 17680 bytes on render memblockq.
D: [alsa-sink-bcm2835 ALSA] source.c: Processing rewind...

<----------------------------------------- CLIENT HANGS HERE ----------------------------------------->

I: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Decreasing wakeup watermark to 15.01 ms
I: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Decreasing wakeup watermark to 10.02 ms
I: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Decreasing wakeup watermark to 5.03 ms
I: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Decreasing wakeup watermark to 3.99 ms
I: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Scheduling delay of 4.30 ms > 3.99 ms, you might want to investigate this to improve latency...

<----------------------------------------- CLIENT EXITS HERE ----------------------------------------->

D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.platform-soc_audio.analog-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: setting avail_min=32593
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Requested volume: front-left: 59065 /  90% / -2.71 dB,   front-right: 59065 /  90% / -2.71 dB
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Got hardware volume: front-left: 59063 /  90% / -2.71 dB,   front-right: 59063 /  90% / -2.71 dB
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Calculated software volume: front-left: 65538 / 100% / 0.00 dB,   front-right: 65538 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-bcm2835 ALSA] sink.c: Volume not changing
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Requested to rewind 131072 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Limited to 18264 bytes.
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: before: 4566
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: after: 4566
D: [alsa-sink-bcm2835 ALSA] alsa-sink.c: Rewound 18264 bytes.
D: [alsa-sink-bcm2835 ALSA] sink.c: Processing rewind...
D: [alsa-sink-bcm2835 ALSA] sink.c: latency = 0
D: [alsa-sink-bcm2835 ALSA] source.c: Processing rewind...
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.platform-soc_audio.analog-stereo becomes idle, timeout in 5 seconds.
D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
I: [pulseaudio] sink-input.c: Freeing input 0 "./a.out"
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
I: [pulseaudio] client.c: Freed 4 "./a.out"
I: [pulseaudio] protocol-native.c: Connection died.