Thank you for your donation!


Cloudsmith graciously provides open-source package management and distribution for our project.


Problem: Static noise when casting via upnp from Audirvana Studio
#11
(03-09-2025, 06:21 PM)Tim Curtis Wrote: Its not obvious to me what might be happening but you can try looking at the MPD log when one of the 192k tracks is playing to see of there any decoder errors.

cat /var/log/mpd/log

There is also a Verbose logging option in MPD Config that prints more detail in the log.

Maybe try copying one of the 192k tracks to a USB stick and see if it plays ok locally.

Here is log when playback stops:


Code:
moode@moode:~ $ tail -f /var/log/mpd/log
Mar 11 21:46 : client: [30] process command "currentsong"
Mar 11 21:46 : client: [30] command returned 0
Mar 11 21:46 : client: [30] process command "close"
Mar 11 21:46 : client: [30] command returned 4
Mar 11 21:46 : client: [30] closed
Mar 11 21:46 : client: [31] opened from 127.0.0.1:51618
Mar 11 21:46 : client: [31] process command "status"
Mar 11 21:46 : client: [31] command returned 0
Mar 11 21:46 : client: [31] process command "idle"
Mar 11 21:46 : client: [31] command returned 1
Mar 11 21:46 : client: [32] opened from 127.0.0.1:55012
Mar 11 21:46 : client: [32] process command "clear"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "repeat "0""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [31] process command "status"
Mar 11 21:46 : client: [31] command returned 0
Mar 11 21:46 : client: [29] process command "status"
Mar 11 21:46 : client: [29] command returned 0
Mar 11 21:46 : client: [32] process command "random "0""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [27] process command "status"
Mar 11 21:46 : client: [27] command returned 0
Mar 11 21:46 : client: [27] process command "currentsong"
Mar 11 21:46 : client: [27] command returned 0
Mar 11 21:46 : client: [29] process command "currentsong"
Mar 11 21:46 : client: [29] command returned 0
Mar 11 21:46 : client: [31] process command "currentsong"
Mar 11 21:46 : client: [31] command returned 0
Mar 11 21:46 : client: [32] process command "single "0""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "consume "0""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "addid "http://192.168.0.74:49152/au                                                              dirvana/audio_f_25.wav" "0""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "addtagid "16" "Artist" "Looking Gla                                                              ss""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "addtagid "16" "Album" "Looking Glas                                                              s""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "addtagid "16" "Title" "Brandy (You'                                                              re a Fine Girl)""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "addtagid "16" "Track" "2""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "addtagid "16" "Comment" "client=upm                                                              pdcli;""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "status"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "stop"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "status"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "play"
Mar 11 21:46 : playlist: play 0:"http://192.168.0.74:49152/audirvana/audio_f_25.                                                              wav"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "status"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "currentsong"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "playlistinfo "1""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : decoder_thread: probing plugin ffmpeg
Mar 11 21:46 : ffmpeg/wav: Format wav probed with size=2048 and score=99
Mar 11 21:46 : client: [31] process command "close"
Mar 11 21:46 : client: [31] command returned 4
Mar 11 21:46 : client: [31] closed
Mar 11 21:46 : client: [29] process command "close"
Mar 11 21:46 : client: [29] command returned 4
Mar 11 21:46 : client: [29] closed
Mar 11 21:46 : client: [27] process command "close"
Mar 11 21:46 : client: [27] command returned 4
Mar 11 21:46 : client: [27] closed
Mar 11 21:46 : ffmpeg: detected input format 'wav' (WAV / WAVE (Waveform Audio))
Mar 11 21:46 : ffmpeg/wav: Before avformat_find_stream_info() pos: 44 bytes read                                                              :65580 seeks:1 nb_streams:1
Mar 11 21:46 : ffmpeg/wav: parser not found for codec pcm_s24le, packets or time                                                              s may be invalid.
Mar 11 21:46 : ffmpeg/wav: parser not found for codec pcm_s24le, packets or time                                                              s may be invalid.
Mar 11 21:46 : ffmpeg/wav: All info found
Mar 11 21:46 : ffmpeg/wav: stream 0: start_time: NOPTS duration: 189.045
Mar 11 21:46 : ffmpeg/wav: format: start_time: NOPTS duration: 189.045 (estimate                                                               from stream) bitrate=9216 kb/s
Mar 11 21:46 : ffmpeg/wav: After avformat_find_stream_info() pos: 204644 bytes r                                                              ead:272816 seeks:1 frames:50
Mar 11 21:46 : ffmpeg: codec 'pcm_s24le'
Mar 11 21:46 : decoder: audio_format=192000:32:2, seekable=true
Mar 11 21:46 : alsa_output: opened _audioout type=COPY
Mar 11 21:46 : alsa_output: buffer: size=48..384000 time=250..2000000
Mar 11 21:46 : alsa_output: period: size=24..192000 time=125..1000000
Mar 11 21:46 : alsa_output: default period_time = buffer_time/4 = 500000/4 = 125                                                              000
Mar 11 21:46 : client: [33] opened from 127.0.0.1:55018
Mar 11 21:46 : client: [33] process command "status"
Mar 11 21:46 : alsa_output: format=S32_LE (Signed 32 bit Little Endian)
Mar 11 21:46 : alsa_output: buffer_size=96000 period_size=24000
Mar 11 21:46 : output: opened "ALSA Default" (alsa) audio_format=192000:32:2
Mar 11 21:46 : client: [33] command returned 0
Mar 11 21:46 : client: [33] process command "currentsong"
Mar 11 21:46 : client: [33] command returned 0
Mar 11 21:46 : client: [4] process command "idle playlist player mixer options"
Mar 11 21:46 : client: [4] command returned 1
Mar 11 21:46 : client: [32] process command "status"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "currentsong"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "playlistinfo "1""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "status"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "currentsong"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "playlistinfo "1""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [33] process command "close"
Mar 11 21:46 : client: [33] command returned 4
Mar 11 21:46 : client: [33] closed
Mar 11 21:46 : client: [34] opened from 127.0.0.1:55022
Mar 11 21:46 : client: [34] process command "status"
Mar 11 21:46 : client: [34] command returned 0
Mar 11 21:46 : client: [34] process command "idle"
Mar 11 21:46 : client: [34] command returned 1
Mar 11 21:46 : client: [4] process command "idle playlist player mixer options"
Mar 11 21:46 : client: [4] command returned 1
Mar 11 21:46 : client: [32] process command "status"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "currentsong"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "playlistinfo "1""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "status"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "currentsong"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "playlistinfo "1""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "status"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "currentsong"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "playlistinfo "1""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "status"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "currentsong"
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:46 : client: [32] process command "playlistinfo "1""
Mar 11 21:46 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "status"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "currentsong"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "playlistinfo "1""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "status"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "currentsong"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "playlistinfo "1""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "status"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "currentsong"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "playlistinfo "1""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "status"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "currentsong"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "playlistinfo "1""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "status"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "currentsong"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "playlistinfo "1""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "repeat "0""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "random "0""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "single "0""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "consume "0""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "addid "http://192.168.0.74:49152/au                                                              dirvana/audio_f_26.wav" "1""
Mar 11 21:47 : playlist: queue song 1:"http://192.168.0.74:49152/audirvana/audio                                                              _f_26.wav"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "addtagid "17" "Artist" "Jet""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [34] process command "status"
Mar 11 21:47 : client: [34] command returned 0
Mar 11 21:47 : client: [32] process command "addtagid "17" "Album" "Get Born""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "addtagid "17" "Title" "Are You Gonn                                                              a Be My Girl""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "addtagid "17" "Track" "2""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "addtagid "17" "Comment" "client=upm                                                              pdcli;""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [34] process command "currentsong"
Mar 11 21:47 : client: [34] command returned 0
Mar 11 21:47 : client: [32] process command "status"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "currentsong"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "playlistinfo "1""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "status"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "currentsong"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "playlistinfo "1""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "status"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "currentsong"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "playlistinfo "1""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [34] process command "close"
Mar 11 21:47 : client: [34] command returned 4
Mar 11 21:47 : client: [34] closed
Mar 11 21:47 : client: [35] opened from 127.0.0.1:40572
Mar 11 21:47 : client: [35] process command "status"
Mar 11 21:47 : client: [35] command returned 0
Mar 11 21:47 : client: [35] process command "idle"
Mar 11 21:47 : client: [35] command returned 1
Mar 11 21:47 : client: [4] process command "idle playlist player mixer options"
Mar 11 21:47 : client: [4] command returned 1
Mar 11 21:47 : client: [32] process command "status"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "currentsong"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "playlistinfo "1""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "status"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "currentsong"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "playlistinfo "1""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [4] process command "idle playlist player mixer options"
Mar 11 21:47 : client: [4] command returned 1
Mar 11 21:47 : client: [32] process command "status"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "currentsong"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "playlistinfo "1""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "pause"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [32] process command "status"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [35] process command "status"
Mar 11 21:47 : client: [35] command returned 0
Mar 11 21:47 : client: [32] process command "currentsong"
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : client: [35] process command "currentsong"
Mar 11 21:47 : client: [35] command returned 0
Mar 11 21:47 : client: [32] process command "playlistinfo "1""
Mar 11 21:47 : client: [32] command returned 0
Mar 11 21:47 : output: closed "ALSA Default" (alsa)
Mar 11 21:47 : client: [4] process command "idle playlist player mixer options"
Mar 11 21:47 : client: [4] command returned 1
Mar 11 21:47 : client: [35] process command "close"
Mar 11 21:47 : client: [35] command returned 4
Mar 11 21:47 : client: [35] closed
Mar 11 21:47 : client: [36] opened from 127.0.0.1:40584
Mar 11 21:47 : client: [36] process command "status"
Mar 11 21:47 : client: [36] command returned 0
Mar 11 21:47 : client: [36] process command "idle"
Mar 11 21:47 : client: [36] command returned 1
Reply
#12
Quite a few odd looking errors. I'm not exactly sure how to interpret them.

Since the file plays ok locally it might be worth comparing the mpd log in that case.
Enjoy the Music!
moodeaudio.org | Mastodon Feed | GitHub
Reply
#13
@Maverick565

Back in your first post, you said

Quote:2. Cast from other upnp apps - from Usb Audio Player pro no issues, from Bubble upnp - no issues


Does this mean you could cast your troublesome 192 kpbs tracks using these control points and they played?

That would seem to place your issue at the intersection of Audirvana and upmpdcli. You may want to ask their maintainers.

You can get logs from upmpdcli but you'll have to hand-edit its configuration file /etc/upmpdcli.conf. It's owned by root so you have to edit as superuser (sudo vi or sudo nano, for example). The options you are looking for are in the general parameters section at the top of the file.

I don't use the UPnP renderer except to test that it still works when new moOde releases are pending. ISTR that when I tried upmpdcli logging I had trouble getting upmpdcli to log to the /var/log directory so I throw the logs into /tmp instead. I also don't recall what log levels beyond 2 get you but even at log level 2, the libnpupnp log gets big fast.

Not at all sure what to expect can be found this way.

Regards,
Kent
Reply
#14
(Today, 12:26 AM)TheOldPresbyope Wrote: @Maverick565

Back in your first post, you said

Quote:2. Cast from other upnp apps - from Usb Audio Player pro no issues, from Bubble upnp - no issues


Does this mean you could cast your troublesome 192 kpbs tracks using these control points and they played?

That would seem to place your issue at the intersection of Audirvana and upmpdcli. You may want to ask their maintainers.

You can get logs from upmpdcli but you'll have to hand-edit its configuration file /etc/upmpdcli.conf. It's owned by root so you have to edit as superuser (sudo vi or sudo nano, for example). The options you are looking for are in the general parameters section at the top of the file.

I don't use the UPnP renderer except to test that it still works when new moOde releases are pending. ISTR that when I tried upmpdcli logging I had trouble getting upmpdcli to log to the /var/log directory so I throw the logs into /tmp instead. I also don't recall what log levels beyond 2 get you but even at log level 2, the libnpupnp log gets big fast.

Not at all sure what to expect can be found this way.

Regards,
Kent

Yes, 192khz tracks playing good from that control points. I was tried also Jriver media center from same PC on which i have Audirvana and there is also no issues.
Reply
#15
(8 hours ago)Maverick565 Wrote: Yes, 192khz tracks playing good from that control points. I was tried also Jriver media center from same PC on which i have Audirvana and there is also no issues.

Hence, as Kent said, your problem is an Audirvana + upnpdcli one... looking/asking in their forums (for sure they have one) should be your first choice.
Reply


Forum Jump: