jackd crashed using freebob cannot complete execution of processing graph ... zombified

Bug #264201 reported by Vivian Stewart
6
Affects Status Importance Assigned to Milestone
jackit
New
Undecided
Unassigned
libraw1394
New
Undecided
Unassigned
libfreebob (Ubuntu)
Invalid
Undecided
Unassigned

Bug Description

using the jackd in hardy via my hp compaq v6000au laptop with mini firewire port I get all this processing graph crap and then jack crashes but firewire connection remains active and requires reset or a power OFF/ON. I have a presonus firepod (FP10) which with previous versions of jackd and freebob worked, maybe not perfectly, but this is a serious step backwards. there are also reports on the ubuntu forums of this happening to people with M-Audio cards also. could this be a cable problem? or a problem with getting realtime priority? I have taken all the necessary steps to ensure realtime priority.

13:12:08.416 Patchbay deactivated.
13:12:08.695 Statistics reset.
13:12:08.724 ALSA connection graph change.
13:12:08.922 ALSA connection change.
13:12:24.005 Startup script...
13:12:24.007 killall pulseaudio && killall esd
pulseaudio: no process killed
13:12:24.432 Startup script terminated with exit status=256.
13:12:24.433 JACK is starting...
13:12:24.435 /usr/bin/jackd -v -R -P75 -p128 -t200 -dfreebob -r96000 -p1024 -n5 -D
getting driver descriptor from /usr/lib64/jack/jack_alsa.so
getting driver descriptor from /usr/lib64/jack/jack_oss.so
getting driver descriptor from /usr/lib64/jack/jack_freebob.so
13:12:24.449 JACK was started with PID=7829.
getting driver descriptor from /usr/lib64/jack/jack_dummy.so
jackd 0.109.2
Copyright 2001-2005 Paul Davis 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
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 = system clock via clock_gettime
loading driver ..
13:12:24.573 Server configuration saved to "/home/vivy/.jackdrc".
13:12:24.575 Statistics reset.
13:12:24.622 Client activated.
13:12:24.632 JACK connection graph change.
new client: freebob_pcm, id = 1 type 1 @ 0x62b630 fd = -1
Enhanced3DNow! detected
SSE2 detected
Freebob using Firewire port 0, node -1
new buffer size 1024
new client: qjackctl, id = 2 type 2 @ 0x7f9eae71d000 fd = 6
Enhanced3DNow! detected
SSE2 detected
LibFreeBoB MSG: FreeBoB Streaming Device Init
LibFreeBoB MSG: Using FreeBoB lib version libfreebob 1.0.7
LibFreeBoB MSG: Device information:
LibFreeBoB MSG: Device options:
LibFreeBoB MSG: Port : 0
LibFreeBoB MSG: Device Node Id : -1
LibFreeBoB MSG: Samplerate : 96000
LibFreeBoB MSG: Period Size : 1024
LibFreeBoB MSG: Nb Buffers : 5
LibFreeBoB MSG: Directions : 0
++ jack_rechain_graph():
client qjackctl: start_fd=5, execution_order=0.
client qjackctl: wait_fd=12, execution_order=1 (last client).
-- jack_rechain_graph()
showDevice: not implemented
13:12:26.175 JACK connection graph change.
FreeBoB MSG: Register MIDI IN port dev1c_MIDI 1
FreeBoB MSG: Register MIDI OUT port dev1p_MIDI 1
FreeBoB MSG: Streaming thread running with Realtime scheduling, priority 79
FreeBoB MSG: Registering audio capture port C0_dev1c_Input 1L
registered port system:capture_1, offset = 4096
FreeBoB MSG: Registering audio capture port C1_dev1c_Input 2R
registered port system:capture_2, offset = 8192
FreeBoB MSG: Registering audio capture port C2_dev1c_Input 3L
registered port system:capture_3, offset = 12288
FreeBoB MSG: Registering audio capture port C3_dev1c_Input 4R
registered port system:capture_4, offset = 16384
FreeBoB MSG: Registering audio capture port C4_dev1c_Input 5L
registered port system:capture_5, offset = 20480
FreeBoB MSG: Registering audio capture port C5_dev1c_Input 6R
registered port system:capture_6, offset = 24576
FreeBoB MSG: Registering audio capture port C6_dev1c_Input 7L
registered port system:capture_7, offset = 28672
FreeBoB MSG: Registering audio capture port C7_dev1c_Input 8R
13:12:26.192 ALSA connection graph change.
registered port system:capture_8, offset = 32768
FreeBoB MSG: Registering audio capture port C8_dev1c_S/PDIF Input 9L
registered port system:capture_9, offset = 36864
FreeBoB MSG: Registering audio capture port C9_dev1c_S/PDIF Input 10R
registered port system:capture_10, offset = 40960
FreeBoB MSG: Don't register capture port for dev1c_MIDI 1
FreeBoB MSG: Registering playback audio port P0_dev1p_Output 1L
registered port system:playback_1, offset = 0
FreeBoB MSG: Registering playback audio port P1_dev1p_Output 2R
registered port system:playback_2, offset = 0
FreeBoB MSG: Registering playback audio port P2_dev1p_Output 3L
registered port system:playback_3, offset = 0
FreeBoB MSG: Registering playback audio port P3_dev1p_Output 4R
registered port system:playback_4, offset = 0
FreeBoB MSG: Registering playback audio port P4_dev1p_Output 5L
registered port system:playback_5, offset = 0
FreeBoB MSG: Registering playback audio port P5_dev1p_Output 6R
registered port system:playback_6, offset = 0
FreeBoB MSG: Registering playback audio port P6_dev1p_Output 7L
registered port system:playback_7, offset = 0
FreeBoB MSG: Registering playback audio port P7_dev1p_Output 8R
registered port system:playback_8, offset = 0
FreeBoB MSG: Registering playback audio port P8_dev1p_S/PDIF Output 9L
registered port system:playback_9, offset = 0
FreeBoB MSG: Registering playback audio port P9_dev1p_S/PDIF Output 10R
registered port system:playback_10, offset = 0
FreeBoB MSG: Don't register playback port dev1p_MIDI 1
++ jack_rechain_graph():
client freebob_pcm: internal client, execution_order=0.
client qjackctl: start_fd=5, execution_order=0.
client qjackctl: wait_fd=12, execution_order=1 (last client).
-- jack_rechain_graph()
FreeBoB MSG: MIDI threads running with Realtime scheduling, priority 78
FreeBoB MSG: MIDI queue thread started
libiec61883 warning: Established connection on channel 0.
You may need to manually set the channel on the receiving node.
libiec61883 warning: Established connection on channel 1.
13:12:26.287 JACK connection change.
13:12:26.291 ALSA connection change.
You may need to manually set the channel on the transmitting node.
7829 waiting for signals
load = 2.2970 max usecs: 490.000, spare = 10176.000
load = 3.4643 max usecs: 494.000, spare = 10172.000
load = 4.0198 max usecs: 488.000, spare = 10178.000
load = 4.2928 max usecs: 487.000, spare = 10179.000
load = 4.6778 max usecs: 540.000, spare = 10126.000
load = 4.8281 max usecs: 531.000, spare = 10135.000
load = 4.7720 max usecs: 503.000, spare = 10163.000
load = 4.7205 max usecs: 498.000, spare = 10168.000
13:12:38.234 ALSA connection graph change.
13:12:38.236 Shutdown notification.
13:12:38.241 JACK is stopping...
13:12:38.243 JACK is being forced...
cannot complete execution of the processing graph (Success)
zombified - calling shutdown handler
13:12:38.251 JACK has crashed.
13:12:38.322 ALSA connection change.
13:12:38.444 JACK was stopped successfully.
13:12:38.445 Post-shutdown script...
13:12:38.446 killall jackd
jackd: no process killed
13:12:38.871 Post-shutdown script terminated with exit status=256.

Revision history for this message
Vivian Stewart (vivichrist) wrote :

also in kernlog:

Sep 4 15:28:48 komcrapper kernel: [ 137.309696] ohci1394: fw-host0: Unrecoverable error!
Sep 4 15:28:48 komcrapper kernel: [ 137.308769] ohci1394: fw-host0: Iso Xmit 0 Context died: ctrl[40649806] cmdptr[f000e2c3]
Sep 4 15:29:35 komcrapper kernel: [ 161.640501] ohci1394: fw-host0: Unrecoverable error!
Sep 4 15:29:35 komcrapper kernel: [ 161.640516] ohci1394: fw-host0: Iso Xmit 0 Context died: ctrl[20648806] cmdptr[f000e2c3]
Sep 4 15:30:44 komcrapper kernel: [ 195.899136] ohci1394: fw-host0: Unrecoverable error!
Sep 4 15:30:44 komcrapper kernel: [ 195.899158] ohci1394: fw-host0: Iso Xmit 0 Context died: ctrl[40649806] cmdptr[f000e2c3]
Sep 4 15:31:18 komcrapper kernel: [ 213.709287] ohci1394: fw-host0: Unrecoverable error!
Sep 4 15:31:18 komcrapper kernel: [ 213.709303] ohci1394: fw-host0: Iso Xmit 0 Context died: ctrl[60649806] cmdptr[f000e2c3]
Sep 4 15:32:45 komcrapper kernel: [ 260.770006] ohci1394: fw-host0: Unrecoverable error!
Sep 4 15:32:45 komcrapper kernel: [ 260.770018] ohci1394: fw-host0: Iso Xmit 0 Context died: ctrl[20649806] cmdptr[f000e2c3]
Sep 4 15:50:33 komcrapper kernel: [ 804.971557] jackd[6400]: segfault at 1300000050 rip 406b5b rsp 40a75140 error 4

Revision history for this message
Vivian Stewart (vivichrist) wrote :

Sep 4 11:56:01 komcrapper kernel: [ 1651.260636] ieee1394: The root node is not cycle master capable; selecting a new root node and resetting...
Sep 4 11:56:02 komcrapper kernel: [ 1651.724569] ieee1394: Error parsing configrom for node 0-00:1023
Sep 4 11:56:02 komcrapper kernel: [ 1651.724827] ieee1394: Node changed: 0-00:1023 -> 0-01:1023
Sep 4 11:56:04 komcrapper kernel: [ 1652.523516] doh, someone wants to mess with state set
Sep 4 11:56:04 komcrapper kernel: [ 1652.646878] ieee1394: Node resumed: ID:BUS[0-00:1023] GUID[000a9200c6040746]
Sep 4 13:07:11 komcrapper kernel: [ 3961.201346] patchage[16107]: segfault at 18 rip 7fcdd0115bc0 rsp 7fffdc59cda8 error 4
Sep 4 13:51:21 komcrapper kernel: [ 5286.538040] hydrogen[16288]: segfault at 0 rip 7fa13656a330 rsp 408efb28 error 6
Sep 4 13:51:21 komcrapper kernel: [ 5286.953929] ohci1394: fw-host0: Unrecoverable error!
Sep 4 13:51:21 komcrapper kernel: [ 5286.953947] ohci1394: fw-host0: Iso Xmit 0 Context died: ctrl[20649806] cmdptr[f000e2c3]
Sep 4 13:52:21 komcrapper kernel: [ 5323.162888] ieee1394: Node changed: 0-01:1023 -> 0-00:1023
Sep 4 13:52:21 komcrapper kernel: [ 5323.162900] ieee1394: Node suspended: ID:BUS[0-00:1023] GUID[000a9200c6040746]
Sep 4 13:52:25 komcrapper kernel: [ 5327.217249] ieee1394: Node changed: 0-00:1023 -> 0-01:1023
Sep 4 13:52:25 komcrapper kernel: [ 5327.217268] ieee1394: Node resumed: ID:BUS[0-01:1023] GUID[000a9200c6040746]
Sep 4 13:52:26 komcrapper kernel: [ 5329.013917] jackd[16304]: segfault at 1300000050 rip 406b5b rsp 41967140 error 4
Sep 4 13:53:11 komcrapper kernel: [ 5351.920989] ohci1394: fw-host0: Unrecoverable error!
Sep 4 13:53:11 komcrapper kernel: [ 5351.921001] ohci1394: fw-host0: Iso Xmit 0 Context died: ctrl[60649806] cmdptr[f000e2c3]
Sep 4 13:53:15 komcrapper kernel: [ 5354.261067] hydrogen[16341] general protection rip:7f95f0819504 rsp:42ca1020 error:0
Sep 4 13:53:54 komcrapper kernel: [ 5375.035727] doh, someone wants to mess with state set
Sep 4 13:53:54 komcrapper kernel: [ 5375.118878] ieee1394: Node changed: 0-01:1023 -> 0-00:1023
Sep 4 13:53:54 komcrapper kernel: [ 5375.119211] ieee1394: Node changed: 0-00:1023 -> 0-01:1023
Sep 4 13:54:05 komcrapper kernel: [ 5382.113195] ohci1394: fw-host0: Unrecoverable error!
Sep 4 13:54:05 komcrapper kernel: [ 5382.113207] ohci1394: fw-host0: Iso Xmit 0 Context died: ctrl[20649806] cmdptr[f000e2c3]
Sep 4 13:54:40 komcrapper kernel: [ 5412.440453] doh, someone wants to mess with state set
Sep 4 13:56:05 komcrapper kernel: [ 5467.278985] ohci1394: fw-host0: Unrecoverable error!
Sep 4 13:56:05 komcrapper kernel: [ 5467.278998] ohci1394: fw-host0: Iso Xmit 0 Context died: ctrl[20649806] cmdptr[f000e2c3]

Revision history for this message
Vivian Stewart (vivichrist) wrote :

but seems to work perfectly ok on my desktop (MA790X-DS4, Phenom 9550, 4gig/ram)

Revision history for this message
Arthur Lutz (arthur-lutz) wrote :

I believe I have the same problem but with an echo audiofire8. I have a PCI firewire extension, could this be the source of the porblem ?

Revision history for this message
Stefan Richter (stefan-r-ubz) wrote :

Is this problem perhaps limited to certain FireWire controllers?
You can check with "lspci" for the controller type.

Did the same hardware work with older kernels?

Revision history for this message
Arthur Lutz (arthur-lutz) wrote :

$ lscpi | grep Firewire
02:07.3 FireWire (IEEE 1394): VIA Technologies, Inc. VT6306 Fire II IEEE 1394 OHCI Link Layer Controller (rev 46)

I haven't tested this hardware with older kernels.

Revision history for this message
Andrew Hunter (rexbron) wrote :

Libfreebob is no longer maintained upstream. It's development has been superseeded by libffado (added in Ubuntu 9.04). Please confirm if you are still having issues and if so, please reopen this report.

Changed in libfreebob (Ubuntu):
status: New → Invalid
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.