Re: [Jack-Devel] jack muted but plain alsa works

PrevNext  Index
DateTue, 26 Jun 2012 01:51:49 +0200
From Jan Kohnert <[hidden] at jankoh dot dyndns dot org>
To[hidden] at lists dot jackaudio dot org
In-Reply-ToPaul Davis Re: [Jack-Devel] jack muted but plain alsa works
Follow-UpJan Kohnert Re: [Jack-Devel] jack muted but plain alsa works
Hi there,

Am Montag, 25. Juni 2012, 18:05:36 schrieben Sie:
On Mon, Jun 25, 2012 at 6:00 PM, Jan Kohnert <nospam001-
[hidden]> wrote:
> > On Wed, Jun 20, 2012 at 09:45:37PM +0200, Jan Kohnert wrote:
> > > Terminating jackd just after the last test and playing a song using
> > > audacity/alsa instead of audacity/jack gave me sound.
> > > Any further ideas?
> > 
> > just getting started to dive into jacks code, here's another snippet of
> > verbose output using mplayer as a client:
> 
> this is not likely to reveal anything. 
> 
> you should be looking at the files in /proc/asound/cardN/pcmM/....
> 
> (for various values of N and M) and comparing the h/w and s/w configuration
> when JACK runs versus when "working" apps run.

well, I thought so, but anyway worth trying. :) I just installed jack-1 and it 
works like a charm using the same configuration:

jankoh@kohni ~ $ jackd -R -P 90 -v -c h -d alsa -n 3 -r 48000 -d hw:Blackjack
jackd 0.121.3
Copyright 2001-2009 Paul Davis, Stephane Letz, Jack O'Quinn, Torben Hohn and 
others.
jackd comes with ABSOLUTELY NO WARRANTY
This is free software, and you are welcome to redistribute it
under certain conditions; see the file COPYING for details

getting driver descriptor from /usr/lib64/jack/jack_net.so
getting driver descriptor from /usr/lib64/jack/jack_alsa.so
getting driver descriptor from /usr/lib64/jack/jack_alsa_midi.so
getting driver descriptor from /usr/lib64/jack/jack_dummy.so
JACK compiled with System V SHM support.
server `default' registered
registered builtin port type 32 bit float mono audio
registered builtin port type 8 bit raw midi
clock source = hpet
loading driver ..
start poll on 3 fd's
apparent rate = 48000
new client: alsa_pcm, id = 1 type 1 @ 0x21eaf00 fd = -1
creating alsa driver ... hw:Blackjack|hw:Blackjack|1024|3|48000|0|0|nomon|
swmeter|-|32bit
configuring for 48000Hz, period = 1024 frames (21.3 ms), buffer = 3 periods
ALSA: final selected sample format for capture: 24bit little-endian
ALSA: use 3 periods for capture
ALSA: final selected sample format for playback: 24bit little-endian
ALSA: use 3 periods for playback
new buffer size 1024
resizing port buffer segment for type 0, one buffer = 4096 bytes
resizing port buffer segment for type 1, one buffer = 4096 bytes
registered port system:capture_1, offset = 4096
registered port system:capture_2, offset = 8192
registered port system:playback_1, offset = 0
registered port system:playback_2, offset = 0
++ jack_sort_graph
++ jack_rechain_graph():
-- jack_rechain_graph()
-- jack_sort_graph
29067 waiting for signals
load = 0.0188 max usecs: 8.000, spare = 21325.000
load = 0.0328 max usecs: 10.000, spare = 21323.000
load = 0.0422 max usecs: 11.000, spare = 21322.000
load = 0.0445 max usecs: 10.000, spare = 21323.000
server thread back from poll
new client: MPlayer [29072], id = 2 type 2 @ 0x7f37153b0000 fd = 13
start poll on 4 fd's
server thread back from poll
new client MPlayer [29072] using 14 for events
start poll on 4 fd's
server thread back from poll
registered port MPlayer [29072]:out_0, offset = 12288
start poll on 4 fd's
server thread back from poll
registered port MPlayer [29072]:out_1, offset = 16384
start poll on 4 fd's
server thread back from poll
++ jack_sort_graph
++ jack_rechain_graph():
+++ client is now MPlayer [29072] active ? 1
client MPlayer [29072]: start_fd=8, execution_order=0.
client event poll on 14 for MPlayer [29072] starts at 301696538
back from client event poll after 99 usecs
client MPlayer [29072]: wait_fd=10, execution_order=1 (last client).
-- jack_rechain_graph()
client event poll on 14 for MPlayer [29072] starts at 301696670
back from client event poll after 47 usecs
client event poll on 14 for MPlayer [29072] starts at 301696736
back from client event poll after 34 usecs
-- jack_sort_graph
client event poll on 14 for MPlayer [29072] starts at 301696790
back from client event poll after 356 usecs
client event poll on 14 for MPlayer [29072] starts at 301697185
back from client event poll after 300 usecs
client event poll on 14 for MPlayer [29072] starts at 301697504
back from client event poll after 33 usecs
start poll on 4 fd's
server thread back from poll
connect MPlayer [29072]:out_0 and system:playback_1 (output)
client event poll on 14 for MPlayer [29072] starts at 301697652
back from client event poll after 51 usecs
++ jack_sort_graph
++ jack_rechain_graph():
+++ client is now MPlayer [29072] active ? 1
client MPlayer [29072]: start_fd=8, execution_order=0.
client event poll on 14 for MPlayer [29072] starts at 301697785
back from client event poll after 69 usecs
client MPlayer [29072]: wait_fd=10, execution_order=1 (last client).
-- jack_rechain_graph()
client event poll on 14 for MPlayer [29072] starts at 301697889
back from client event poll after 25 usecs
client event poll on 14 for MPlayer [29072] starts at 301697933
back from client event poll after 30 usecs
-- jack_sort_graph
start poll on 4 fd's
server thread back from poll
connect MPlayer [29072]:out_1 and system:playback_2 (output)
client event poll on 14 for MPlayer [29072] starts at 301698093
back from client event poll after 36 usecs
++ jack_sort_graph
++ jack_rechain_graph():
+++ client is now MPlayer [29072] active ? 1
client MPlayer [29072]: start_fd=8, execution_order=0.
client event poll on 14 for MPlayer [29072] starts at 301698262
back from client event poll after 12 usecs
client MPlayer [29072]: wait_fd=10, execution_order=1 (last client).
-- jack_rechain_graph()
client event poll on 14 for MPlayer [29072] starts at 301698325
back from client event poll after 11 usecs
client event poll on 14 for MPlayer [29072] starts at 301698372
back from client event poll after 11 usecs
-- jack_sort_graph
start poll on 4 fd's
load = 0.3129 max usecs: 124.000, spare = 21209.000
load = 0.5268 max usecs: 158.000, spare = 21175.000
load = 0.5564 max usecs: 125.000, spare = 21208.000
load = 0.8055 max usecs: 225.000, spare = 21108.000
load = 0.7004 max usecs: 127.000, spare = 21206.000
load = 0.6362 max usecs: 122.000, spare = 21211.000
load = 0.5993 max usecs: 120.000, spare = 21213.000
load = 0.6911 max usecs: 167.000, spare = 21166.000
load = 0.8049 max usecs: 196.000, spare = 21137.000
load = 0.7259 max usecs: 138.000, spare = 21195.000
load = 0.6630 max usecs: 128.000, spare = 21205.000
load = 0.6174 max usecs: 122.000, spare = 21211.000
load = 0.6040 max usecs: 126.000, spare = 21207.000
load = 0.5950 max usecs: 125.000, spare = 21208.000
load = 0.7709 max usecs: 202.000, spare = 21131.000
load = 0.6855 max usecs: 128.000, spare = 21205.000
load = 0.6310 max usecs: 123.000, spare = 21210.000
load = 0.6507 max usecs: 143.000, spare = 21190.000
load = 0.7074 max usecs: 163.000, spare = 21170.000
load = 0.7053 max usecs: 150.000, spare = 21183.000
load = 0.8565 max usecs: 215.000, spare = 21118.000
load = 0.7166 max usecs: 123.000, spare = 21210.000
load = 0.6442 max usecs: 122.000, spare = 21211.000
load = 0.6081 max usecs: 122.000, spare = 21211.000
load = 0.6861 max usecs: 163.000, spare = 21170.000
load = 0.6313 max usecs: 123.000, spare = 21210.000
load = 0.6110 max usecs: 126.000, spare = 21207.000
load = 0.5891 max usecs: 121.000, spare = 21212.000
load = 0.5852 max usecs: 124.000, spare = 21209.000
load = 0.5809 max usecs: 123.000, spare = 21210.000
load = 0.5811 max usecs: 124.000, spare = 21209.000
load = 0.7616 max usecs: 201.000, spare = 21132.000
load = 0.6714 max usecs: 124.000, spare = 21209.000
load = 0.6146 max usecs: 119.000, spare = 21214.000
load = 0.5909 max usecs: 121.000, spare = 21212.000
load = 0.6072 max usecs: 133.000, spare = 21200.000
load = 0.5895 max usecs: 122.000, spare = 21211.000
load = 0.6018 max usecs: 131.000, spare = 21202.000
load = 0.6126 max usecs: 133.000, spare = 21200.000
load = 0.5923 max usecs: 122.000, spare = 21211.000
load = 0.5821 max usecs: 122.000, spare = 21211.000
load = 0.6754 max usecs: 164.000, spare = 21169.000
load = 0.7854 max usecs: 191.000, spare = 21142.000
load = 0.7068 max usecs: 134.000, spare = 21199.000
load = 9.9582 max usecs: 4098.000, spare = 17235.000
load = 5.3330 max usecs: 151.000, spare = 21182.000
load = 3.0228 max usecs: 152.000, spare = 21181.000
load = 1.8536 max usecs: 146.000, spare = 21187.000
load = 1.4471 max usecs: 222.000, spare = 21111.000
load = 1.0048 max usecs: 120.000, spare = 21213.000
load = 0.8024 max usecs: 128.000, spare = 21205.000
load = 0.6965 max usecs: 126.000, spare = 21207.000
load = 0.8123 max usecs: 198.000, spare = 21135.000
server thread back from poll
DIS-connect MPlayer [29072]:out_1 and system:playback_2
client event poll on 14 for MPlayer [29072] starts at 356713646
back from client event poll after 88 usecs
++ jack_rechain_graph():
+++ client is now MPlayer [29072] active ? 1
client MPlayer [29072]: start_fd=8, execution_order=0.
client event poll on 14 for MPlayer [29072] starts at 356713809
back from client event poll after 77 usecs
client MPlayer [29072]: wait_fd=10, execution_order=1 (last client).
-- jack_rechain_graph()
client event poll on 14 for MPlayer [29072] starts at 356713918
back from client event poll after 30 usecs
client event poll on 14 for MPlayer [29072] starts at 356713970
back from client event poll after 26 usecs
-- jack_sort_graph
client event poll on 14 for MPlayer [29072] starts at 356714027
back from client event poll after 35 usecs
++ jack_sort_graph
++ jack_rechain_graph():
+++ client is now MPlayer [29072] active ? 1
client MPlayer [29072]: start_fd=8, execution_order=0.
client event poll on 14 for MPlayer [29072] starts at 356714146
back from client event poll after 58 usecs
client MPlayer [29072]: wait_fd=10, execution_order=1 (last client).
-- jack_rechain_graph()
client event poll on 14 for MPlayer [29072] starts at 356714250
back from client event poll after 31 usecs
client event poll on 14 for MPlayer [29072] starts at 356714306
back from client event poll after 30 usecs
-- jack_sort_graph
+++ deactivate MPlayer [29072]
++ jack_sort_graph
++ jack_rechain_graph():
+++ client is now MPlayer [29072] active ? 0
-- jack_rechain_graph()
-- jack_sort_graph
start poll on 4 fd's
server thread back from poll
marking client MPlayer [29072] with SOCKET error state = Finished errors = 0
waking server thread
trying to lock graph to remove 1 problems
we have problem clients (problems = 1
++ Removing failed clients ...
client alsa_pcm error status 0
client MPlayer [29072] error status 10000000
removing failed client MPlayer [29072] state = Finished errors = 10000000
removing client "MPlayer [29072]"
removing client "MPlayer [29072]" from the processing chain
+++ deactivate MPlayer [29072]
++ jack_sort_graph
++ jack_rechain_graph():
-- jack_rechain_graph()
-- jack_sort_graph
-- Removing failed clients ...
after removing clients, problems = 0
start poll on 3 fd's
server thread back from poll
start poll on 3 fd's
load = 0.4366 max usecs: 13.000, spare = 21320.000
load = 0.2418 max usecs: 10.000, spare = 21323.000
load = 0.1467 max usecs: 11.000, spare = 21322.000
load = 0.0897 max usecs: 7.000, spare = 21326.000
load = 0.0683 max usecs: 10.000, spare = 21323.000
load = 0.0693 max usecs: 15.000, spare = 21318.000
load = 0.0628 max usecs: 12.000, spare = 21321.000
load = 0.0478 max usecs: 7.000, spare = 21326.000
^Cjack main caught signal 2
starting server engine shutdown
stopping driver
server thread back from poll
unloading driver
freeing shared port segments
stopping server thread
stopping watchdog thread
max usecs: 4098.000, engine deleted
WARNING: 5 message buffer overruns!
cleaning up shared memory
cleaning up files
unregistering server `default'
jankoh@kohni ~ $ 

So I guess, this is a bug in jack-2's code. If you're willing to search what 
the problem actually is, I will give any help I can, but so far, I'm happy to 
got it working. :)

-- 
MfG Jan
PrevNext  Index

1340668325.23943_0.ltw:2,a <446892070.UQtakXo0q1 at kohni>