Guida a Logitech Media Server, Squeezelite e derivati.

[marcoc1712]

[17:35:32.657064] stream_init:290 init stream
[17:35:32.657319] stream_init:291 streambuf size: 2097152
[17:35:32.660963] output_init_alsa:889 init output
[17:35:32.661029] output_init_alsa:930 device : iec958:CARD=Interface,DEV=0 requested alsa_buffer: 40 alsa_period: 4 format: any mmap: 1
[17:35:32.661050] output_init_common:346 outputbuf size: 3528000
[17:35:32.661138] output_init_common:370 idle timeout: 0
[17:35:32.667794] output_init_common:410 supported rates: 384000 352800 192000 176400 96000 88200 48000 44100
[17:35:32.680359] output_init_alsa:946 memory locked
[17:35:32.680748] output_thread:697 open output device: iec958:CARD=Interface,DEV=0
[17:35:32.680753] output_init_alsa:969 set output sched fifo rt: 45
[17:35:32.681820] alsa_open:367 opening device at: 44100
[17:35:32.681830] decode_init:129 init decode, include codecs: exclude codecs:
[17:35:32.682505] load_faad:610 loaded libfaad.so.2
[17:35:32.682539] register_faad:640 using faad to decode aac
[17:35:32.683452] load_vorbis:300 loaded libvorbisfile.so.3
[17:35:32.683487] register_vorbis:329 using vorbis to decode ogg
[17:35:32.684405] load_flac:266 loaded libFLAC.so.8
[17:35:32.684436] register_flac:294 using flac to decode flc
[17:35:32.684454] register_pcm:424 using pcm to decode aif,pcm
[17:35:32.684887] load_mad:383 loaded libmad.so.0
[17:35:32.684911] register_mad:412 using mad to decode mp3
[17:35:32.685352] discover_server:756 sending discovery
[17:35:32.685425] alsa_open:440 opened device iec958:CARD=Interface,DEV=0 using format: S32_LE sample rate: 44100 mmap: 1
[17:35:32.685498] alsa_open:531 buffer: 40 period: 4 -> buffer size: 1764 period size: 441
[17:35:32.685654] discover_server:767 got response from: 192.168.0.205:3483
[17:35:32.686387] slimproto:849 connecting to 192.168.0.205:3483
[17:35:32.686636] slimproto:882 connected
[17:35:32.686665] slimproto:893 local player
[17:35:32.686684] sendHELO:124 mac: ac:1f:6b:26:ed:32
[17:35:32.686700] sendHELO:126 cap: Model=squeezelite,AccuratePlayPoints=1,HasDigitalOut=1,HasPolarityInversion=1,Firmware=v1.8.4 (R2),ModelName=SqueezeLite-R2,MaxSampleRate=384000,aac,ogg,flc,aif,pcm,mp3,loc
[17:35:32.699548] process:494 strm
[17:35:32.699604] process_strm:256 strm command q
[17:35:32.699623] decode_flush:190 decode flush
[17:35:32.699639] output_flush:423 flush output buffer
[17:35:32.699656] sendSTAT:171 STAT: STMf
[17:35:32.699930] process:494 strm
[17:35:32.699965] process_strm:256 strm command q
[17:35:32.699982] decode_flush:190 decode flush
[17:35:32.699999] output_flush:423 flush output buffer
[17:35:32.700017] sendSTAT:171 STAT: STMf
[17:35:32.700076] process:494 setd
[17:35:32.700108] process:494 setd
[17:35:32.700140] process:494 aude
[17:35:32.700157] process_aude:395 enable spdif: 1 dac: 1
[17:35:32.700565] process:494 audg
[17:35:32.700606] process_audg:413 audg gainL: 65536 gainR: 65536 adjust: 1
[17:35:32.700625] set_volume:251 setting internal gain left: 65536 right: 65536
[17:35:35.001054] process:494 strm
[17:35:35.001114] process_strm:256 strm command t
[17:35:35.001134] sendSTAT:171 STAT: STMt
...
[17:36:18.210777] process_strm:256 strm command s
[17:36:18.210795] process_strm:325 strm s autostart: 1 transition period: 10 transition type: 0 codec: f
[17:36:18.210814] sendSTAT:171 STAT: STMf
[17:36:18.210867] codec_open:218 codec open: 'f'
[17:36:18.210938] stream_sock:384 connecting to 192.168.0.205:9000
[17:36:18.211078] stream_sock:413 header: GET /stream.mp3?player=ac:1f:6b:26:ed:32 HTTP/1.0


[17:36:18.211103] sendSTAT:171 STAT: STMc
[17:36:18.211146] process_strm:357 set fade mode: 0
[17:36:18.211243] process:494 audg
[17:36:18.211264] process_audg:413 audg gainL: 65536 gainR: 65536 adjust: 1
[17:36:18.211281] set_volume:251 setting internal gain left: 65536 right: 65536
[17:36:18.296039] stream_thread:176 headers: len: 137
HTTP/1.1 200 OK
Server: Logitech Media Server (7.9.2 - ARCH-AUR-GIT.20181118.e5c860d)
Connection: close
Content-Type: audio/x-flac


[17:36:18.297117] sendRESP:202 RESP
[17:36:18.318474] write_cb:116 setting track_start
[17:36:18.323817] _output_frames:61 start buffer frames: 90112
[17:36:18.323893] _output_frames:146 track start sample rate: 44100 replay_gain: 0
[17:36:18.343191] sendSTAT:171 STAT: STMs
[17:36:18.343324] sendSTAT:171 STAT: STMt
[17:36:19.344420] sendSTAT:171 STAT: STMt
[17:36:20.345574] sendSTAT:171 STAT: STMt
[17:36:21.346732] sendSTAT:171 STAT: STMt
[17:36:22.000940] process:494 strm
[17:36:22.000992] process_strm:256 strm command t
...
[17:37:14.000626] sendSTAT:171 STAT: STMt
[17:37:14.260483] output_thread:788 pcm wait error: No such device
[17:37:14.260546] output_thread:744 Device iec958:CARD=Interface,DEV=0 no longer available
[17:37:14.264261] alsa_close:286 snd_pcm_close error: No such device
[17:37:14.265324] ALSA parse_card:767 cannot find card 'Interface'
[17:37:14.265380] ALSA _snd_config_evaluate:4555 function snd_func_card_driver returned error: No such device
[17:37:14.265409] ALSA snd_func_concat:392 error evaluating strings
[17:37:14.265433] ALSA _snd_config_evaluate:4555 function snd_func_concat returned error: No such device
[17:37:14.265457] ALSA snd_func_refer:1246 error evaluating name
[17:37:14.265480] ALSA _snd_config_evaluate:4555 function snd_func_refer returned error: No such device
[17:37:14.265505] ALSA snd_config_expand:5034 Evaluate error: No such device
[17:37:14.265536] ALSA snd_pcm_open_noupdate:2565 Unknown PCM iec958:CARD=Interface,DEV=0
[17:37:14.265560] output_thread:684 waiting for device iec958:CARD=Interface,DEV=0 to return
[17:37:15.001736] sendSTAT:171 STAT: STMt
[17:37:16.002887] sendSTAT:171 STAT: STMt
[17:37:17.004006] sendSTAT:171 STAT: STMt
[17:37:18.005125] sendSTAT:171 STAT: STMt
[17:37:18.012519] process:494 strm
[17:37:18.012585] process_strm:256 strm command t
[17:37:18.012605] sendSTAT:171 STAT: STMt
[17:37:19.013688] sendSTAT:171 STAT: STMt
[17:37:19.266735] output_thread:697 open output device: iec958:CARD=Interface,DEV=0
[17:37:19.266780] alsa_open:367 opening device at: 44100
[17:37:19.267822] alsa_open:440 opened device iec958:CARD=Interface,DEV=0 using format: S32_LE sample rate: 44100 mmap: 1
[17:37:19.267879] alsa_open:531 buffer: 40 period: 4 -> buffer size: 1764 period size: 441
[17:37:20.014824] sendSTAT:171 STAT: STMt
[17:37:21.015970] sendSTAT:171 STAT: STMt
[17:37:22.017117] sendSTAT:171 STAT: STMt
[17:37:23.018281] sendSTAT:171 STAT: STMt
[17:37:23.105659] process:494 strm
[17:37:23.105720] process_strm:256 strm command t
[17:37:23.105740] sendSTAT:171 STAT: STMt
[17:37:24.106854] sendSTAT:171 STAT: STMt
[17:37:24.500259] output_thread:788 pcm wait error: No such device
[17:37:24.500316] output_thread:744 Device iec958:CARD=Interface,DEV=0 no longer available
[17:37:24.505271] alsa_close:286 snd_pcm_close error: No such device
[17:37:24.506345] ALSA parse_card:767 cannot find card 'Interface'
...
[17:39:09.707945] output_thread:697 open output device: iec958:CARD=Interface,DEV=0
[17:39:09.712423] alsa_open:367 opening device at: 44100
[17:39:09.713565] alsa_open:440 opened device iec958:CARD=Interface,DEV=0 using format: S32_LE sample rate: 44100 mmap: 1
[17:39:09.713622] alsa_open:531 buffer: 40 period: 4 -> buffer size: 1764 period size: 441
[17:39:09.719888] alsa_open:548 unable to set hw params: No such device or address

It looks like a hardware or configuration error, ALSA loose connection to the device. Double check the USB connections, try to replace the cable and if an external PSU is in use, inspect that is feeding the interface with enough power.

This should not be limited to Squeezelite, all players, aplay included, should produce the same kind of error, could you confirm?

[hifi25nl]

It looks like a hardware or configuration error, ALSA loose connection to the device. Double check the USB connections, try to replace the cable and if an external PSU is in use, inspect that is feeding the interface with enough power.

This should not be limited to Squeezelite, all players, aplay included, should produce the same kind of error, could you confirm?

It could also be that another audio application/service is using the dac. I suggest to disable Roon, hqplayer etc (in audiolinux there is a shortcut or a menu entry for this) and possibly reboot before restarting squeezelite.
I have tested today reinstalling logitechmediaserver-git with squeezelite-r2/squeezelite and all is working fine in both cases.

[marcoc1712]

It could also be that another audio application/service is using the dac. I suggest to disable Roon, hqplayer etc (in audiolinux there is a shortcut or a menu entry for this) and possibly reboot before restarting squeezelite.
I have tested today reinstalling logitechmediaserver-git with squeezelite-r2/squeezelite and all is working fine in both cases.

It could be... If the other application is using "brute force" to gain control of the interface, but the normal behaviour is not this one.

In order to release the ALSA connection you need to add -C 1 to the squeezelite command line, that way, after 1 sec of silence the connection is released and other players could then take control over it.

If you then try to restart the playback in squeezelite, you get an error similar to that one, but - in the posted log - You could see the connection is lost and found back in short time during the playback (1 min after the start), that sound weird to me.