[PlanetCCRMA] about Fedora updates

[Niels Mayer]

Fernando -- Thank you for your help! I had long fixed the issues with
improper parameters to Jack, so your message forced me to revisit all my old
assumptions regarding CCRMA's Jack by re-adding CCRMA to my repos and
updating to
  jack-audio-connection-kit.i686 0:1.9.4-1.fc12.ccrma

  jack-audio-connection-kit.x86_64 0:1.9.4-1.fc12.ccrma

  jack-audio-connection-kit-example-clients.x86_64 0:1.9.4-1.fc12.ccrma


jack 0:1.9.4-1.fc12 now starts correctly, and outputs a concerning error
message, however it works, and worked beautifully until it crashed along
with rosegarden in the "is it really realtime" part of my test. While
running, it gave fewer of those inconsequential Xruns that I get whenever I
have jack playing back music and decide to type an email message in gmail.

The concerning error message is "Cannot use real-time scheduling (RR/60)(1:
Operation not permitted) // AcquireSelfRealTime error" Prior, it says "JACK
server starting in realtime mode with priority 60."

Despite the error messages, I see the "RT" indicator flashing in the
qjackctl display, and even starting mythtv and simultaneously watching an HD
channel during music playback (something realtime intensive to test
nonrealtimeness of music playback) only causes **** alsa_pcm: xrun of at
least 0.666 msecs 13:39:04.515 XRUN callback (4)." once, when mythtv starts
up while Hydrogen is playing. A second run using Rosegarden 10 without all
the torture testing resulted in 0 x-runs and no crashes. I will continue
testing further w/ more audio intensive apps later (these were tests were
mostly MIDI, or one softsynth and sequencer, which is not very challenging).

Here's the results and logs from the first run that resulted in a crash, and
a second successful run with Rosegarden (with mythfrontend no longer running
and displaying off-air HD channel, and also with cpu scaling set to
"performance" mode). The first run had both mythtv playing and was running
in CPU "ondemand" mode (0.8G->3.4G CPU freq scaling) for maximum realtime
torture of Jack. (yes I realize cpu freq scaling isn't helpful to realtime
performance).

12:58:54.643 Logging started --- Sat Mar 13 12:58:54 2010 ---
12:58:54.684 Patchbay activated.
12:58:54.699 Statistics reset.
12:58:54.734 Startup script...
12:58:54.734 artsshell -q terminate
Cannot connect to server socket err = No such file or directory
Cannot connect to server socket
jack server is not running or cannot be started
Cannot connect to server socket err = No such file or directory
Cannot connect to server socket
jack server is not running or cannot be started
12:58:54.779 ALSA connection graph change.
12:58:55.503 Startup script terminated with exit status=256.
12:58:55.503 JACK is starting...
12:58:55.504 /usr/bin/jackd -R -dalsa -r44100 -p256 -n2 -D -Chw:M66 -Phw:M66
-Xseq -zs -H -M
12:58:55.507 JACK was started with PID=31183.
12:58:55.705 ALSA active patchbay scan...
12:58:55.708 ALSA connection change.
no message buffer overruns
no message buffer overruns
12:58:55.909 ALSA active patchbay scan...
jackdmp 1.9.4
Copyright 2001-2005 Paul Davis and others.
Copyright 2004-2009 Grame.
jackdmp 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
JACK server starting in realtime mode with priority 60
audio_reservation_init
Acquire audio card Audio2
creating alsa driver ... hw:M66|hw:M66|256|2|44100|0|0|hwmon|hwmeter|-|32bit
Using ALSA driver ICE1712 running on card 2 - M Audio Delta 66 at 0xe880,
irq 21
configuring for 44100Hz, period = 256 frames (5.8 ms), buffer = 2 periods
ALSA: final selected sample format for capture: 32bit integer little-endian
ALSA: use 2 periods for capture
ALSA: final selected sample format for playback: 32bit integer little-endian
ALSA: use 2 periods for playback
port created: Midi-Through:midi/playback_1
port created: Midi-Through:midi/capture_1
port created: MT4:midi/playback_1
port created: MT4:midi/capture_1
port created: MT4:midi/playback_2
port created: MT4:midi/capture_2
port created: MT4:midi/playback_3
port created: MT4:midi/capture_3
port created: MT4:midi/capture_4
port created: MT4:midi/capture_5
port created: BCD3000:midi/playback_1
port created: BCD3000:midi/capture_1
port created: UM-2:midi/playback_1
port created: UM-2:midi/capture_1
port created: UM-2:midi/playback_2
port created: UM-2:midi/capture_2
Cannot use real-time scheduling (RR/60)(1: Operation not permitted)
AcquireSelfRealTime error
12:58:55.965 ALSA connection graph change.
12:58:56.110 ALSA active patchbay scan...
12:58:57.762 Server configuration saved to "/home/npm/.jackdrc".
12:58:57.765 Statistics reset.
12:58:57.772 Client activated.
12:58:57.776 JACK connection change.
12:58:57.979 JACK active patchbay scan...
13:28:47.600 JACK connection graph change.
13:28:47.614 JACK active patchbay scan...
13:28:47.630 ALSA connection graph change.
13:28:47.818 ALSA active patchbay scan...
13:28:48.761 JACK connection graph change.
13:28:48.784 ALSA connection graph change.
port created: Hydrogen:midi/capture_1
13:28:48.821 JACK active patchbay scan...
13:28:48.821 ALSA active patchbay scan...
13:28:48.822 JACK connection change.
13:28:48.824 ALSA connection change.
13:28:49.024 JACK active patchbay scan...
13:28:49.025 ALSA active patchbay scan...
13:29:09.845 Transport start.
JackEngine::XRun: client = Hydrogen was not run: state = 1
JackAudioDriver::ProcessAsync Process error
13:31:19.160 XRUN callback (1).
13:34:14.405 XRUN callback (2).
JackEngine::XRun: client = Hydrogen was not run: state = 2
JackAudioDriver::ProcessAsync Process error
13:36:49.190 XRUN callback (3).
JackEngine::XRun: client = Hydrogen was not run: state = 1
JackAudioDriver::ProcessAsync Process error
alsa_driver_xrun_recovery
**** alsa_pcm: xrun of at least 0.666 msecs
13:39:04.515 XRUN callback (4).
JackAudioDriver::ProcessAsync: read error, skip cycle
13:43:38.316 ALSA connection graph change.
13:43:38.336 JACK connection graph change.
port deleted: Hydrogen:midi/capture_1
13:43:38.359 JACK active patchbay scan...
13:43:38.361 ALSA active patchbay scan...
13:43:38.363 JACK connection change.
13:43:38.367 ALSA connection change.
13:43:38.569 JACK active patchbay scan...
13:43:38.571 ALSA active patchbay scan...
13:43:39.176 Transport stop.
13:43:41.433 JACK connection graph change.
13:43:41.447 ALSA connection graph change.
port created: rosegarden:midi/capture_1
port created: rosegarden:midi/playback_2
port created: rosegarden:midi/playback_3
port created: rosegarden:midi/capture_3
port created: rosegarden:midi/playback_4
13:43:41.580 JACK active patchbay scan...
13:43:41.582 ALSA active patchbay scan...
13:43:41.586 JACK connection change.
13:43:41.600 ALSA connection change.
13:43:41.601 JACK connection graph change.
port created: rosegarden:midi/playback_5
port created: rosegarden:midi/playback_6
port created: rosegarden:midi/playback_7
13:43:41.614 ALSA connection graph change.
13:43:41.801 JACK active patchbay scan...
13:43:41.802 ALSA active patchbay scan...
13:43:41.803 JACK connection change.
13:43:41.803 ALSA connection change.
13:43:42.007 JACK active patchbay scan...
13:43:42.010 ALSA active patchbay scan...
13:43:42.528 ALSA connection graph change.
13:43:42.615 ALSA active patchbay scan...
13:44:29.633 ALSA connection graph change.
13:44:29.641 JACK connection graph change.
port deleted: rosegarden:midi/playback_4
port deleted: rosegarden:midi/playback_5
port deleted: rosegarden:midi/playback_6
port deleted: rosegarden:midi/playback_7
13:44:29.667 JACK active patchbay scan...
13:44:29.670 ALSA active patchbay scan...
13:44:29.674 JACK connection change.
13:44:29.677 ALSA connection change.
13:44:29.746 ALSA connection graph change.
13:44:29.751 JACK connection graph change.
port created: rosegarden:midi/playback_4
13:44:29.878 JACK active patchbay scan...
13:44:29.879 ALSA active patchbay scan...
13:44:29.880 JACK connection change.
13:44:29.881 ALSA connection change.
13:44:30.081 JACK active patchbay scan...
13:44:30.084 ALSA active patchbay scan...
13:44:36.842 ALSA connection graph change.
13:44:36.850 JACK connection graph change.
port deleted: rosegarden:midi/playback_4
13:44:36.896 JACK active patchbay scan...
13:44:36.899 ALSA active patchbay scan...
13:44:36.905 JACK connection change.
13:44:36.907 ALSA connection change.
13:44:36.909 JACK connection graph change.
port created: rosegarden:midi/playback_4
13:44:36.915 ALSA connection graph change.
port created: rosegarden:midi/playback_5
port created: rosegarden:midi/playback_6
port created: rosegarden:midi/playback_7
13:44:37.109 JACK active patchbay scan...
13:44:37.111 ALSA active patchbay scan...
13:44:37.116 JACK connection change.
13:44:37.119 ALSA connection change.
13:44:37.320 JACK active patchbay scan...
13:44:37.322 ALSA active patchbay scan...
13:44:39.530 Transport start.
13:45:17.807 Transport stop.
13:45:20.010 Transport start.
13:46:50.307 Transport stop.
13:47:24.147 ALSA connection graph change.
13:47:24.156 JACK connection graph change.
port deleted: rosegarden:midi/playback_4
port deleted: rosegarden:midi/playback_5
port deleted: rosegarden:midi/playback_6
port deleted: rosegarden:midi/playback_7
port created: rosegarden:midi/playback_4
port created: rosegarden:midi/playback_5
port created: rosegarden:midi/playback_6
13:47:24.221 JACK active patchbay scan...
13:47:24.222 ALSA active patchbay scan...
13:47:24.223 JACK connection change.
13:47:24.229 ALSA connection graph change.
13:47:24.230 JACK connection graph change.
port created: rosegarden:midi/playback_7
13:47:24.424 JACK active patchbay scan...
13:47:24.427 ALSA active patchbay scan...
13:47:24.432 JACK connection change.
13:47:24.634 JACK active patchbay scan...
13:47:47.574 ALSA connection graph change.
13:47:47.583 JACK connection graph change.
port deleted: rosegarden:midi/playback_4
port deleted: rosegarden:midi/playback_5
port deleted: rosegarden:midi/playback_6
port deleted: rosegarden:midi/playback_7
port created: rosegarden:midi/playback_4
port created: rosegarden:midi/playback_5
port created: rosegarden:midi/playback_6
port created: rosegarden:midi/playback_7
13:47:47.663 JACK active patchbay scan...
13:47:47.666 ALSA active patchbay scan...
13:47:47.670 JACK connection change.
13:47:47.672 JACK connection graph change.
13:47:47.872 JACK active patchbay scan...
13:47:54.684 Transport start.
13:50:41.064 Transport stop.
JackEngine::XRun: client = rosegarden was not run: state = 1
JackAudioDriver::ProcessAsync Process error
13:50:57.644 XRUN callback (5).
JackEngine::XRun: client = rosegarden was not run: state = 1
JackAudioDriver::ProcessAsync Process error
   [...]
JackEngine::XRun: client = rosegarden was not run: state = 1
JackAudioDriver::ProcessAsync Process error
JackAudioDriver::ProcessAsync Process error
JackEngine::XRun: client = rosegarden was not run: state = 1
   [...]
JackAudioDriver::ProcessAsync Process error
JackAudioDriver::ProcessAsync Process error
  [...]
JackAudioDriver::ProcessAsync Process error
13:50:59.289 XRUN callback (52 skipped).
  [...]
JackAudioDriver::ProcessAsync Process error
JackEngine::XRun: client = rosegarden was not run: state = 1
JackAudioDriver::ProcessAsync Process error
13:51:00.084 XRUN callback (58).
Cannot write socket fd = 25 err = Connection reset by peer
Cannot write socket fd = 25 err = Broken pipe
Cannot write socket fd = 25 err = Broken pipe
Cannot write socket fd = 25 err = Broken pipe
Cannot write socket fd = 25 err = Broken pipe
Cannot write socket fd = 25 err = Broken pipe
Cannot write socket fd = 25 err = Broken pipe
Cannot write socket fd = 25 err = Broken pipe
Cannot write socket fd = 25 err = Broken pipe
Cannot write socket fd = 25 err = Broken pipe
Cannot write socket fd = 25 err = Broken pipe
Cannot write socket fd = 25 err = Broken pipe
13:51:00.094 ALSA connection graph change.
13:51:00.096 JACK connection graph change.
13:51:00.097 JACK active patchbay scan...
13:51:00.098 ALSA active patchbay scan...
13:51:00.099 JACK connection change.
13:51:00.101 ALSA connection change.
13:51:00.101 JACK connection graph change.
port deleted: rosegarden:midi/capture_1
port deleted: rosegarden:midi/capture_3
Unknown request 4294967295
port deleted: rosegarden:midi/playback_2
port deleted: rosegarden:midi/playback_3
port deleted: rosegarden:midi/playback_4
port deleted: rosegarden:midi/playback_5
port deleted: rosegarden:midi/playback_6
port deleted: rosegarden:midi/playback_7
jackd: ../common/JackGraphManager.cpp:36: void
Jack::AssertPort(jack_port_id_t): Assertion `port_index < 2048' failed.
13:51:00.301 JACK active patchbay scan...
13:51:00.305 ALSA active patchbay scan...
13:51:00.716 ALSA connection graph change.
13:51:00.718 Shutdown notification.
13:51:00.727 JACK is stopping...
13:51:00.732 JACK is being forced...
JackSocketClientChannel read fail
13:51:00.748 JACK has crashed.
13:51:00.910 ALSA active patchbay scan...
13:51:00.933 JACK was stopped successfully.
13:51:00.933 Post-shutdown script...
13:51:00.933 killall jackd
jackd: no process killed
13:51:01.160 ALSA connection graph change.
13:51:01.311 ALSA active patchbay scan...
13:51:01.354 Post-shutdown script terminated with exit status=256.
13:54:42.475 Startup script...
13:54:42.476 artsshell -q terminate
Jack server was closed but clients are still allocated, cleanup...
Cleanup client ref = 2
Cannot write socket fd = 25 err = Broken pipe
Cannot write socket fd = 25 err = Broken pipe
Could not read result type = 7
Cannot connect to server socket err = Connection refused
Cannot connect to server socket
jack server is not running or cannot be started
13:54:42.913 Startup script terminated with exit status=256.
13:54:42.913 JACK is starting...
13:54:42.914 /usr/bin/jackd -R -dalsa -r44100 -p256 -n2 -D -Chw:M66 -Phw:M66
-Xseq -zs -H -M
13:54:42.915 JACK was started with PID=1129.
no message buffer overruns
no message buffer overruns
jackdmp 1.9.4
Copyright 2001-2005 Paul Davis and others.
Copyright 2004-2009 Grame.
jackdmp 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
JACK server starting in realtime mode with priority 60
audio_reservation_init
Acquire audio card Audio2
creating alsa driver ... hw:M66|hw:M66|256|2|44100|0|0|hwmon|hwmeter|-|32bit
Using ALSA driver ICE1712 running on card 2 - M Audio Delta 66 at 0xe880,
irq 21
configuring for 44100Hz, period = 256 frames (5.8 ms), buffer = 2 periods
ALSA: final selected sample format for capture: 32bit integer little-endian
ALSA: use 2 periods for capture
ALSA: final selected sample format for playback: 32bit integer little-endian
ALSA: use 2 periods for playback
port created: Midi-Through:midi/playback_1
port created: Midi-Through:midi/capture_1
13:54:42.982 ALSA connection graph change.
port created: MT4:midi/playback_1
port created: MT4:midi/capture_1
port created: MT4:midi/playback_2
port created: MT4:midi/capture_2
port created: MT4:midi/playback_3
port created: MT4:midi/capture_3
port created: MT4:midi/capture_4
port created: MT4:midi/capture_5
port created: BCD3000:midi/playback_1
port created: BCD3000:midi/capture_1
port created: UM-2:midi/playback_1
port created: UM-2:midi/capture_1
port created: UM-2:midi/playback_2
port created: UM-2:midi/capture_2
Cannot use real-time scheduling (RR/60)(1: Operation not permitted)
AcquireSelfRealTime error
13:54:43.115 ALSA active patchbay scan...
13:54:45.166 Server configuration saved to "/home/npm/.jackdrc".
13:54:45.167 Statistics reset.
13:54:45.171 Client activated.
13:54:45.172 JACK connection change.
13:54:45.373 JACK active patchbay scan...
13:56:58.844 JACK connection graph change.
13:56:58.858 ALSA connection graph change.
port created: rosegarden:midi/capture_1
port created: rosegarden:midi/playback_2
port created: rosegarden:midi/playback_3
port created: rosegarden:midi/capture_3
port created: rosegarden:midi/playback_4
port created: rosegarden:midi/playback_5
port created: rosegarden:midi/playback_6
port created: rosegarden:midi/playback_7
13:56:59.040 JACK active patchbay scan...
13:56:59.041 ALSA active patchbay scan...
13:56:59.042 JACK connection change.
13:56:59.043 ALSA connection change.
13:56:59.243 JACK active patchbay scan...
13:56:59.244 ALSA active patchbay scan...
13:56:59.752 ALSA connection graph change.
13:56:59.845 ALSA active patchbay scan...
13:57:31.973 ALSA connection graph change.
13:57:31.983 JACK connection graph change.
port deleted: rosegarden:midi/playback_4
port deleted: rosegarden:midi/playback_5
port deleted: rosegarden:midi/playback_6
port deleted: rosegarden:midi/playback_7
port created: rosegarden:midi/playback_4
port created: rosegarden:midi/playback_5
port created: rosegarden:midi/playback_6
port created: rosegarden:midi/playback_7
13:57:32.057 JACK active patchbay scan...
13:57:32.058 ALSA active patchbay scan...
13:57:32.059 JACK connection change.
13:57:32.260 JACK active patchbay scan...
13:58:25.279 Transport start.
13:59:55.514 Transport stop.
14:07:51.996 Transport start.
14:08:10.203 Transport stop.


Niels
http://nielsmayer.com
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://ccrma-mail.stanford.edu/pipermail/planetccrma/attachments/20100313/1ae67216/attachment-0001.html