From: Stefan R. <st...@s5...> - 2012-05-19 10:55:38
|
On May 19 Yves Grenier wrote: > The relevant file was /var/log/kern.log > And here is the log from the moment where I switch the Focusrite Saffire > Pro 24 OFF then ON. The final line is obtained after ffado-test BusReset > prints "Doing busreset on port 0" and does not give back control to the > terminal. I waited a quarter of an hour to be sure that nothing else > would appear in kern.log and I copied the following lines. [...] OK. Unless I missed something, this all looks like it is expected to look like. Does anybody have more ideas how to inspect what ffado-test is waiting for? Maybe strace could give a hint. Again for comparison, below is how the tail of the syscall trace looks for me. I put the full trace at http://paste.debian.net/170095/. $ strace ffado-test BusReset [...about 450 lines deleted...] open("/dev/raw1394", O_RDWR) = -1 ENOENT (No such file or directory) open("/dev/raw1394", O_RDWR) = -1 ENOENT (No such file or directory) epoll_create(16) = 27 pipe([29, 30]) = 0 inotify_init() = 31 inotify_add_watch(31, "/dev", IN_CREATE) = 1 epoll_ctl(27, EPOLL_CTL_ADD, 29, {EPOLLIN, {u32=36404288, u64=36404288}}) = 0 epoll_ctl(27, EPOLL_CTL_ADD, 31, {EPOLLIN, {u32=36404296, u64=36404296}}) = 0 open("/dev", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = 32 getdents(32, /* 164 entries */, 32768) = 4712 open("/dev/fw4", O_RDWR) = 33 ioctl(33, FW_CDEV_IOC_GET_INFO, 0x7fff311f28c0) = 0 close(33) = 0 open("/dev/fw5", O_RDWR) = -1 EACCES (Permission denied) open("/dev/fw3", O_RDWR) = -1 EACCES (Permission denied) open("/dev/fw2", O_RDWR) = -1 EACCES (Permission denied) open("/dev/fw1", O_RDWR) = -1 EACCES (Permission denied) open("/dev/fw0", O_RDWR) = -1 EACCES (Permission denied) getdents(32, /* 0 entries */, 32768) = 0 close(32) = 0 close(31) = 0 close(29) = 0 close(30) = 0 close(27) = 0 ioctl(28, FW_CDEV_IOC_GET_CYCLE_TIMER, 0x7fff311f2830) = 0 clock_nanosleep(CLOCK_REALTIME, 0, {0, 1000000}, NULL) = 0 ioctl(28, FW_CDEV_IOC_GET_CYCLE_TIMER, 0x7fff311f2730) = 0 mmap(NULL, 8392704, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7fcd3b28e000 mprotect(0x7fcd3b28e000, 4096, PROT_NONE) = 0 clone(child_stack=0x7fcd3ba8dfd0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fcd3ba8e9d0, tls=0x7fcd3ba8e700, child_tidptr=0x7fcd3ba8e9d0) = 19504 futex(0x22b7948, FUTEX_WAKE_PRIVATE, 1) = 1 mmap(NULL, 8392704, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7fcd3aa8d000 mprotect(0x7fcd3aa8d000, 4096, PROT_NONE) = 0 clone(child_stack=0x7fcd3b28cfd0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fcd3b28d9d0, tls=0x7fcd3b28d700, child_tidptr=0x7fcd3b28d9d0) = 19505 futex(0x22b7d48, FUTEX_WAKE_PRIVATE, 1) = 1 mmap(NULL, 8392704, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7fcd3a28c000 mprotect(0x7fcd3a28c000, 4096, PROT_NONE) = 0 clone(child_stack=0x7fcd3aa8bfd0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fcd3aa8c9d0, tls=0x7fcd3aa8c700, child_tidptr=0x7fcd3aa8c9d0) = 19506 futex(0x22b7f68, FUTEX_WAKE_PRIVATE, 1) = 1 sched_setscheduler(19505, SCHED_OTHER, { 0 }) = 0 sched_setscheduler(19506, SCHED_OTHER, { 0 }) = 0 sched_setscheduler(19504, SCHED_OTHER, { 0 }) = 0 sched_setscheduler(19503, SCHED_OTHER, { 0 }) = 0 write(1, "Doing busreset on port 0\n", 25Doing busreset on port 0 ) = 25 ioctl(8, FW_CDEV_IOC_INITIATE_BUS_RESET, 0x7fff311f2ab0) = 0 futex(0x7fcd3b28d9d0, FUTEX_WAIT, 19505, NULL) = 0 futex(0x7fcd3aa8c9d0, FUTEX_WAIT, 19506, NULL) = 0 futex(0x7fcd3ba8e9d0, FUTEX_WAIT, 19504, NULL) = 0 write(10, "\0\0\0\0", 4) = 4 futex(0x7fcd3d2919d0, FUTEX_WAIT, 19501, NULL) = 0 write(15, "\0\0\0\0", 4) = 4 futex(0x7fcd3ca909d0, FUTEX_WAIT, 19502, NULL) = 0 munmap(0x7fcd3aa8d000, 8392704) = 0 write(20, "\0\0\0\0", 4) = 4 futex(0x7fcd3c28f9d0, FUTEX_WAIT, 19503, NULL) = 0 munmap(0x7fcd3a28c000, 8392704) = 0 tgkill(19497, 19500, SIGRTMIN) = 0 futex(0x7fcd3da929d0, FUTEX_WAIT, 19500, NULL) = 0 munmap(0x7fcd3b28e000, 8392704) = 0 tgkill(19497, 19499, SIGRTMIN) = 0 futex(0x7fcd3e2939d0, FUTEX_WAIT, 19499, NULL) = 0 munmap(0x7fcd3ca91000, 8392704) = 0 close(6) = 0 close(4) = 0 close(5) = 0 close(8) = 0 close(3) = 0 close(11) = 0 close(9) = 0 close(10) = 0 close(13) = 0 close(7) = 0 close(16) = 0 close(14) = 0 close(15) = 0 close(18) = 0 close(12) = 0 close(21) = 0 close(19) = 0 close(20) = 0 close(23) = 0 close(17) = 0 close(26) = 0 close(24) = 0 close(25) = 0 close(28) = 0 close(22) = 0 futex(0x7fcd3ec95078, FUTEX_WAKE_PRIVATE, 1) = 1 munmap(0x7fcd3c290000, 8392704) = 0 write(2, "no message buffer overruns\n", 27no message buffer overruns ) = 27 munmap(0x7fcd3ea95000, 2101248) = 0 exit_group(0) = ? $ -- Stefan Richter -=====-===-- -=-= =--== http://arcgraph.de/sr/ |