Review of Messages

[shdawson]

I have been working with ZynAddSubFX to use for live. It works great at the house. I believe all is ready to go. However, I would like to share some JACK and CLI messages I am getting. I am not sure how to interpret them.

Is there a way I can add TXT files to the forum posting, for easy review? Or, should I paste in sections of the message output(s)?


Thanks!
S

[fundamental]

> Is there a way I can add TXT files to the forum posting, for easy review?
You might have some success posting the output in the bbcode's code tag, but if that doesn't work, there is always pastbin or a similar service.

[shdawson]

Right, the code tags. Excellent suggestion.

From JACK Mesages tab:

Code: Select all

Jack: JackClient::ClientNotify ref = 3 name = qjackctl notify = 3
Jack: JackClient::kXRunCallback
Wed Apr 10 18:34:23 2013: Jack: **** alsa_pcm: xrun of at least 0.021 msecs
Wed Apr 10 18:34:23 2013: Jack: ALSA XRun wait_status = 0
Wed Apr 10 18:34:23 2013: Jack: fPollTable i = 1 fd = 13
Wed Apr 10 18:34:23 2013: Jack: JackRequest::Notification
Wed Apr 10 18:34:23 2013: Jack: JackEngine::NotifyClient: no callback for event = 3
Wed Apr 10 18:34:23 2013: Jack: JackEngine::NotifyClient: no callback for event = 3
Wed Apr 10 18:34:23 2013: Jack: JackPosixMutex::Unlock res = 1
Wed Apr 10 18:34:23 2013: Jack: JackClient::ClientNotify ref = 2 name = dbusapi notify = 3
Wed Apr 10 18:34:23 2013: Jack: JackClient::kXRunCallback
Wed Apr 10 18:34:23 2013: Jack: JackPosixMutex::Unlock res = 1
Wed Apr 10 18:34:23 2013: Jack: JackExternalClient::ClientNotify ref = 3 client = qjackctl name = qjackctl notify = 3
Wed Apr 10 18:34:23 2013: Jack: JackPosixMutex::Unlock res = 1
Wed Apr 10 18:34:23 2013: Jack: JackExternalClient::ClientNotify ref = 4 client = zynaddsubfx name = zynaddsubfx notify = 3
Wed Apr 10 18:34:23 2013: Jack: fPollTable i = 2 fd = 14
Wed Apr 10 18:34:23 2013: Jack: fPollTable i = 3 fd = 16

....pretty much the them of this output. Lots of it, over and over.


From the CLI output:

Code: Select all

Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackActivationCount::Signal value = 0 ref = 4
Jack info message: Jack: JackActivationCount::Signal value = 0 ref = 4
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackActivationCount::Signal value = 0 ref = 4
Jack info message: Jack: JackActivationCount::Signal value = 0 ref = 4
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackActivationCount::Signal value = 0 ref = 4
Jack info message: Jack: JackActivationCount::Signal value = 0 ref = 4
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 3
Jack info message: Jack: JackClient::kXRunCallback
Jack reports xrun
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 18
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 18
Jack info message: Jack: JackGraphManager::RecalculateLatency port_index = 5
Jack info message: Jack: JackGraphManager::RecalculateLatency port_index = 6
Jack info message: Jack: JackClient::ClientNotify ref = 3 name = qjackctl notify = 1
Jack info message: Jack: JackClient::RemoveClient name = qjackctl, ref = 3 
Jack info message: Jack: JackPosixSemaphore::Disconnect name = jack_sem.1000_default_qjackctl
Jack info message: Jack: JackClient::kRemoveClient fName = zynaddsubfx name = qjackctl
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 18
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 18
Jack info message: Jack: JackGraphManager::RecalculateLatency port_index = 5
Jack info message: Jack: JackGraphManager::RecalculateLatency port_index = 6
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 18
Jack info message: Jack: JackClient::ClientNotify ref = 2 name = dbusapi notify = 1
Jack info message: Jack: JackClient::RemoveClient name = dbusapi, ref = 2 
Jack info message: Jack: JackPosixSemaphore::Disconnect name = jack_sem.1000_default_dbusapi
Jack info message: Jack: JackClient::kRemoveClient fName = zynaddsubfx name = dbusapi
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 18
Jack info message: Jack: JackGraphManager::RecalculateLatency port_index = 5
Jack info message: Jack: JackGraphManager::RecalculateLatency port_index = 6
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 18
Jack info message: Jack: JackClient::ClientNotify ref = 4 name = zynaddsubfx notify = 18
Jack info message: Jack: JackGraphManager::RecalculateLatency port_index = 5
Jack info message: Jack: JackGraphManager::RecalculateLatency port_index = 6
Jack info message: Jack: JackClient::ClientNotify ref = 0 name = system notify = 1
Jack info message: Jack: JackClient::RemoveClient name = system, ref = 0 
Jack info message: Jack: JackPosixSemaphore::Disconnect name = jack_sem.1000_default_system
Jack info message: Jack: JackClient::kRemoveClient fName = zynaddsubfx name = system
Jack info message: Jack: JackClient::ClientNotify ref = 1 name = freewheel notify = 1
Jack info message: Jack: JackClient::RemoveClient name = freewheel, ref = 1 
Jack info message: Jack: JackPosixSemaphore::Disconnect name = jack_sem.1000_default_freewheel
Jack info message: Jack: JackClient::kRemoveClient fName = zynaddsubfx name = freewheel
Jack reports error: Cannot read socket fd = 5 err = Success
Jack reports error: JackSocketClientChannel read fail
Jack info message: Jack: JackClientSocket::Close
Jack info message: Jack: JackClient::ShutDown
Jack info message: Jack: ThreadHandler: exit
Jack info message: Jack: JackClient::PortUnRegister port_index = 5
Jack reports error: Cannot write socket fd = 3 err = Broken pipe
Jack reports error: Cannot write socket fd = 3 err = Broken pipe
Jack reports error: Cannot write socket fd = 3 err = Broken pipe
Jack reports error: Cannot read socket fd = 3 err = Broken pipe
Jack reports error: Could not read result type = 2
Jack info message: Jack: JackClient::PortUnRegister port_index = 6
Jack reports error: Cannot write socket fd = 3 err = Broken pipe
Jack reports error: Cannot write socket fd = 3 err = Broken pipe
Jack reports error: Cannot write socket fd = 3 err = Broken pipe
Jack reports error: Cannot read socket fd = 3 err = Broken pipe
Jack reports error: Could not read result type = 2
Deactivating and closing JACK client
Jack info message: Jack: JackClient::Deactivate
Jack reports error: Cannot write socket fd = 3 err = Broken pipe
Jack reports error: Cannot write socket fd = 3 err = Broken pipe
Jack reports error: Cannot read socket fd = 3 err = Broken pipe
Jack reports error: Could not read result type = 7
Jack info message: Jack: JackClient::Deactivate res = -1
Jack info message: Jack: JackPosixThread::Kill
Jack info message: Jack: jack_client_close
Jack info message: Jack: JackClient::Close ref = 4
Jack info message: Jack: JackClient::Deactivate
Jack info message: Jack: JackSocketClientChannel::Stop
Jack info message: Jack: JackPosixThread::Kill
Jack info message: Jack: JackClient::Close server is shutdown
Jack info message: Jack: JackClientSocket::Close
Jack info message: Jack: JackClientSocket::Close
Jack info message: Jack: JackPosixSemaphore::Disconnect name = jack_sem.1000_default_zynaddsubfx
Jack info message: Jack: JackLibClient::~JackLibClient
Jack info message: Jack: JackShmReadWritePtr1::~JackShmReadWritePtr1 3
Jack info message: Jack: Succeeded in unlocking 377 byte memory area
Jack info message: Jack: JackLibGlobals Destroy b409a10
Jack info message: Jack: ~JackLibGlobals
Jack info message: Jack: no message buffer overruns
Jack info message: Jack: ThreadHandler: exit
Jack info message: Jack: JackPosixThread::Stop
Jack info message: Jack: JackShmReadWritePtr::~JackShmReadWritePtr 1
Jack info message: Jack: Succeeded in unlocking 994 byte memory area
Jack info message: Jack: JackShmReadWritePtr::~JackShmReadWritePtr 0
Jack info message: Jack: Succeeded in unlocking 82241434 byte memory area
Jack info message: Jack: jack_client_close res = 0
stephen@SONY ~ $ 

....pretty much the same thing, over and over.


Please advise.

Thanks,
S

[fundamental]

While I'm less familiar with jack2 error messages, that just looks like lots of xruns.
Zyn does have some behavior that can introduce these issues on the best of systems, but even so these events would be expected to be sparse.

Do other programs result in the same output?
What is the cpu usage? (if it is above 90% consistently you will see these)
Do you have a low latency kernel?
What are the jack parameters?

[shdawson]

Do other programs result in the same output?
Dunno, never seen these messages before.

What is the cpu usage? (if it is above 90% consistently you will see these)
JACK reports about 14%.

Do you have a low latency kernel?
No, Linux Mint 13.x

What are the jack parameters?
I have none. I fire up JACK through QJACKCTL, click start, run zynaddsubfx -I alsa -O jack -a from the command line, then connect my controller to JACK in the QJACKCTL connections window.

Do I need more parameters in JACK?


Thanks,
S

[fundamental]

jack can be a bit finicky to get a good config, but if it chooses some bad defaults it will xrun like you have posted above.
Generally you will want to make sure that the sample rate, number of periods and period size are set correctly.
Prior to updating my kernel my parameters looked something like
jackd -d alsa -r 48000 -n 3 -p 1024
after the kernel I could use something closer to
jackd -d alsa -r 48000 -n 2 -p 128

More or less the larger the value of the period and the larger value of the number of periods the more forgiving the setup is, but also the more latent the system.
Some sound cards have preferences on the sample rate and period size (or other params), though some may just not work well at lower latencies.

I'd verify that your jack does not xrun (miss a scheduled deadline) by itself before tossing zyn into the mix.
Try running jack as a basic nothing-should-go-wrong client to test the parameters.

[shdawson]

Good idea.

I restarted my box. Fired up QTJACKCTL, and started. This is the Message Tab:

Code: Select all

12:04:57.399 Patchbay deactivated.
12:04:57.518 Statistics reset.
12:04:57.608 ALSA connection change.
12:04:58.232 D-BUS: Service is available (org.jackaudio.service aka jackdbus).
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:04:58.296 ALSA connection graph change.
12:05:00.173 D-BUS: JACK server is starting...
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:05:00.204 D-BUS: JACK server was started (org.jackaudio.service aka jackdbus).
Thu Apr 11 12:05:00 2013: Starting jack server...
Thu Apr 11 12:05:00 2013: JACK server starting in realtime mode with priority 10
Thu Apr 11 12:05:00 2013: [1m[31mERROR: Cannot lock down 82241434 byte memory area (Cannot allocate memory)[0m
Thu Apr 11 12:05:00 2013: Jack: Create non RT thread
Thu Apr 11 12:05:00 2013: Jack: ThreadHandler: start
Thu Apr 11 12:05:00 2013: Jack: playback device hw:0
Thu Apr 11 12:05:00 2013: Jack: capture device hw:0
Thu Apr 11 12:05:00 2013: Jack: apparent rate = 44100
Thu Apr 11 12:05:00 2013: Jack: frames per period = 1024
Thu Apr 11 12:05:00 2013: Jack: JackDriver::Open capture_driver_name = hw:0
Thu Apr 11 12:05:00 2013: Jack: JackDriver::Open playback_driver_name = hw:0
Thu Apr 11 12:05:00 2013: Jack: Check protocol client = 8 server = 8
Thu Apr 11 12:05:00 2013: Jack: JackEngine::ClientInternalOpen: name = system
Thu Apr 11 12:05:00 2013: Jack: JackEngine::AllocateRefNum ref = 0
Thu Apr 11 12:05:00 2013: Jack: JackPosixSemaphore::Allocate name = jack_sem.1000_default_system val = 0
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyAddClient: name = system
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::SetBufferSize size = 1024
Thu Apr 11 12:05:00 2013: Jack: JackConnectionManager::DirectConnect first: ref1 = 0 ref2 = 0
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::ConnectRefNum cur_index = 0 ref1 = 0 ref2 = 0
Thu Apr 11 12:05:00 2013: Jack: JackDriver::SetupDriverSync driver sem in flush mode
Thu Apr 11 12:05:00 2013: control device hw:0
Thu Apr 11 12:05:00 2013: control device hw:0
Thu Apr 11 12:05:00 2013: Acquired audio card Audio0
Thu Apr 11 12:05:00 2013: creating alsa driver ... hw:0|hw:0|1024|2|44100|0|0|hwmon|hwmeter|-|32bit
Thu Apr 11 12:05:00 2013: control device hw:0
Thu Apr 11 12:05:00 2013: configuring for 44100Hz, period = 1024 frames (23.2 ms), buffer = 2 periods
Thu Apr 11 12:05:00 2013: ALSA: final selected sample format for capture: 32bit integer little-endian
Thu Apr 11 12:05:00 2013: ALSA: use 2 periods for capture
Thu Apr 11 12:05:00 2013: ALSA: final selected sample format for playback: 32bit integer little-endian
Thu Apr 11 12:05:00 2013: ALSA: use 2 periods for playback
Thu Apr 11 12:05:00 2013: Jack: JackSocketServerChannel::Open
Thu Apr 11 12:05:00 2013: Jack: Bind: addr.sun_path /dev/shm/jack_default_1000_0
Thu Apr 11 12:05:00 2013: Jack: JackSocketServerChannel::BuildPoolTable size = 1
Thu Apr 11 12:05:00 2013: Jack: JackEngine::Open
Thu Apr 11 12:05:00 2013: Jack: Connect: addr.sun_path /dev/shm/jack_default_1000_0
Thu Apr 11 12:05:00 2013: Jack: JackEngine::ClientInternalOpen: name = freewheel
Thu Apr 11 12:05:00 2013: Jack: JackEngine::AllocateRefNum ref = 1
Thu Apr 11 12:05:00 2013: Jack: JackPosixSemaphore::Allocate name = jack_sem.1000_default_freewheel val = 0
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyAddClient: name = freewheel
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 1 driver = system name = freewheel notify = 0
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 0 driver = freewheel name = system notify = 0
Thu Apr 11 12:05:00 2013: Jack: JackConnectionManager::DirectConnect first: ref1 = 1 ref2 = 1
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::ConnectRefNum cur_index = 0 ref1 = 1 ref2 = 1
Thu Apr 11 12:05:00 2013: Jack: JackDriver::SetupDriverSync driver sem in flush mode
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::SetBufferSize size = 1024
Thu Apr 11 12:05:00 2013: Jack: JackAlsaDriver::Attach fBufferSize 1024 fSampleRate 44100
Thu Apr 11 12:05:00 2013: Jack: JackEngine::PortRegister ref = 0 name = system:capture_1 type = 32 bit float mono audio flags = 22 buffer_size = 1024
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::AllocatePortAux port_index = 1 name = system:capture_1 type = 32 bit float mono audio
Thu Apr 11 12:05:00 2013: Jack: JackConnectionManager::AddOutputPort ref = 0 port = 1
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 9
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 9
Thu Apr 11 12:05:00 2013: Jack: JackAlsaDriver::Attach fCapturePortList[i] 1 
Thu Apr 11 12:05:00 2013: Jack: JackEngine::PortRegister ref = 0 name = system:capture_2 type = 32 bit float mono audio flags = 22 buffer_size = 1024
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::AllocatePortAux port_index = 2 name = system:capture_2 type = 32 bit float mono audio
Thu Apr 11 12:05:00 2013: Jack: JackConnectionManager::AddOutputPort ref = 0 port = 2
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 9
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 9
Thu Apr 11 12:05:00 2013: Jack: JackAlsaDriver::Attach fCapturePortList[i] 2 
Thu Apr 11 12:05:00 2013: Jack: JackEngine::PortRegister ref = 0 name = system:playback_1 type = 32 bit float mono audio flags = 21 buffer_size = 1024
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::AllocatePortAux port_index = 3 name = system:playback_1 type = 32 bit float mono audio
Thu Apr 11 12:05:00 2013: Jack: JackConnectionManager::AddInputPort ref = 0 port = 3
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 9
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 9
Thu Apr 11 12:05:00 2013: Jack: JackAlsaDriver::Attach fPlaybackPortList[i] 3 
Thu Apr 11 12:05:00 2013: Jack: JackEngine::PortRegister ref = 0 name = system:playback_2 type = 32 bit float mono audio flags = 21 buffer_size = 1024
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::AllocatePortAux port_index = 4 name = system:playback_2 type = 32 bit float mono audio
Thu Apr 11 12:05:00 2013: Jack: JackConnectionManager::AddInputPort ref = 0 port = 4
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 9
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 9
Thu Apr 11 12:05:00 2013: Jack: JackAlsaDriver::Attach fPlaybackPortList[i] 4 
Thu Apr 11 12:05:00 2013: Jack: Clock source : system clock via clock_gettime
Thu Apr 11 12:05:00 2013: Jack: JackServer::Start
Thu Apr 11 12:05:00 2013: Jack: JackThreadedDriver::Start
Thu Apr 11 12:05:00 2013: Jack: Create non RT thread
Thu Apr 11 12:05:00 2013: Jack: ThreadHandler: start
Thu Apr 11 12:05:00 2013: Jack: JackThreadedDriver::Init real-time
Thu Apr 11 12:05:00 2013: Jack: JackPosixThread::AcquireRealTimeImp priority = 10
Thu Apr 11 12:05:00 2013: [1m[31mERROR: Cannot use real-time scheduling (RR/10)(1: Operation not permitted)[0m
Thu Apr 11 12:05:00 2013: [1m[31mERROR: AcquireSelfRealTime error[0m
Thu Apr 11 12:05:00 2013: Jack: Create non RT thread
Thu Apr 11 12:05:00 2013: Jack: jack_client_open dbusapi
Thu Apr 11 12:05:00 2013: Jack: JackInternalClient::Open name = dbusapi
Thu Apr 11 12:05:00 2013: Jack: Check protocol client = 8 server = 8
Thu Apr 11 12:05:00 2013: Jack: JackEngine::ClientInternalOpen: name = dbusapi
Thu Apr 11 12:05:00 2013: Jack: JackEngine::AllocateRefNum ref = 2
Thu Apr 11 12:05:00 2013: Jack: JackPosixSemaphore::Allocate name = jack_sem.1000_default_dbusapi val = 0
Thu Apr 11 12:05:00 2013: Jack: JackProcessSync::TimedWait time out = 5000000
Thu Apr 11 12:05:00 2013: Jack: ThreadHandler: start
Thu Apr 11 12:05:00 2013: Jack: JackSocketServerChannel::ClientCreate socket
Thu Apr 11 12:05:00 2013: Jack: JackSocketServerChannel::BuildPoolTable size = 2
Thu Apr 11 12:05:00 2013: Jack: fSocketTable i = 1 fd = 13
Thu Apr 11 12:05:00 2013: Jack: JackProcessSync::TimedWait finished delta = 21987.0
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyAddClient: name = dbusapi
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 2 driver = system name = dbusapi notify = 0
Thu Apr 11 12:05:00 2013: Jack: JackClient::ClientNotify ref = 0 name = system notify = 0
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 2 driver = freewheel name = dbusapi notify = 0
Thu Apr 11 12:05:00 2013: Jack: JackClient::ClientNotify ref = 1 name = freewheel notify = 0
Thu Apr 11 12:05:00 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:00 2013: Jack: JackRequest::Notification
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 4
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 4
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 4
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 3
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 4
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 3
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 4
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 1
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 2
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 1
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 2
Thu Apr 11 12:05:00 2013: Jack: JackClient::SetupDriverSync driver sem in flush mode
Thu Apr 11 12:05:00 2013: Jack: JackInternalClient::Open name = dbusapi refnum = 2
Thu Apr 11 12:05:00 2013: Jack: jack_set_graph_order_callback ext_client 8cb7f90 client 8cb7f90 
Thu Apr 11 12:05:00 2013: Jack: JackClient::Activate
Thu Apr 11 12:05:00 2013: Jack: JackEngine::ClientActivate ref = 2 name = dbusapi
Thu Apr 11 12:05:00 2013: Jack: JackProcessSync::TimedWait time out = 5000000
Thu Apr 11 12:05:00 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:00 2013: Jack: JackRequest::Notification
Thu Apr 11 12:05:00 2013: Jack: JackProcessSync::TimedWait finished delta = 13116.0
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 4
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 4
Thu Apr 11 12:05:00 2013: Jack: JackClient::ClientNotify ref = 2 name = dbusapi notify = 4
Thu Apr 11 12:05:00 2013: Jack: JackClient::kGraphOrderCallback
Thu Apr 11 12:05:00 2013: graph reorder: new port 'system:capture_1'
Thu Apr 11 12:05:00 2013: New client 'system' with PID 0
Thu Apr 11 12:05:00 2013: graph reorder: new port 'system:capture_2'
Thu Apr 11 12:05:00 2013: graph reorder: new port 'system:playback_1'
Thu Apr 11 12:05:00 2013: graph reorder: new port 'system:playback_2'
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 3
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 4
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 3
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 4
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 1
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 2
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 1
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 2
Thu Apr 11 12:05:00 2013: Jack: JackClient::ClientNotify ref = 2 name = dbusapi notify = 2
Thu Apr 11 12:05:00 2013: Jack: JackClient::kActivateClient name = dbusapi ref = 2 
Thu Apr 11 12:05:00 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:00 2013: Jack: JackRequest::Notification
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 4
Thu Apr 11 12:05:00 2013: Jack: JackEngine::NotifyClient: no callback for event = 4
Thu Apr 11 12:05:00 2013: Jack: JackClient::ClientNotify ref = 2 name = dbusapi notify = 4
Thu Apr 11 12:05:00 2013: Jack: JackClient::kGraphOrderCallback
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 3
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 4
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 3
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 4
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:00 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 1
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 2
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 1
Thu Apr 11 12:05:00 2013: Jack: JackGraphManager::RecalculateLatency port_index = 2
Thu Apr 11 12:05:01 2013: Saving settings to "/home/stephen/.config/jack/conf.xml" ...
Thu Apr 11 12:05:03 2013: Jack: **** alsa_pcm: xrun of at least 0.058 msecs
Thu Apr 11 12:05:03 2013: Jack: ALSA XRun wait_status = 0
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:03 2013: Jack: JackRequest::Notification
Thu Apr 11 12:05:03 2013: Jack: JackEngine::NotifyClient: no callback for event = 3
Thu Apr 11 12:05:03 2013: Jack: JackEngine::NotifyClient: no callback for event = 3
Thu Apr 11 12:05:03 2013: Jack: JackPosixMutex::Unlock res = 1
Thu Apr 11 12:05:03 2013: Jack: JackClient::ClientNotify ref = 2 name = dbusapi notify = 3
Thu Apr 11 12:05:03 2013: Jack: JackClient::kXRunCallback
12:05:03.661 JACK connection change.
12:05:03.668 Server configuration saved to "/home/stephen/.jackdrc".
12:05:03.670 Statistics reset.
12:05:03.701 Client activated.
12:05:03.718 JACK connection graph change.
Cannot lock down 82241434 byte memory area (Cannot allocate memory)
Jack: JackClient::SetupDriverSync driver sem in flush mode
Jack: JackPosixSemaphore::Connect name = jack_sem.1000_default_qjackctl
Jack: JackPosixSemaphore::Connect sem_getvalue 0
Jack: Clock source : system clock via clock_gettime
Jack: JackLibClient::Open name = qjackctl refnum = 3
Jack: jack_set_graph_order_callback ext_client a634750 client a634750 
Jack: WaitGraphChange...
Jack: JackClient::ClientNotify ref = 3 name = qjackctl notify = 4
Jack: JackClient::Activate
Jack: JackClient::ClientNotify ref = 3 name = qjackctl notify = 2
Jack: JackClient::kActivateClient name = qjackctl ref = 3 
Jack: JackClient::ClientNotify ref = 3 name = qjackctl notify = 4
Jack: JackClient::kGraphOrderCallback
Thu Apr 11 12:05:03 2013: Jack: **** alsa_pcm: xrun of at least 0.059 msecs
Thu Apr 11 12:05:03 2013: Jack: ALSA XRun wait_status = 0
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:03 2013: Jack: JackRequest::Notification
Thu Apr 11 12:05:03 2013: Jack: JackEngine::NotifyClient: no callback for event = 3
Thu Apr 11 12:05:03 2013: Jack: JackEngine::NotifyClient: no callback for event = 3
Thu Apr 11 12:05:03 2013: Jack: JackPosixMutex::Unlock res = 1
Thu Apr 11 12:05:03 2013: Jack: JackClient::ClientNotify ref = 2 name = dbusapi notify = 3
Thu Apr 11 12:05:03 2013: Jack: JackClient::kXRunCallback
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:03 2013: Jack: JackSocketServerChannel::ClientCreate socket
Thu Apr 11 12:05:03 2013: Jack: JackSocketServerChannel::BuildPoolTable size = 3
Thu Apr 11 12:05:03 2013: Jack: fSocketTable i = 1 fd = 13
Thu Apr 11 12:05:03 2013: Jack: fSocketTable i = 2 fd = 14
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 2 fd = 14
Thu Apr 11 12:05:03 2013: Jack: Poll client error err = Success
Thu Apr 11 12:05:03 2013: Jack: JackSocketServerChannel::ClientKill ref = -1
Thu Apr 11 12:05:03 2013: Jack: Client was not opened : probably correspond to server_check
Thu Apr 11 12:05:03 2013: Jack: JackClientSocket::Close
Thu Apr 11 12:05:03 2013: Jack: JackSocketServerChannel::BuildPoolTable size = 2
Thu Apr 11 12:05:03 2013: Jack: fSocketTable i = 1 fd = 13
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:03 2013: Jack: JackSocketServerChannel::ClientCreate socket
Thu Apr 11 12:05:03 2013: Jack: JackSocketServerChannel::BuildPoolTable size = 3
Thu Apr 11 12:05:03 2013: Jack: fSocketTable i = 1 fd = 13
Thu Apr 11 12:05:03 2013: Jack: fSocketTable i = 2 fd = 14
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 2 fd = 14
Thu Apr 11 12:05:03 2013: Jack: JackRequest::ClientCheck
Thu Apr 11 12:05:03 2013: Jack: Check protocol client = 8 server = 8
Thu Apr 11 12:05:03 2013: Jack: JackRequest::ClientOpen
Thu Apr 11 12:05:03 2013: Jack: JackSocketServerChannel::ClientAdd
Thu Apr 11 12:05:03 2013: Jack: JackEngine::ClientExternalOpen: uuid = 0, name = qjackctl 
Thu Apr 11 12:05:03 2013: Jack: JackEngine::AllocateRefNum ref = 3
Thu Apr 11 12:05:03 2013: Jack: JackPosixSemaphore::Allocate name = jack_sem.1000_default_qjackctl val = 0
Thu Apr 11 12:05:03 2013: Jack: JackSocketNotifyChannel::Open name = qjackctl
Thu Apr 11 12:05:03 2013: Jack: Connect: addr.sun_path /dev/shm/jack_qjackctl_1000_0
Thu Apr 11 12:05:03 2013: Jack: JackShmMem::new index = 2 attached = b01be000 size = 377 
Thu Apr 11 12:05:03 2013: Jack: JackExternalClient::Open name = qjackctl index = 2 base = b01be000
Thu Apr 11 12:05:03 2013: Jack: JackProcessSync::TimedWait time out = 5000000
Thu Apr 11 12:05:03 2013: Jack: JackProcessSync::TimedWait finished delta = 13163.0
Thu Apr 11 12:05:03 2013: Jack: JackEngine::NotifyAddClient: name = qjackctl
Thu Apr 11 12:05:03 2013: Jack: JackDriver::ClientNotify ref = 3 driver = system name = qjackctl notify = 0
Thu Apr 11 12:05:03 2013: Jack: JackExternalClient::ClientNotify ref = 0 client = qjackctl name = system notify = 0
Thu Apr 11 12:05:03 2013: Jack: JackDriver::ClientNotify ref = 3 driver = freewheel name = qjackctl notify = 0
Thu Apr 11 12:05:03 2013: Jack: JackExternalClient::ClientNotify ref = 1 client = qjackctl name = freewheel notify = 0
Thu Apr 11 12:05:03 2013: Jack: JackClient::ClientNotify ref = 3 name = qjackctl notify = 0
Thu Apr 11 12:05:03 2013: Jack: JackClient::kAddClient fName = dbusapi name = qjackctl
Thu Apr 11 12:05:03 2013: Jack: client 'qjackctl' created
Thu Apr 11 12:05:03 2013: New client 'qjackctl' with PID 2212
Thu Apr 11 12:05:03 2013: Jack: JackExternalClient::ClientNotify ref = 2 client = qjackctl name = dbusapi notify = 0
Thu Apr 11 12:05:03 2013: Jack: JackSocketServerChannel::BuildPoolTable size = 3
Thu Apr 11 12:05:03 2013: Jack: fSocketTable i = 1 fd = 13
Thu Apr 11 12:05:03 2013: Jack: fSocketTable i = 2 fd = 14
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:03 2013: Jack: JackRequest::Notification
Thu Apr 11 12:05:03 2013: Jack: JackEngine::NotifyClient: no callback for event = 4
Thu Apr 11 12:05:03 2013: Jack: JackEngine::NotifyClient: no callback for event = 4
Thu Apr 11 12:05:03 2013: Jack: JackClient::ClientNotify ref = 2 name = dbusapi notify = 4
Thu Apr 11 12:05:03 2013: Jack: JackClient::kGraphOrderCallback
Thu Apr 11 12:05:03 2013: Jack: JackExternalClient::ClientNotify ref = 3 client = qjackctl name = qjackctl notify = 4
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 3
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 4
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 3
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 4
Thu Apr 11 12:05:03 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:03 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:03 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:03 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 1
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 2
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 1
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 2
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 2 fd = 14
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 2 fd = 14
Thu Apr 11 12:05:03 2013: Jack: JackRequest::ActivateClient
Thu Apr 11 12:05:03 2013: Jack: JackEngine::ClientActivate ref = 3 name = qjackctl
Thu Apr 11 12:05:03 2013: Jack: JackProcessSync::TimedWait time out = 5000000
Thu Apr 11 12:05:03 2013: Jack: JackProcessSync::TimedWait finished delta = 12562.0
Thu Apr 11 12:05:03 2013: Jack: JackExternalClient::ClientNotify ref = 3 client = qjackctl name = qjackctl notify = 2
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:03 2013: Jack: JackRequest::Notification
Thu Apr 11 12:05:03 2013: Jack: JackEngine::NotifyClient: no callback for event = 4
Thu Apr 11 12:05:03 2013: Jack: JackEngine::NotifyClient: no callback for event = 4
Thu Apr 11 12:05:03 2013: Jack: JackClient::ClientNotify ref = 2 name = dbusapi notify = 4
Thu Apr 11 12:05:03 2013: Jack: JackClient::kGraphOrderCallback
Thu Apr 11 12:05:03 2013: Jack: JackExternalClient::ClientNotify ref = 3 client = qjackctl name = qjackctl notify = 4
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 3
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 4
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 3
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 4
Thu Apr 11 12:05:03 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:03 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:03 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:03 2013: Jack: JackDriver::ClientNotify ref = 1 driver = freewheel name = freewheel notify = 18
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 1
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 2
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 1
Thu Apr 11 12:05:03 2013: Jack: JackGraphManager::RecalculateLatency port_index = 2
Thu Apr 11 12:05:03 2013: Jack: fPollTable i = 2 fd = 14
12:05:08.377 XRUN callback (1).
Jack: JackClient::ClientNotify ref = 3 name = qjackctl notify = 3
Jack: JackClient::kXRunCallback
Jack: JackClient::ClientNotify ref = 3 name = qjackctl notify = 3
Jack: JackClient::kXRunCallback
Thu Apr 11 12:05:08 2013: Jack: **** alsa_pcm: xrun of at least 0.080 msecs
Thu Apr 11 12:05:08 2013: Jack: ALSA XRun wait_status = 0
Thu Apr 11 12:05:08 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:08 2013: Jack: JackRequest::Notification
Thu Apr 11 12:05:08 2013: Jack: JackEngine::NotifyClient: no callback for event = 3
Thu Apr 11 12:05:08 2013: Jack: JackEngine::NotifyClient: no callback for event = 3
Thu Apr 11 12:05:08 2013: Jack: JackPosixMutex::Unlock res = 1
Thu Apr 11 12:05:08 2013: Jack: JackClient::ClientNotify ref = 2 name = dbusapi notify = 3
Thu Apr 11 12:05:08 2013: Jack: JackClient::kXRunCallback
Thu Apr 11 12:05:08 2013: Jack: JackPosixMutex::Unlock res = 1
Thu Apr 11 12:05:08 2013: Jack: JackExternalClient::ClientNotify ref = 3 client = qjackctl name = qjackctl notify = 3
Thu Apr 11 12:05:08 2013: Jack: fPollTable i = 2 fd = 14
Thu Apr 11 12:05:08 2013: Jack: **** alsa_pcm: xrun of at least 0.122 msecs
Thu Apr 11 12:05:08 2013: Jack: ALSA XRun wait_status = 0
Thu Apr 11 12:05:08 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:08 2013: Jack: JackRequest::Notification
Thu Apr 11 12:05:08 2013: Jack: JackEngine::NotifyClient: no callback for event = 3
Thu Apr 11 12:05:08 2013: Jack: JackEngine::NotifyClient: no callback for event = 3
Thu Apr 11 12:05:08 2013: Jack: JackPosixMutex::Unlock res = 1
Thu Apr 11 12:05:08 2013: Jack: JackClient::ClientNotify ref = 2 name = dbusapi notify = 3
Thu Apr 11 12:05:08 2013: Jack: JackClient::kXRunCallback
Thu Apr 11 12:05:08 2013: Jack: JackPosixMutex::Unlock res = 1
Thu Apr 11 12:05:08 2013: Jack: JackExternalClient::ClientNotify ref = 3 client = qjackctl name = qjackctl notify = 3
Thu Apr 11 12:05:08 2013: Jack: fPollTable i = 2 fd = 14
12:05:09.814 XRUN callback (1 skipped).
12:05:15.563 XRUN callback (3).
Jack: JackClient::ClientNotify ref = 3 name = qjackctl notify = 3
Jack: JackClient::kXRunCallback
Thu Apr 11 12:05:15 2013: Jack: **** alsa_pcm: xrun of at least 0.048 msecs
Thu Apr 11 12:05:15 2013: Jack: ALSA XRun wait_status = 0
Thu Apr 11 12:05:15 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:15 2013: Jack: JackRequest::Notification
Thu Apr 11 12:05:15 2013: Jack: JackEngine::NotifyClient: no callback for event = 3
Thu Apr 11 12:05:15 2013: Jack: JackEngine::NotifyClient: no callback for event = 3
Thu Apr 11 12:05:15 2013: Jack: JackPosixMutex::Unlock res = 1
Thu Apr 11 12:05:15 2013: Jack: JackClient::ClientNotify ref = 2 name = dbusapi notify = 3
Thu Apr 11 12:05:15 2013: Jack: JackClient::kXRunCallback
Thu Apr 11 12:05:15 2013: Jack: JackPosixMutex::Unlock res = 1
Thu Apr 11 12:05:15 2013: Jack: JackExternalClient::ClientNotify ref = 3 client = qjackctl name = qjackctl notify = 3
Thu Apr 11 12:05:15 2013: Jack: fPollTable i = 2 fd = 14
Thu Apr 11 12:05:25 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:25 2013: Jack: fPollTable i = 2 fd = 14
Thu Apr 11 12:05:35 2013: Jack: fPollTable i = 1 fd = 13
Thu Apr 11 12:05:35 2013: Jack: fPollTable i = 2 fd = 14

All this from running about 10-seconds. So, how does this look?


Thanks,
S

[fundamental]

I see xruns in there, the message "Cannot use real-time scheduling", and a few issues related to RT permissions.
So, this would make the earlier messages likely caused by a jack misconfiguration rather than zyn itself.
There should be some resources on how to get Mint to grant jack/your-user the right permissions to use the realtime mode somewhere and then I'd also try getting the number of periods up to three.

With jack, if you see an xrun, somewhere/something is wrong.

[shdawson]

Thank you very, very much.


SHD