kirknorthrop / squeezelite

Automatically exported from code.google.com/p/squeezelite
Other
0 stars 0 forks source link

Synchro troubles #67

Open GoogleCodeExporter opened 8 years ago

GoogleCodeExporter commented 8 years ago
What steps will reproduce the problem?

1. The configuration is 2 SqueezeBox Radio and a RPi running 
squeezelite-armv6hf, connecting to a LMS

The two radios runs very well in sync mode, via squeezebox.com or via the local 
LMS

2. the three devices are well synchronized when I start playing 

3 I "next" the song via the web interface or the radio button

What is the expected output? What do you see instead?

It is expected the squeezelite to be kept synchronized with the radios. 
Instead, the squeezelite stop being sync when  It starts playing a few 
micro-secondes before or after the radio and keep being unsync.

What version of the product are you using? On what operating system?

- SqueezeBox Radio version 7.7.2r9663 
- Linux raspberrypi (3.10.25+ #622 PREEMPT Fri Jan 3 18:41:00 GMT 2014 armv6l 
GNU/Linux) running squeezelite-armv6hf v1.6. 
- Logitech Media Server is Version : 7.8.0 - 1395409907 @ Thu Mar 27 20:19:17 
CUT 2014. 

Please provide any additional information below.

A simple launch : 

Croot@raspberrypi:/usr/squeeze/lite# ./squeezelite-armv6hf -d all=debug -n 
Framboise
[18:47:53.749666] stream_init:290 init stream
[18:47:53.752495] stream_init:291 streambuf size: 2097152
[18:47:53.758330] output_init_alsa:637 init output
[18:47:53.759588] output_init_alsa:660 requested alsa_buffer: 40 alsa_period: 4 
format: any mmap: 1
[18:47:53.760897] output_init_common:341 outputbuf size: 3528000
[18:47:53.804809] output_init_common:401 supported rates: 384000 352800 192000 
176400 96000 88200 48000 44100 32000 24000 22500 16000 12000 11025 8000 
[18:47:53.820791] output_init_alsa:672 memory locked
[18:47:53.822944] output_thread:465 open output device: default
[18:47:53.826491] output_init_alsa:695 set output sched fifo rt: 45
[18:47:53.831891] alsa_open:234 opened device default using format: S32_LE 
sample rate: 44100 mmap: 1
[18:47:53.834080] alsa_open:313 buffer: 40 period: 4 -> buffer size: 1760 
period size: 440
[18:47:53.837922] decode_init:129 init decode
[18:47:53.839697] register_dsd:624 using dsd to decode dsf,dff
[18:47:53.842573] load_faad:592 dlerror: libfaad.so.2: cannot open shared 
object file: No such file or directory
[18:47:53.848564] load_vorbis:295 loaded libvorbisfile.so.3
[18:47:53.850327] register_vorbis:324 using vorbis to decode ogg
[18:47:53.856486] load_flac:258 loaded libFLAC.so.8
[18:47:53.857762] register_flac:286 using flac to decode flc
[18:47:53.859469] register_pcm:382 using pcm to decode aif,pcm
[18:47:53.863411] load_mad:383 loaded libmad.so.0
[18:47:53.864956] register_mad:412 using mad to decode mp3
[18:47:53.868527] discover_server:699 sending discovery
[18:47:54.039972] discover_server:710 got response from: 192.168.38.11:3483
[18:47:54.041418] slimproto:787 connecting to 192.168.38.11:3483
[18:47:54.045684] slimproto:820 connected
[18:47:54.047092] sendHELO:117 mac: b8:27:eb:f9:50:95
[18:47:54.048903] sendHELO:119 cap: 
Model=squeezelite,ModelName=SqueezeLite,AccuratePlayPoints=1,HasDigitalOut=1,Max
SampleRate=384000,dsf,dff,ogg,flc,aif,pcm,mp3
[18:47:54.298969] process:469 strm
[18:47:54.299179] process_strm:232 strm command q
[18:47:54.299281] decode_flush:188 decode flush
[18:47:54.299371] output_flush:414 flush output buffer
[18:47:54.299477] sendSTAT:164 STAT: STMf
[18:47:54.366679] process:469 audg
[18:47:54.369293] process_audg:385 audg gainL: 12032 gainR: 12032 adjust: 1
[18:47:54.370550] process:469 strm
[18:47:54.371804] process_strm:232 strm command s
[18:47:54.372923] process_strm:299 strm s autostart: 0 transition period: 10 
transition type: 0 codec: m
[18:47:54.373182] sendSTAT:164 STAT: STMf
[18:47:54.373965] codec_open:211 codec open: 'm'
[18:47:54.374450] stream_sock:383 connecting to 192.168.38.11:9000
[18:47:54.388660] stream_sock:412 header: GET 
/stream.mp3?player=b8:27:eb:f9:50:95 HTTP/1.0

[18:47:54.389619] sendSTAT:164 STAT: STMc
[18:47:54.390444] process_strm:330 set fade mode: 0
[18:47:54.390746] process:469 audg
[18:47:54.391461] process_audg:385 audg gainL: 12032 gainR: 12032 adjust: 1
[18:47:54.392297] process:469 setd
[18:47:54.393017] sendSETDName:223 set playername: Framboise
[18:47:54.393818] process:469 setd
[18:47:54.394632] process:469 aude
[18:47:54.394856] process_aude:368 enable spdif: 1 dac: 1
[18:47:54.395634] process:469 audg
[18:47:54.397044] process_audg:385 audg gainL: 12032 gainR: 12032 adjust: 1
[18:47:54.539233] stream_thread:176 headers: len: 116
HTTP/1.1 200 OK
Server: Logitech Media Server (7.8.0 - 1395409907)
Connection: close
Content-Type: audio/mpeg

[18:47:54.543111] sendRESP:195 RESP
[18:47:55.581960] sendSTAT:164 STAT: STMl
[18:47:56.017350] process:469 strm
[18:47:56.018923] process_strm:232 strm command u
[18:47:56.020069] process_strm:286 unpause at: 2180180847 now: 2180180803
[18:47:56.021208] sendSTAT:164 STAT: STMr
[18:47:56.073582] _check_lame_header:159 gapless: skip: 2257 samples: 23498244 
delay: 1105 padding: 1451
[18:47:56.076160] mad_decode:242 setting track_start
[18:47:56.077057] mad_decode:271 gapless: skipping 1152 frames at start
[18:47:56.079921] mad_decode:271 gapless: skipping 1105 frames at start
[18:47:56.088068] _output_frames:144 track start sample rate: 44100 
replay_gain: 0
[18:47:57.023595] sendSTAT:164 STAT: STMs
[18:47:57.025061] sendSTAT:164 STAT: STMt
[18:47:57.339241] process:469 strm
[18:47:57.340514] process_strm:232 strm command a
[18:47:57.341640] process_strm:273 skip ahead interval: 24
[18:47:57.358057] _output_frames:67 skip 1058 of 1058 frames
[18:47:57.843290] process:469 strm
[18:47:57.844557] process_strm:232 strm command t
[18:47:57.845690] sendSTAT:164 STAT: STMt
[18:47:58.848113] sendSTAT:164 STAT: STMt
[18:47:59.818308] process:469 strm
[18:47:59.819573] process_strm:232 strm command a
[18:47:59.820697] process_strm:273 skip ahead interval: 10
[18:47:59.837913] _output_frames:67 skip 441 of 441 frames
[18:48:00.822924] sendSTAT:164 STAT: STMt
^C[18:48:01.033433] slimproto_stop:858 slimproto stop
[18:48:01.135426] decode_close:173 close decode
[18:48:01.183131] stream_close:322 close stream
[18:48:01.254258] output_close_alsa:700 close output

Original issue reported on code.google.com by charles....@gmail.com on 8 Apr 2014 at 4:49