Distorted playback

[mrwhale]

Hey so after setting this up and actually playing a track for more then 30 seconds in my tests, I've run into an issue where after about 10 minutes or so of playing a track, it will start being very jumpy/stuttery
Running snapclient, here is the output when this starts happening

2016-01-20 11-02-10 [out] sleep > chunkDuration: 39 > 26, chunks: 37, out: 6, needed: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 14, age: -11, bufferDuration: 30
2016-01-20 11-02-10 [out] Chunk: -116   -116    -116    -116    1   6
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 14, age: -12, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 14, age: -9, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 14, age: -3, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 13, age: 5, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 13, age: 12, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 13, age: 18, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 13, age: 18, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 13, age: 18, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 13, age: 16, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 13, age: 14, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 13, age: 13, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 13, age: 10, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 13, age: 7, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 12, age: 2, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 12, age: -2, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 12, age: -3, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 12, age: -7, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 12, age: -10, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 12, age: -14, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 12, age: -19, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 12, age: -22, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 12, age: -26, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 12, age: -27, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 11, age: -29, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 11, age: -33, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 11, age: -35, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 11, age: -38, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 11, age: -40, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 11, age: -41, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 11, age: -43, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 11, age: -44, bufferDuration: 30
2016-01-20 11-02-10 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-10 [out] Sleep 11, age: -45, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 11, age: -46, bufferDuration: 30
2016-01-20 11-02-11 [out] Chunk: -462   -462    -462    -462    1   93
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 10, age: -46, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 10, age: -47, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 10, age: -47, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 10, age: -47, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 10, age: -47, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 10, age: -47, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 10, age: -46, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 10, age: -46, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 10, age: -45, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 10, age: -45, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 9, age: -44, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 9, age: -43, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 9, age: -43, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 9, age: -43, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 9, age: -42, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 9, age: -41, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 9, age: -40, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 9, age: -39, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 9, age: -39, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 9, age: -38, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 8, age: -37, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 8, age: -37, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 8, age: -36, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 8, age: -35, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 8, age: -35, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 8, age: -34, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 8, age: -33, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 8, age: -33, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 8, age: -32, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 8, age: -31, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 7, age: -31, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 7, age: -30, bufferDuration: 30
2016-01-20 11-02-11 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-11 [out] Sleep 7, age: -30, bufferDuration: 30
2016-01-20 11-02-12 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-12 [out] Sleep 7, age: -29, bufferDuration: 30
2016-01-20 11-02-12 [out] Chunk: -297   -297    -297    -297    1   118
2016-01-20 11-02-12 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-12 [out] Sleep 7, age: -29, bufferDuration: 30
2016-01-20 11-02-12 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-12 [out] Sleep 7, age: -28, bufferDuration: 30
2016-01-20 11-02-12 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-12 [out] Sleep 7, age: -28, bufferDuration: 30
2016-01-20 11-02-12 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-12 [out] Sleep 7, age: -28, bufferDuration: 30
2016-01-20 11-02-12 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-12 [out] Sleep 7, age: -28, bufferDuration: 30
2016-01-20 11-02-12 [out] correction: 4, factor: 1.00302
2016-01-20 11-02-12 [out] Sleep 7, age: -27, bufferDuration: 30
2016-01-20 11-02-44 [out] time sync main loop

There are also alot of

[err] XRUN 

errors throughout it too

the time sync main loop is after i have paused the music. The only output from the server around this time is

 2016-01-20 11-00-12 [out] onResync 280ms
2016-01-20 11-00-13 [out] onResync 80ms
2016-01-20 11-00-40 [out] onResync 80ms
2016-01-20 11-00-52 [out] onResync 281ms
2016-01-20 11-00-53 [out] onResync 81ms
2016-01-20 11-01-07 [out] onResync 81ms
2016-01-20 11-05-13 [out] onResync 154045ms

What could be the cause of this? anyway to fix
I have the server as an rpi2, and client is rpi1 (model B) both wired connection

[badaix]

What is your audio output source? (what do you feed into the snapcast pipe?)
Maybe your source uses a different sample rate than the Snapserver (which would lead to underruns and resync events).
Or you bumped into issue #16?

[mrwhale]

Audio source if from mopidy, so i'll play around with sample rates with that. But thats, i didnt see #16, that looks like my problem from that output posted in there. I"ll try that too!

[badaix]

any success with #16?

[mrwhale]

Yeah no dice for me, trying the various other fixes in the other issues. I think it may also be related to my network setup and the load on my devices. Recently found that my WD livewire powerline adaptors were playing funny buggers and the latency from one device to another on the other end was in the 1000's of ms. Also, I think I may be doing a little too much with my raspberry pi's :P I'll close for now and sort things out and give it another go with the latest beta release (and the updated version of mopidy)