Ticket #176 (closed bug: fixed)

Opened 15 years ago

Last modified 15 years ago

jackd doesn't start w/ ffado and realtime

Reported by: gunde Assigned to:
Priority: major Milestone: FFADO 2.0
Component: Version: FFADO 2.0-beta7 (1.999.38)
Keywords: Cc:
The device the bug applies to:

Description

the problem is that jackd running on my maudio FW solo doesn't start w/ the firewire extention and the realtime option. it works well on freebob w/ realtime and w/ firewire w/out realtime but then the same error message:

Error (PosixThread?.cpp)[ 135] Start: Cannot set create thread 22 Invalid argument Fatal (CycleTimerHelper?.cpp)[ 150] Start: Could not start update thread Fatal (ieee1394service.cpp)[ 327] initialize: Could not start CycleTimerHelper? Fatal (devicemanager.cpp)[ 184] initialize: Could not initialize Ieee1349Service object for port 0 Fatal (ffado.cpp)[ 152] ffado_streaming_init: Could not initialize device manager

i'm using the ffado deb packages from the apt repository. i have the following packages installed: ii ffado-dbus-server 2.0~beta7-2 FFADO DBUS server ii ffado-mixer-qt4 2.0~beta7-2 FFADO DBUS mixer applets ii ffado-tools 2.0~beta7-2 FFADO tools ii libffado2 2.0~beta7-2 FFADO API ii libffado2-dev 2.0~beta7-2 FFADO API - development files

jackd is compiled and installed from the latest svn source: jackd version 0.115.1 tmpdir /dev/shm protocol 23

the kernel is from the vanilla tree with the rt patch applied, and is running on Debian lenny/sid gunde@gndbox ~% uname -a Linux gndbox 2.6.26.6-rt11-g2.1 #1 SMP PREEMPT RT Tue Nov 18 03:52:11 CET 2008 i686 GNU/Linux

the machine is an acer aspire 5024 laptop w/ an amd turion 1800mhz 1 gb ram

i included the jack log and the ffado diagnostic log. i might try the latest svn of ffado to see if there is any change.


gunde@gndbox ~% jackd --verbose -R -d firewire -v6 -n3 -p 2048 getting driver descriptor from /usr/lib/jack/jack_freebob.so getting driver descriptor from /usr/lib/jack/jack_oss.so getting driver descriptor from /usr/lib/jack/jack_firewire.so no message buffer overruns getting driver descriptor from /usr/lib/jack/jack_alsa.so getting driver descriptor from /usr/lib/jack/jack_dummy.so jackd 0.115.1 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 start poll on 3 fd's loading driver .. new client: firewire_pcm, id = 1 type 1 @ 0x80696c0 fd = -1 new buffer size 2048 03213877931: (ffado.cpp)[ 92] ffado_streaming_init: libffado 1.999.38- built Oct 30 2008 22:22:52 03213881856: Debug (Element.cpp)[ 123] setVerboseLevel: Setting verbose level to 6... 03213881983: Debug (StreamProcessorManager?.cpp)[1335] setVerboseLevel: Setting verbose level to 6... 03213883037: Debug (devicemanager.cpp)[1081] setVerboseLevel: Setting verbose level to 6... 03213883161: Debug (DeviceStringParser?.cpp)[ 277] isValidString: isvalid? hw:0 03213883283: Debug (devicemanager.cpp)[ 207] addSpecString: Adding spec string hw:0 03213883394: Debug (DeviceStringParser?.cpp)[ 246] parseString: parse: hw:0 03213883500: Debug (DeviceStringParser?.cpp)[ 251] parseString: left: hw:0 03213883612: Debug (DeviceStringParser?.cpp)[ 56] parse: parse: hw:0 03213883748: Debug (ffado.cpp)[ 140] ffado_streaming_init: setting slave mode to 0 03213883876: Debug (ffado.cpp)[ 146] ffado_streaming_init: setting snoop mode to 0 03213884218: Debug (Configuration.cpp)[ 62] openFile: Could not open file: ~/.ffado/configuration 03213886825: Debug (devicemanager.cpp)[ 168] initialize: Found 1 firewire adapters (ports) 03213888150: Debug (IsoHandlerManager?.cpp)[1191] setVerboseLevel: Setting verbose level to 6... 03213888266: Debug (ieee1394service.cpp)[1328] setVerboseLevel: Setting verbose level to 6... 03213888390: Debug (ieee1394service.cpp)[ 360] setThreadParameters: Switching IsoManager? to (rt=1, prio=10) 03213888504: Debug (IsoHandlerManager?.cpp)[ 533] setThreadParameters: (0x8072c60) switch to: (rt=1, prio=10)... 03213888695: Debug (Configuration.cpp)[ 285] getSetting: temporary has no setting ieee1394.isomanager.prio_increase 03213888879: Debug (Configuration.cpp)[ 285] getSetting: /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase 03213889030: Debug (Configuration.cpp)[ 246] getValueForSetting: path 'ieee1394.isomanager.prio_increase' not found 03213889215: Debug (Configuration.cpp)[ 285] getSetting: temporary has no setting ieee1394.isomanager.prio_increase_xmit 03213889399: Debug (Configuration.cpp)[ 285] getSetting: /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_xmit 03213889525: Debug (Configuration.cpp)[ 246] getValueForSetting: path 'ieee1394.isomanager.prio_increase_xmit' not found 03213889707: Debug (Configuration.cpp)[ 285] getSetting: temporary has no setting ieee1394.isomanager.prio_increase_recv 03213889891: Debug (Configuration.cpp)[ 285] getSetting: /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_recv 03213890128: Debug (Configuration.cpp)[ 246] getValueForSetting: path 'ieee1394.isomanager.prio_increase_recv' not found 03213891127: Debug (ieee1394service.cpp)[ 366] setThreadParameters: Switching CycleTimerHelper? to (rt=1, prio=0) 03213891241: Debug (CycleTimerHelper?.cpp)[ 242] setThreadParameters: (0x8072ca0) switch to: (rt=1, prio=0)... 03213891394: Debug (Watchdog.cpp)[ 200] start: (0x8072e28) Starting watchdog... 03213891506: Debug (Watchdog.cpp)[ 201] start: Create hartbeat task/thread for 0x8072e28... 03213891623: Debug (Watchdog.cpp)[ 215] start: hartbeat task: 0x8076e70, thread 0x8072e78... 03213891733: Debug (Watchdog.cpp)[ 217] start: Create check task/thread for 0x8072e28... 03213891847: Debug (Watchdog.cpp)[ 231] start: check task: 0x8072e98, thread 0x8072ed0... 03213944676: Debug (Watchdog.cpp)[ 249] start: (0x8072e28) Watchdog running... 03213945074: Debug (ieee1394service.cpp)[ 264] initialize: This system supports the raw1394_read_cycle_timer call, using it. 03213945358: Debug (Configuration.cpp)[ 285] getSetting: temporary has no setting ieee1394.min_split_timeout_usecs 03213945545: Debug (Configuration.cpp)[ 285] getSetting: /usr/share/libffado/configuration has no setting ieee1394.min_split_timeout_usecs 03213945662: Debug (Configuration.cpp)[ 246] getValueForSetting: path 'ieee1394.min_split_timeout_usecs' not found 03213945776: Debug (ieee1394service.cpp)[ 886] getSplitTimeoutUsecs: reading SPLIT_TIMEOUT on node 0x0... 03213945918: Debug (ieee1394service.cpp)[ 893] getSplitTimeoutUsecs: READ HI: 0x01000000 03213946074: Debug (ieee1394service.cpp)[ 900] getSplitTimeoutUsecs: READ LO: 0x00000000 03213946179: Debug (ieee1394service.cpp)[ 309] initialize: Minimum SPLIT_TIMEOUT: 1000000. Current: 1000000 03213946285: Debug (CycleTimerHelper?.cpp)[ 126] Start: Start 0x8072ca0... 03213946382: Debug (CycleTimerHelper?.cpp)[ 159] initValues: (0x8072ca0) Init values... 03213946482: Debug (CycleTimerHelper?.cpp)[ 166] initValues: Read CTR... 03213946584: Debug (CycleTimerHelper?.cpp)[ 177] initValues: read : CTR: 3119225011, local: 1227019400542394 03213946691: Debug (CycleTimerHelper?.cpp)[ 183] initValues: ctr : 0xB9EB98B3 2285155507 (092s 7865cy 2227ticks) 03213946799: Debug (CycleTimerHelper?.cpp)[ 189] initValues: requesting DLL re-init... 03213947969: Debug (CycleTimerHelper?.cpp)[ 306] initDLL: (0x8072ca0) First run 03213948099: Debug (CycleTimerHelper?.cpp)[ 309] initDLL: usecs/update: 200000, ticks/update: 4915200, m_dll_e2: 4915200.000000 03213948231: Debug (CycleTimerHelper?.cpp)[ 312] initDLL: usecs current: 1227019400543775.000000, next: 1227019400743775.000000 03213948355: Debug (CycleTimerHelper?.cpp)[ 315] initDLL: ticks current: 2285189449.000000, next: 2290104649.000000 03213948470: Debug (CycleTimerHelper?.cpp)[ 199] initValues: ready... 03213948575: Debug (Watchdog.cpp)[ 281] registerThread: (0x8072e28) Adding thread 0x8075208 03213983387: Error (PosixThread?.cpp)[ 135] Start: Cannot set create thread 22 Invalid argument 03213983603: Fatal (CycleTimerHelper?.cpp)[ 150] Start: Could not start update thread 03213983713: Fatal (ieee1394service.cpp)[ 327] initialize: Could not start CycleTimerHelper? 03213983821: Fatal (devicemanager.cpp)[ 184] initialize: Could not initialize Ieee1349Service object for port 0 03213983931: Fatal (ffado.cpp)[ 152] ffado_streaming_init: Could not initialize device manager 03213984075: Debug (Configuration.cpp)[ 137] save: Not saving temporary config file: temporary 03213984182: Debug (Configuration.cpp)[ 134] save: Not saving readonly config file: /usr/share/libffado/configuration 03213984323: Debug (IsoHandlerManager?.cpp)[1127] stopHandlers: enter... 03213984422: Error (IsoHandlerManager?.cpp)[1131] stopHandlers: Incorrect state, expected E_Running, got Created 03213984531: Debug (IsoHandlerManager?.cpp)[ 977] pruneHandlers: enter... firewire ERR: Error creating FFADO streaming device cannot load driver module firewire starting server engine shutdown server thread back from poll freeing shared port segments stopping server thread last xrun delay: 0.000 usecs max delay reported by backend: 0.000 usecs freeing engine shared memory max usecs: 0.000, engine deleted cleaning up shared memory cleaning up files unregistering server `default' no message buffer overruns


FFADO diagnostic utility 0.1 ============================ (C) 2008 Pieter Palmers

CHECK

Base system...

kernel version............ 2.6.26.6-rt11-g2.1

FIXME: implement test for RT kernel

RT patched............... False

old 1394 stack present.... True old 1394 stack loaded..... True new 1394 stack present.... False new 1394 stack loaded..... False /dev/raw1394 node present. True /dev/raw1394 permissions.. True

Prerequisites...

gcc................ gcc (Debian 4.3.2-1) 4.3.2 g++................ g++ (Debian 4.3.2-1) 4.3.2 PyQt?............... sh: pyuic: command not found jackd.............. jackd version 0.115.1 tmpdir /dev/shm protocol 23

path............. /usr/bin/jackd flags............ -ljack -lpthread -lrt

libraw1394......... 1.3.0

flags............ -lraw1394

libavc1394......... 0.5.3

flags............ -lavc1394 -lrom1394 -lraw1394

libiec61883........ 1.1.1

flags............ -liec61883 -lraw1394

libxml++-2.6....... 2.20.0

flags............ -I/usr/include/libxml++-2.6 -I/usr/lib/libxml++-2.6/include -I/usr/include/libxml2 -I/usr/include/glibmm-2.4 -I/usr/lib/glibmm-2.4/include -I/usr/include/sigc++-2.0 -I/usr/lib/sigc++-2.0/include -I/usr/include/glib-2.0 -I/usr/lib/glib-2.0/include -lxml++-2.6 -lxml2 -lglibmm-2.4 -lgobject-2.0 -lsigc-2.0 -lglib-2.0

dbus-1............. Package dbus-1 was not found in the pkg-config search path.

Perhaps you should add the directory containing `dbus-1.pc' to the PKG_CONFIG_PATH environment variable No package 'dbus-1' found

flags............ Package dbus-1 was not found in the pkg-config search path.

Perhaps you should add the directory containing `dbus-1.pc' to the PKG_CONFIG_PATH environment variable No package 'dbus-1' found

Hardware...

Host controllers:

06:06.2 FireWire? (IEEE 1394) [0c00]: Texas Instruments OHCI Compliant IEEE 1394 Host Controller [104c:8032] (prog-if 10 [OHCI])

Subsystem: Acer Incorporated [ALI] Aspire 5024WLMi [1025:0080] Control: I/O- Mem+ BusMaster?+ SpecCycle?- MemWINV+ VGASnoop- ParErr?- Stepping- SERR- FastB2B- DisINTx- Status: Cap+ 66MHz- UDF- FastB2B- ParErr?- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx- Latency: 64 (500ns min, 1000ns max), Cache Line Size: 32 bytes Interrupt: pin C routed to IRQ 22 Region 0: Memory at c0208000 (32-bit, non-prefetchable) [size=2K] Region 1: Memory at c0200000 (32-bit, non-prefetchable) [size=16K] Capabilities: <access denied> Kernel driver in use: ohci1394 Kernel modules: ohci1394

CPU info:

processor : 0 vendor_id : AuthenticAMD cpu family : 15 model : 36 model name : AMD Turion(tm) 64 Mobile Technology ML-34 stepping : 2 cpu MHz : 800.000 cache size : 1024 KB fdiv_bug : no hlt_bug : no f00f_bug : no coma_bug : no fpu : yes fpu_exception : yes cpuid level : 1 wp : yes flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 syscall nx mmxext fxsr_opt lm 3dnowext 3dnow up pni lahf_lm bogomips : 1601.07 clflush size : 64 power management: ts fid vid ttp tm stc

Configuration...

IRQ information

Hardware Interrupts:


IRQ 0: PID: None, count: [45720], Sched None (priority None), drivers: timer? IRQ 1: PID: 311, count: [8887], Sched FF (priority 60), drivers: i8042? IRQ 8: PID: 1134, count: [2], Sched FF (priority 80), drivers: rtc0? IRQ 12: PID: 310, count: [325926], Sched FF (priority 59), drivers: i8042? IRQ 14: PID: 381, count: [48825], Sched FF (priority 50), drivers: ide0? IRQ 15: PID: 382, count: [35752], Sched FF (priority 50), drivers: ide1? IRQ 17: PID: 1233, count: [332], Sched FF (priority 70), drivers: ATI IXP? IRQ 18: PID: 2864, count: [0], Sched FF (priority 50), drivers: radeon? IRQ 19: PID: 1057, count: [62950], Sched FF (priority 65), drivers: ['ehci_hcd:usb1', 'ohci_hcd:usb2', 'ohci_hcd:usb3'] IRQ 20: PID: 1173, count: [1], Sched FF (priority 50), drivers: ['yenta', 'mmc0', 'mmc1', 'mmc2'] IRQ 21: PID: 51, count: [130875], Sched FF (priority 50), drivers: ['acpi', 'ndiswrapper'] IRQ 22: PID: 1166, count: [524003], Sched FF (priority 75), drivers: ohci1394?

Software Interrupts:


REPORT

FireWire? kernel drivers: [PASS] Kernel modules present and correctly loaded. [PASS] /dev/raw1394 node present and accessible.

Attachments

ffado-diag.log2 (4.2 kB) - added by gunde on 11/18/08 07:17:14.
ffado diagnostic
ffado-diag.2.log2 (4.2 kB) - added by gunde on 11/18/08 07:17:52.
ffado diagnostic
ffado-jack-whole.log (7.6 kB) - added by gunde on 11/18/08 07:18:52.
jack log

Change History

11/18/08 07:17:14 changed by gunde

  • attachment ffado-diag.log2 added.

ffado diagnostic

11/18/08 07:17:52 changed by gunde

  • attachment ffado-diag.2.log2 added.

ffado diagnostic

11/18/08 07:18:52 changed by gunde

  • attachment ffado-jack-whole.log added.

jack log

11/20/08 04:36:14 changed by ppalmers

  • status changed from new to closed.
  • resolution set to fixed.

this is a side effect of a change in jackd. It has already been fixed in the ffado repository.

The options for you are: * upgrade to the latest maintenance repo version of ffado * use an older jackd