[alsa-devel] : Kernal+debug installed: Help with TASCAM US-122

Joseph Spencer joespenceris at gmail.com
Thu Aug 9 08:38:41 CEST 2018


OK,

I booted with nomodeset in the kernel options.  This time I was able to
plug the tascam us-122 in to usb and the UI didn't freeze; however, pulse
audio never came up.  I'm attaching the journalctl logs.  You can see that
it never gets past "task pulseaudio:1750 blocked for more than 120 seconds".

Joe Spencer
602.777.2307


On Wed, Aug 8, 2018 at 11:49 AM Joseph Spencer <joespenceris at gmail.com>
wrote:

> I'll give that a shot!
>
> Also, I thought the following lines in journalctl were a bit odd:
>
> first:
>
> Aug 04 03:13:50 localhost.localdomain /lib/udev/tascam_fw[2834]: load
> /usr/share/alsa/firmware/usx2yloader/us122fw.ihx for 1604/8006/100 to
> /dev/bus/usb/003/002
> Aug 04 03:13:50 localhost.localdomain /lib/udev/tascam_fw[2837]: load
> /usr/share/alsa/firmware/usx2yloader/us122fw.ihx for 1604/8006/100 to
>
>
> second:
>
> Aug 04 03:13:52 localhost.localdomain /lib/udev/tascam_fpga[2859]: calling
> /usr/bin/usx2yloader for
> Aug 04 03:13:52 localhost.localdomain /lib/udev/tascam_fpga[2861]: leaving
> Aug 04 03:13:52 localhost.localdomain kernel: usbcore: registered new
> interface driver snd-usb-usx2y
> Aug 04 03:13:52 localhost.localdomain /lib/udev/tascam_fpga[2864]: calling
> /usr/bin/usx2yloader for
> Aug 04 03:13:54 localhost.localdomain /lib/udev/tascam_fpga[2867]: leaving
>
>
> I thought it was stranged that "to" and "for" had missing values after
> them.
>
> So in the respective /lib/udev/tascam_f* scripts I added some debug
> output...
>
> In /lib/udev/tascam_fpga I added:
>
> debug_date=$(date +%S%N)
>
> env > "/fpga-${debug_date}.env"
>
>
> In /lib/udev/tascam_fw I added:
>
> debug_date=$(date +%S%N)
> env > "/fw-${debug_date}.env"
>
> Plugged my device in and sure enough, DEVNAME wasn't set in tascam_fw on
> the 2nd run, and DEVICE is never set in tascam_fpga.
>
> Could it be that I don't have udev configured properly?
>
>
> Joe Spencer
> 602.777.2307
>
>
> On Wed, Aug 8, 2018 at 11:35 AM Takashi Iwai <tiwai at suse.de> wrote:
>
>> On Wed, 08 Aug 2018 18:46:13 +0200,
>> Joseph Spencer wrote:
>> >
>> > I installed the kernal-debug package along with debug info (Linux
>> > localhost.localdomain 4.17.11-200.fc28.x86_64+debug #1 SMP Mon Jul 30
>> > 14:53:06 UTC 2018 x86_64 x86_64 x86_64 GNU/Linux).
>> >
>> > I looked in /boot/config-4.17.11-200.fc28.x86_64+debug and I see that
>> the
>> > debug options are now enabled (attaching this file in case there are
>> more
>> > options I can enable).
>> >
>> > I repeated another failure.  The output in journalctl -f appears to me
>> to
>> > be about the same (see attached)
>> >
>> > Any pointers on what I could enable or do to debug this further?
>>
>> If so, then try to disable i915 KMS. e.g. boot with nomodeset option,
>> and check whether the crash still happens.
>>
>>
>> Takashi
>>
>
-------------- next part --------------
-- Logs begin at Tue 2017-05-23 09:32:46 MST, end at Wed 2018-08-08 23:33:37 MST. --
Aug 08 23:16:44 localhost.localdomain kernel: usb 3-2: new full-speed USB device number 2 using xhci_hcd
Aug 08 23:16:44 localhost.localdomain kernel: usb 3-2: New USB device found, idVendor=1604, idProduct=8006, bcdDevice= 1.00
Aug 08 23:16:44 localhost.localdomain kernel: usb 3-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Aug 08 23:16:44 localhost.localdomain /lib/udev/tascam_fw[2219]: load /usr/share/alsa/firmware/usx2yloader/us122fw.ihx for 1604/8006/100 to /dev/bus/usb/003/002
Aug 08 23:16:44 localhost.localdomain kernel: usb 3-2: USB disconnect, device number 2
Aug 08 23:16:44 localhost.localdomain /lib/udev/tascam_fw[2224]: load /usr/share/alsa/firmware/usx2yloader/us122fw.ihx for 1604/8006/100 to
Aug 08 23:16:46 localhost.localdomain kernel: usb 3-2: new full-speed USB device number 3 using xhci_hcd
Aug 08 23:16:46 localhost.localdomain kernel: usb 3-2: New USB device found, idVendor=1604, idProduct=8007, bcdDevice= 0.01
Aug 08 23:16:46 localhost.localdomain kernel: usb 3-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Aug 08 23:16:46 localhost.localdomain /lib/udev/tascam_fpga[2247]: calling /usr/bin/usx2yloader for
Aug 08 23:16:46 localhost.localdomain /lib/udev/tascam_fpga[2249]: leaving
Aug 08 23:16:46 localhost.localdomain kernel: usbcore: registered new interface driver snd-usb-usx2y
Aug 08 23:16:46 localhost.localdomain /lib/udev/tascam_fpga[2254]: calling /usr/bin/usx2yloader for
Aug 08 23:16:49 localhost.localdomain /lib/udev/tascam_fpga[2257]: leaving
Aug 08 23:17:18 localhost.localdomain chronyd[1091]: Selected source 107.181.191.189
Aug 08 23:19:23 localhost.localdomain kernel: INFO: task pulseaudio:1750 blocked for more than 120 seconds.
Aug 08 23:19:23 localhost.localdomain kernel:       Tainted: G           OE     4.17.11-200.fc28.x86_64+debug #1
Aug 08 23:19:23 localhost.localdomain kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 08 23:19:23 localhost.localdomain kernel: pulseaudio      D12408  1750   1268 0x00000000
Aug 08 23:19:23 localhost.localdomain kernel: Call Trace:
Aug 08 23:19:23 localhost.localdomain kernel:  ? __schedule+0x2ba/0xaf0
Aug 08 23:19:23 localhost.localdomain kernel:  ? prepare_to_wait_event+0xd2/0x180
Aug 08 23:19:23 localhost.localdomain kernel:  schedule+0x2f/0x90
Aug 08 23:19:23 localhost.localdomain kernel:  usX2Y_urbs_start+0x208/0x470 [snd_usb_usx2y]
Aug 08 23:19:23 localhost.localdomain kernel:  ? finish_wait+0x80/0x80
Aug 08 23:19:23 localhost.localdomain kernel:  snd_usX2Y_pcm_prepare+0xb4/0xd0 [snd_usb_usx2y]
Aug 08 23:19:23 localhost.localdomain kernel:  snd_pcm_do_prepare+0x19/0x30 [snd_pcm]
Aug 08 23:19:23 localhost.localdomain kernel:  snd_pcm_action_single+0x36/0x80 [snd_pcm]
Aug 08 23:19:23 localhost.localdomain kernel:  snd_pcm_action_nonatomic+0x61/0x70 [snd_pcm]
Aug 08 23:19:23 localhost.localdomain kernel:  snd_pcm_common_ioctl+0x3d2/0xb70 [snd_pcm]
Aug 08 23:19:23 localhost.localdomain kernel:  ? selinux_file_ioctl+0x171/0x210
Aug 08 23:19:23 localhost.localdomain kernel:  snd_pcm_ioctl+0x23/0x30 [snd_pcm]
Aug 08 23:19:23 localhost.localdomain kernel:  do_vfs_ioctl+0xa5/0x6d0
Aug 08 23:19:23 localhost.localdomain kernel:  ksys_ioctl+0x60/0x90
Aug 08 23:19:23 localhost.localdomain kernel:  __x64_sys_ioctl+0x16/0x20
Aug 08 23:19:23 localhost.localdomain kernel:  do_syscall_64+0x60/0x1f0
Aug 08 23:19:23 localhost.localdomain kernel:  entry_SYSCALL_64_after_hwframe+0x49/0xbe
Aug 08 23:19:23 localhost.localdomain kernel: RIP: 0033:0x7f59021c4ec7
Aug 08 23:19:23 localhost.localdomain kernel: RSP: 002b:00007ffd83d81448 EFLAGS: 00000206 ORIG_RAX: 0000000000000010
Aug 08 23:19:23 localhost.localdomain kernel: RAX: ffffffffffffffda RBX: 0000556d69312220 RCX: 00007f59021c4ec7
Aug 08 23:19:23 localhost.localdomain kernel: RDX: 0000556d6931ae20 RSI: 0000000000004140 RDI: 0000000000000016
Aug 08 23:19:23 localhost.localdomain kernel: RBP: 00007ffd83d81b60 R08: 0000000000000000 R09: 0000000000001138
Aug 08 23:19:23 localhost.localdomain kernel: R10: 0000000000000004 R11: 0000000000000206 R12: 00007ffd83d81830
Aug 08 23:19:23 localhost.localdomain kernel: R13: 00007ffd83d81cf4 R14: 0000000000000000 R15: 00007ffd83d815c0
Aug 08 23:19:23 localhost.localdomain kernel:
                                              Showing all locks held in the system:
Aug 08 23:19:23 localhost.localdomain kernel: 1 lock held by khungtaskd/38:
Aug 08 23:19:23 localhost.localdomain kernel:  #0: 00000000b04056c7 (tasklist_lock){.+.+}, at: debug_show_all_locks+0x3e/0x165
Aug 08 23:19:23 localhost.localdomain kernel: 2 locks held by pulseaudio/1750:
Aug 08 23:19:23 localhost.localdomain kernel:  #0: 000000008d30dafb (snd_pcm_link_rwsem){++++}, at: snd_pcm_action_nonatomic+0x1e/0x70 [snd_pcm]
Aug 08 23:19:23 localhost.localdomain kernel:  #1: 00000000c8fc4ab6 (&usX2Y(card)->pcm_mutex){+.+.}, at: snd_usX2Y_pcm_prepare+0x38/0xd0 [snd_usb_usx2y]
Aug 08 23:19:23 localhost.localdomain kernel:
Aug 08 23:19:23 localhost.localdomain kernel: =============================================
Aug 08 23:21:26 localhost.localdomain kernel: INFO: task pulseaudio:1750 blocked for more than 120 seconds.
Aug 08 23:21:26 localhost.localdomain kernel:       Tainted: G           OE     4.17.11-200.fc28.x86_64+debug #1
Aug 08 23:21:26 localhost.localdomain kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 08 23:21:26 localhost.localdomain kernel: pulseaudio      D12408  1750   1268 0x00000000
Aug 08 23:21:26 localhost.localdomain kernel: Call Trace:
Aug 08 23:21:26 localhost.localdomain kernel:  ? __schedule+0x2ba/0xaf0
Aug 08 23:21:26 localhost.localdomain kernel:  ? prepare_to_wait_event+0xd2/0x180
Aug 08 23:21:26 localhost.localdomain kernel:  schedule+0x2f/0x90
Aug 08 23:21:26 localhost.localdomain kernel:  usX2Y_urbs_start+0x208/0x470 [snd_usb_usx2y]
Aug 08 23:21:26 localhost.localdomain kernel:  ? finish_wait+0x80/0x80
Aug 08 23:21:26 localhost.localdomain kernel:  snd_usX2Y_pcm_prepare+0xb4/0xd0 [snd_usb_usx2y]
Aug 08 23:21:26 localhost.localdomain kernel:  snd_pcm_do_prepare+0x19/0x30 [snd_pcm]
Aug 08 23:21:26 localhost.localdomain kernel:  snd_pcm_action_single+0x36/0x80 [snd_pcm]
Aug 08 23:21:26 localhost.localdomain kernel:  snd_pcm_action_nonatomic+0x61/0x70 [snd_pcm]
Aug 08 23:21:26 localhost.localdomain kernel:  snd_pcm_common_ioctl+0x3d2/0xb70 [snd_pcm]
Aug 08 23:21:26 localhost.localdomain kernel:  ? selinux_file_ioctl+0x171/0x210
Aug 08 23:21:26 localhost.localdomain kernel:  snd_pcm_ioctl+0x23/0x30 [snd_pcm]
Aug 08 23:21:26 localhost.localdomain kernel:  do_vfs_ioctl+0xa5/0x6d0
Aug 08 23:21:26 localhost.localdomain kernel:  ksys_ioctl+0x60/0x90
Aug 08 23:21:26 localhost.localdomain kernel:  __x64_sys_ioctl+0x16/0x20
Aug 08 23:21:26 localhost.localdomain kernel:  do_syscall_64+0x60/0x1f0
Aug 08 23:21:26 localhost.localdomain kernel:  entry_SYSCALL_64_after_hwframe+0x49/0xbe
Aug 08 23:21:26 localhost.localdomain kernel: RIP: 0033:0x7f59021c4ec7
Aug 08 23:21:26 localhost.localdomain kernel: RSP: 002b:00007ffd83d81448 EFLAGS: 00000206 ORIG_RAX: 0000000000000010
Aug 08 23:21:26 localhost.localdomain kernel: RAX: ffffffffffffffda RBX: 0000556d69312220 RCX: 00007f59021c4ec7
Aug 08 23:21:26 localhost.localdomain kernel: RDX: 0000556d6931ae20 RSI: 0000000000004140 RDI: 0000000000000016
Aug 08 23:21:26 localhost.localdomain kernel: RBP: 00007ffd83d81b60 R08: 0000000000000000 R09: 0000000000001138
Aug 08 23:21:26 localhost.localdomain kernel: R10: 0000000000000004 R11: 0000000000000206 R12: 00007ffd83d81830
Aug 08 23:21:26 localhost.localdomain kernel: R13: 00007ffd83d81cf4 R14: 0000000000000000 R15: 00007ffd83d815c0
Aug 08 23:21:26 localhost.localdomain kernel:
                                              Showing all locks held in the system:
Aug 08 23:21:26 localhost.localdomain kernel: 1 lock held by khungtaskd/38:
Aug 08 23:21:26 localhost.localdomain kernel:  #0: 00000000b04056c7 (tasklist_lock){.+.+}, at: debug_show_all_locks+0x3e/0x165
Aug 08 23:21:26 localhost.localdomain kernel: 2 locks held by pulseaudio/1750:
Aug 08 23:21:26 localhost.localdomain kernel:  #0: 000000008d30dafb (snd_pcm_link_rwsem){++++}, at: snd_pcm_action_nonatomic+0x1e/0x70 [snd_pcm]
Aug 08 23:21:26 localhost.localdomain kernel:  #1: 00000000c8fc4ab6 (&usX2Y(card)->pcm_mutex){+.+.}, at: snd_usX2Y_pcm_prepare+0x38/0xd0 [snd_usb_usx2y]
Aug 08 23:21:26 localhost.localdomain kernel:
Aug 08 23:21:26 localhost.localdomain kernel: =============================================
Aug 08 23:21:37 localhost.localdomain chronyd[1091]: Selected source 65.19.178.219
Aug 08 23:23:29 localhost.localdomain kernel: INFO: task pulseaudio:1750 blocked for more than 120 seconds.
Aug 08 23:23:29 localhost.localdomain kernel:       Tainted: G           OE     4.17.11-200.fc28.x86_64+debug #1
Aug 08 23:23:29 localhost.localdomain kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 08 23:23:29 localhost.localdomain kernel: pulseaudio      D12408  1750   1268 0x00000000
Aug 08 23:23:29 localhost.localdomain kernel: Call Trace:
Aug 08 23:23:29 localhost.localdomain kernel:  ? __schedule+0x2ba/0xaf0
Aug 08 23:23:29 localhost.localdomain kernel:  ? prepare_to_wait_event+0xd2/0x180
Aug 08 23:23:29 localhost.localdomain kernel:  schedule+0x2f/0x90
Aug 08 23:23:29 localhost.localdomain kernel:  usX2Y_urbs_start+0x208/0x470 [snd_usb_usx2y]
Aug 08 23:23:29 localhost.localdomain kernel:  ? finish_wait+0x80/0x80
Aug 08 23:23:29 localhost.localdomain kernel:  snd_usX2Y_pcm_prepare+0xb4/0xd0 [snd_usb_usx2y]
Aug 08 23:23:29 localhost.localdomain kernel:  snd_pcm_do_prepare+0x19/0x30 [snd_pcm]
Aug 08 23:23:29 localhost.localdomain kernel:  snd_pcm_action_single+0x36/0x80 [snd_pcm]
Aug 08 23:23:29 localhost.localdomain kernel:  snd_pcm_action_nonatomic+0x61/0x70 [snd_pcm]
Aug 08 23:23:29 localhost.localdomain kernel:  snd_pcm_common_ioctl+0x3d2/0xb70 [snd_pcm]
Aug 08 23:23:29 localhost.localdomain kernel:  ? selinux_file_ioctl+0x171/0x210
Aug 08 23:23:29 localhost.localdomain kernel:  snd_pcm_ioctl+0x23/0x30 [snd_pcm]
Aug 08 23:23:29 localhost.localdomain kernel:  do_vfs_ioctl+0xa5/0x6d0
Aug 08 23:23:29 localhost.localdomain kernel:  ksys_ioctl+0x60/0x90
Aug 08 23:23:29 localhost.localdomain kernel:  __x64_sys_ioctl+0x16/0x20
Aug 08 23:23:29 localhost.localdomain kernel:  do_syscall_64+0x60/0x1f0
Aug 08 23:23:29 localhost.localdomain kernel:  entry_SYSCALL_64_after_hwframe+0x49/0xbe
Aug 08 23:23:29 localhost.localdomain kernel: RIP: 0033:0x7f59021c4ec7
Aug 08 23:23:29 localhost.localdomain kernel: RSP: 002b:00007ffd83d81448 EFLAGS: 00000206 ORIG_RAX: 0000000000000010
Aug 08 23:23:29 localhost.localdomain kernel: RAX: ffffffffffffffda RBX: 0000556d69312220 RCX: 00007f59021c4ec7
Aug 08 23:23:29 localhost.localdomain kernel: RDX: 0000556d6931ae20 RSI: 0000000000004140 RDI: 0000000000000016
Aug 08 23:23:29 localhost.localdomain kernel: RBP: 00007ffd83d81b60 R08: 0000000000000000 R09: 0000000000001138
Aug 08 23:23:29 localhost.localdomain kernel: R10: 0000000000000004 R11: 0000000000000206 R12: 00007ffd83d81830
Aug 08 23:23:29 localhost.localdomain kernel: R13: 00007ffd83d81cf4 R14: 0000000000000000 R15: 00007ffd83d815c0
Aug 08 23:23:29 localhost.localdomain kernel:
                                              Showing all locks held in the system:
Aug 08 23:23:29 localhost.localdomain kernel: 1 lock held by khungtaskd/38:
Aug 08 23:23:29 localhost.localdomain kernel:  #0: 00000000b04056c7 (tasklist_lock){.+.+}, at: debug_show_all_locks+0x3e/0x165
Aug 08 23:23:29 localhost.localdomain kernel: 2 locks held by pulseaudio/1750:
Aug 08 23:23:29 localhost.localdomain kernel:  #0: 000000008d30dafb (snd_pcm_link_rwsem){++++}, at: snd_pcm_action_nonatomic+0x1e/0x70 [snd_pcm]
Aug 08 23:23:29 localhost.localdomain kernel:  #1: 00000000c8fc4ab6 (&usX2Y(card)->pcm_mutex){+.+.}, at: snd_usX2Y_pcm_prepare+0x38/0xd0 [snd_usb_usx2y]
Aug 08 23:23:29 localhost.localdomain kernel:
Aug 08 23:23:29 localhost.localdomain kernel: =============================================
Aug 08 23:25:31 localhost.localdomain kernel: INFO: task pulseaudio:1750 blocked for more than 120 seconds.
Aug 08 23:25:31 localhost.localdomain kernel:       Tainted: G           OE     4.17.11-200.fc28.x86_64+debug #1
Aug 08 23:25:31 localhost.localdomain kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 08 23:25:31 localhost.localdomain kernel: pulseaudio      D12408  1750   1268 0x00000000
Aug 08 23:25:31 localhost.localdomain kernel: Call Trace:
Aug 08 23:25:31 localhost.localdomain kernel:  ? __schedule+0x2ba/0xaf0
Aug 08 23:25:31 localhost.localdomain kernel:  ? prepare_to_wait_event+0xd2/0x180
Aug 08 23:25:31 localhost.localdomain kernel:  schedule+0x2f/0x90
Aug 08 23:25:31 localhost.localdomain kernel:  usX2Y_urbs_start+0x208/0x470 [snd_usb_usx2y]
Aug 08 23:25:31 localhost.localdomain kernel:  ? finish_wait+0x80/0x80
Aug 08 23:25:31 localhost.localdomain kernel:  snd_usX2Y_pcm_prepare+0xb4/0xd0 [snd_usb_usx2y]
Aug 08 23:25:31 localhost.localdomain kernel:  snd_pcm_do_prepare+0x19/0x30 [snd_pcm]
Aug 08 23:25:31 localhost.localdomain kernel:  snd_pcm_action_single+0x36/0x80 [snd_pcm]
Aug 08 23:25:31 localhost.localdomain kernel:  snd_pcm_action_nonatomic+0x61/0x70 [snd_pcm]
Aug 08 23:25:31 localhost.localdomain kernel:  snd_pcm_common_ioctl+0x3d2/0xb70 [snd_pcm]
Aug 08 23:25:31 localhost.localdomain kernel:  ? selinux_file_ioctl+0x171/0x210
Aug 08 23:25:31 localhost.localdomain kernel:  snd_pcm_ioctl+0x23/0x30 [snd_pcm]
Aug 08 23:25:31 localhost.localdomain kernel:  do_vfs_ioctl+0xa5/0x6d0
Aug 08 23:25:31 localhost.localdomain kernel:  ksys_ioctl+0x60/0x90
Aug 08 23:25:31 localhost.localdomain kernel:  __x64_sys_ioctl+0x16/0x20
Aug 08 23:25:31 localhost.localdomain kernel:  do_syscall_64+0x60/0x1f0
Aug 08 23:25:31 localhost.localdomain kernel:  entry_SYSCALL_64_after_hwframe+0x49/0xbe
Aug 08 23:25:31 localhost.localdomain kernel: RIP: 0033:0x7f59021c4ec7
Aug 08 23:25:31 localhost.localdomain kernel: RSP: 002b:00007ffd83d81448 EFLAGS: 00000206 ORIG_RAX: 0000000000000010
Aug 08 23:25:31 localhost.localdomain kernel: RAX: ffffffffffffffda RBX: 0000556d69312220 RCX: 00007f59021c4ec7
Aug 08 23:25:31 localhost.localdomain kernel: RDX: 0000556d6931ae20 RSI: 0000000000004140 RDI: 0000000000000016
Aug 08 23:25:31 localhost.localdomain kernel: RBP: 00007ffd83d81b60 R08: 0000000000000000 R09: 0000000000001138
Aug 08 23:25:31 localhost.localdomain kernel: R10: 0000000000000004 R11: 0000000000000206 R12: 00007ffd83d81830
Aug 08 23:25:31 localhost.localdomain kernel: R13: 00007ffd83d81cf4 R14: 0000000000000000 R15: 00007ffd83d815c0
Aug 08 23:25:31 localhost.localdomain kernel:
                                              Showing all locks held in the system:
Aug 08 23:25:31 localhost.localdomain kernel: 1 lock held by khungtaskd/38:
Aug 08 23:25:31 localhost.localdomain kernel:  #0: 00000000b04056c7 (tasklist_lock){.+.+}, at: debug_show_all_locks+0x3e/0x165
Aug 08 23:25:31 localhost.localdomain kernel: 2 locks held by pulseaudio/1750:
Aug 08 23:25:31 localhost.localdomain kernel:  #0: 000000008d30dafb (snd_pcm_link_rwsem){++++}, at: snd_pcm_action_nonatomic+0x1e/0x70 [snd_pcm]
Aug 08 23:25:31 localhost.localdomain kernel:  #1: 00000000c8fc4ab6 (&usX2Y(card)->pcm_mutex){+.+.}, at: snd_usX2Y_pcm_prepare+0x38/0xd0 [snd_usb_usx2y]
Aug 08 23:25:31 localhost.localdomain kernel:
Aug 08 23:25:31 localhost.localdomain kernel: =============================================
Aug 08 23:25:42 localhost.localdomain systemd[1]: Starting dnf makecache...
Aug 08 23:25:48 localhost.localdomain dnf[2281]: enabling updates-debuginfo repository
Aug 08 23:25:48 localhost.localdomain dnf[2281]: enabling fedora-debuginfo repository
Aug 08 23:25:48 localhost.localdomain dnf[2281]: enabling rpmfusion-free-updates-debuginfo repository
Aug 08 23:25:48 localhost.localdomain dnf[2281]: enabling rpmfusion-free-debuginfo repository
Aug 08 23:25:48 localhost.localdomain dnf[2281]: enabling rpmfusion-nonfree-updates-debuginfo repository
Aug 08 23:25:48 localhost.localdomain dnf[2281]: enabling rpmfusion-nonfree-debuginfo repository
Aug 08 23:25:48 localhost.localdomain dnf[2281]: Metadata timer caching disabled when running on a battery.
Aug 08 23:25:48 localhost.localdomain systemd[1]: Started dnf makecache.
Aug 08 23:25:48 localhost.localdomain audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 08 23:25:48 localhost.localdomain audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 08 23:27:34 localhost.localdomain kernel: INFO: task pulseaudio:1750 blocked for more than 120 seconds.
Aug 08 23:27:34 localhost.localdomain kernel:       Tainted: G           OE     4.17.11-200.fc28.x86_64+debug #1
Aug 08 23:27:34 localhost.localdomain kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 08 23:27:34 localhost.localdomain kernel: pulseaudio      D12408  1750   1268 0x00000000
Aug 08 23:27:34 localhost.localdomain kernel: Call Trace:
Aug 08 23:27:34 localhost.localdomain kernel:  ? __schedule+0x2ba/0xaf0
Aug 08 23:27:34 localhost.localdomain kernel:  ? prepare_to_wait_event+0xd2/0x180
Aug 08 23:27:34 localhost.localdomain kernel:  schedule+0x2f/0x90
Aug 08 23:27:34 localhost.localdomain kernel:  usX2Y_urbs_start+0x208/0x470 [snd_usb_usx2y]
Aug 08 23:27:34 localhost.localdomain kernel:  ? finish_wait+0x80/0x80
Aug 08 23:27:34 localhost.localdomain kernel:  snd_usX2Y_pcm_prepare+0xb4/0xd0 [snd_usb_usx2y]
Aug 08 23:27:34 localhost.localdomain kernel:  snd_pcm_do_prepare+0x19/0x30 [snd_pcm]
Aug 08 23:27:34 localhost.localdomain kernel:  snd_pcm_action_single+0x36/0x80 [snd_pcm]
Aug 08 23:27:34 localhost.localdomain kernel:  snd_pcm_action_nonatomic+0x61/0x70 [snd_pcm]
Aug 08 23:27:34 localhost.localdomain kernel:  snd_pcm_common_ioctl+0x3d2/0xb70 [snd_pcm]
Aug 08 23:27:34 localhost.localdomain kernel:  ? selinux_file_ioctl+0x171/0x210
Aug 08 23:27:34 localhost.localdomain kernel:  snd_pcm_ioctl+0x23/0x30 [snd_pcm]
Aug 08 23:27:34 localhost.localdomain kernel:  do_vfs_ioctl+0xa5/0x6d0
Aug 08 23:27:34 localhost.localdomain kernel:  ksys_ioctl+0x60/0x90
Aug 08 23:27:34 localhost.localdomain kernel:  __x64_sys_ioctl+0x16/0x20
Aug 08 23:27:34 localhost.localdomain kernel:  do_syscall_64+0x60/0x1f0
Aug 08 23:27:34 localhost.localdomain kernel:  entry_SYSCALL_64_after_hwframe+0x49/0xbe
Aug 08 23:27:34 localhost.localdomain kernel: RIP: 0033:0x7f59021c4ec7
Aug 08 23:27:34 localhost.localdomain kernel: RSP: 002b:00007ffd83d81448 EFLAGS: 00000206 ORIG_RAX: 0000000000000010
Aug 08 23:27:34 localhost.localdomain kernel: RAX: ffffffffffffffda RBX: 0000556d69312220 RCX: 00007f59021c4ec7
Aug 08 23:27:34 localhost.localdomain kernel: RDX: 0000556d6931ae20 RSI: 0000000000004140 RDI: 0000000000000016
Aug 08 23:27:34 localhost.localdomain kernel: RBP: 00007ffd83d81b60 R08: 0000000000000000 R09: 0000000000001138
Aug 08 23:27:34 localhost.localdomain kernel: R10: 0000000000000004 R11: 0000000000000206 R12: 00007ffd83d81830
Aug 08 23:27:34 localhost.localdomain kernel: R13: 00007ffd83d81cf4 R14: 0000000000000000 R15: 00007ffd83d815c0
Aug 08 23:27:34 localhost.localdomain kernel:
                                              Showing all locks held in the system:
Aug 08 23:27:34 localhost.localdomain kernel: 1 lock held by khungtaskd/38:
Aug 08 23:27:34 localhost.localdomain kernel:  #0: 00000000b04056c7 (tasklist_lock){.+.+}, at: debug_show_all_locks+0x3e/0x165
Aug 08 23:27:34 localhost.localdomain kernel: 2 locks held by pulseaudio/1750:
Aug 08 23:27:34 localhost.localdomain kernel:  #0: 000000008d30dafb (snd_pcm_link_rwsem){++++}, at: snd_pcm_action_nonatomic+0x1e/0x70 [snd_pcm]
Aug 08 23:27:34 localhost.localdomain kernel:  #1: 00000000c8fc4ab6 (&usX2Y(card)->pcm_mutex){+.+.}, at: snd_usX2Y_pcm_prepare+0x38/0xd0 [snd_usb_usx2y]
Aug 08 23:27:34 localhost.localdomain kernel:
Aug 08 23:27:34 localhost.localdomain kernel: =============================================
Aug 08 23:29:37 localhost.localdomain kernel: INFO: task pulseaudio:1750 blocked for more than 120 seconds.
Aug 08 23:29:37 localhost.localdomain kernel:       Tainted: G           OE     4.17.11-200.fc28.x86_64+debug #1
Aug 08 23:29:37 localhost.localdomain kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 08 23:29:37 localhost.localdomain kernel: pulseaudio      D12408  1750   1268 0x00000000
Aug 08 23:29:37 localhost.localdomain kernel: Call Trace:
Aug 08 23:29:37 localhost.localdomain kernel:  ? __schedule+0x2ba/0xaf0
Aug 08 23:29:37 localhost.localdomain kernel:  ? prepare_to_wait_event+0xd2/0x180
Aug 08 23:29:37 localhost.localdomain kernel:  schedule+0x2f/0x90
Aug 08 23:29:37 localhost.localdomain kernel:  usX2Y_urbs_start+0x208/0x470 [snd_usb_usx2y]
Aug 08 23:29:37 localhost.localdomain kernel:  ? finish_wait+0x80/0x80
Aug 08 23:29:37 localhost.localdomain kernel:  snd_usX2Y_pcm_prepare+0xb4/0xd0 [snd_usb_usx2y]
Aug 08 23:29:37 localhost.localdomain kernel:  snd_pcm_do_prepare+0x19/0x30 [snd_pcm]
Aug 08 23:29:37 localhost.localdomain kernel:  snd_pcm_action_single+0x36/0x80 [snd_pcm]
Aug 08 23:29:37 localhost.localdomain kernel:  snd_pcm_action_nonatomic+0x61/0x70 [snd_pcm]
Aug 08 23:29:37 localhost.localdomain kernel:  snd_pcm_common_ioctl+0x3d2/0xb70 [snd_pcm]
Aug 08 23:29:37 localhost.localdomain kernel:  ? selinux_file_ioctl+0x171/0x210
Aug 08 23:29:37 localhost.localdomain kernel:  snd_pcm_ioctl+0x23/0x30 [snd_pcm]
Aug 08 23:29:37 localhost.localdomain kernel:  do_vfs_ioctl+0xa5/0x6d0
Aug 08 23:29:37 localhost.localdomain kernel:  ksys_ioctl+0x60/0x90
Aug 08 23:29:37 localhost.localdomain kernel:  __x64_sys_ioctl+0x16/0x20
Aug 08 23:29:37 localhost.localdomain kernel:  do_syscall_64+0x60/0x1f0
Aug 08 23:29:37 localhost.localdomain kernel:  entry_SYSCALL_64_after_hwframe+0x49/0xbe
Aug 08 23:29:37 localhost.localdomain kernel: RIP: 0033:0x7f59021c4ec7
Aug 08 23:29:37 localhost.localdomain kernel: RSP: 002b:00007ffd83d81448 EFLAGS: 00000206 ORIG_RAX: 0000000000000010
Aug 08 23:29:37 localhost.localdomain kernel: RAX: ffffffffffffffda RBX: 0000556d69312220 RCX: 00007f59021c4ec7
Aug 08 23:29:37 localhost.localdomain kernel: RDX: 0000556d6931ae20 RSI: 0000000000004140 RDI: 0000000000000016
Aug 08 23:29:37 localhost.localdomain kernel: RBP: 00007ffd83d81b60 R08: 0000000000000000 R09: 0000000000001138
Aug 08 23:29:37 localhost.localdomain kernel: R10: 0000000000000004 R11: 0000000000000206 R12: 00007ffd83d81830
Aug 08 23:29:37 localhost.localdomain kernel: R13: 00007ffd83d81cf4 R14: 0000000000000000 R15: 00007ffd83d815c0
Aug 08 23:29:37 localhost.localdomain kernel:
                                              Showing all locks held in the system:
Aug 08 23:29:37 localhost.localdomain kernel: 1 lock held by khungtaskd/38:
Aug 08 23:29:37 localhost.localdomain kernel:  #0: 00000000b04056c7 (tasklist_lock){.+.+}, at: debug_show_all_locks+0x3e/0x165
Aug 08 23:29:37 localhost.localdomain kernel: 2 locks held by pulseaudio/1750:
Aug 08 23:29:37 localhost.localdomain kernel:  #0: 000000008d30dafb (snd_pcm_link_rwsem){++++}, at: snd_pcm_action_nonatomic+0x1e/0x70 [snd_pcm]
Aug 08 23:29:37 localhost.localdomain kernel:  #1: 00000000c8fc4ab6 (&usX2Y(card)->pcm_mutex){+.+.}, at: snd_usX2Y_pcm_prepare+0x38/0xd0 [snd_usb_usx2y]
Aug 08 23:29:37 localhost.localdomain kernel:
Aug 08 23:29:37 localhost.localdomain kernel: =============================================
Aug 08 23:30:42 localhost.localdomain systemd[1]: Starting Cleanup of Temporary Directories...
Aug 08 23:30:42 localhost.localdomain systemd[1]: Started Cleanup of Temporary Directories.
Aug 08 23:30:42 localhost.localdomain audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 08 23:30:42 localhost.localdomain audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 08 23:30:53 localhost.localdomain sudo[2128]: pam_unix(sudo:session): session closed for user root
Aug 08 23:30:53 localhost.localdomain audit[2128]: USER_END pid=2128 uid=0 auid=1000 ses=1 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Aug 08 23:30:53 localhost.localdomain audit[2128]: CRED_DISP pid=2128 uid=0 auid=1000 ses=1 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:setcred grantors=pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Aug 08 23:30:59 localhost.localdomain systemd-logind[1069]: Power key pressed.
Aug 08 23:30:59 localhost.localdomain systemd-logind[1069]: Suspending...
Aug 08 23:30:59 localhost.localdomain NetworkManager[1107]: <info>  [1533796259.2738] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Aug 08 23:30:59 localhost.localdomain NetworkManager[1107]: <info>  [1533796259.2756] manager: NetworkManager state is now ASLEEP
Aug 08 23:30:59 localhost.localdomain NetworkManager[1107]: <info>  [1533796259.2764] device (wlp2s0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Aug 08 23:30:59 localhost.localdomain NetworkManager[1107]: <info>  [1533796259.3003] device (wlp2s0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Aug 08 23:30:59 localhost.localdomain dbus-daemon[1062]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.6' (uid=0 pid=1107 comm="/usr/sbin/NetworkManager --no-daemon " label="system_u:system_r:NetworkManager_t:s0")
Aug 08 23:30:59 localhost.localdomain avahi-daemon[1104]: Withdrawing address record for fe80::f3ca:ecd8:3b4f:e72a on wlp2s0.
Aug 08 23:30:59 localhost.localdomain avahi-daemon[1104]: Leaving mDNS multicast group on interface wlp2s0.IPv6 with address fe80::f3ca:ecd8:3b4f:e72a.
Aug 08 23:30:59 localhost.localdomain avahi-daemon[1104]: Interface wlp2s0.IPv6 no longer relevant for mDNS.
Aug 08 23:30:59 localhost.localdomain systemd[1]: Starting Network Manager Script Dispatcher Service...
Aug 08 23:30:59 localhost.localdomain NetworkManager[1107]: <info>  [1533796259.3207] dhcp4 (wlp2s0): canceled DHCP transaction, DHCP client pid 2145
Aug 08 23:30:59 localhost.localdomain NetworkManager[1107]: <info>  [1533796259.3208] dhcp4 (wlp2s0): state changed bound -> done
Aug 08 23:30:59 localhost.localdomain kernel: wlp2s0: deauthenticating from 84:1b:5e:03:b3:bd by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 08 23:30:59 localhost.localdomain dbus-daemon[1062]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Aug 08 23:30:59 localhost.localdomain systemd[1]: Started Network Manager Script Dispatcher Service.
Aug 08 23:30:59 localhost.localdomain audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 08 23:30:59 localhost.localdomain nm-dispatcher[2334]: req:1 'connectivity-change': new request (5 scripts)
Aug 08 23:30:59 localhost.localdomain nm-dispatcher[2334]: req:1 'connectivity-change': start running ordered scripts...
Aug 08 23:30:59 localhost.localdomain wpa_supplicant[1236]: wlp2s0: CTRL-EVENT-DISCONNECTED bssid=84:1b:5e:03:b3:bd reason=3 locally_generated=1
Aug 08 23:30:59 localhost.localdomain avahi-daemon[1104]: Withdrawing address record for 192.168.4.5 on wlp2s0.
Aug 08 23:30:59 localhost.localdomain avahi-daemon[1104]: Leaving mDNS multicast group on interface wlp2s0.IPv4 with address 192.168.4.5.
Aug 08 23:30:59 localhost.localdomain wpa_supplicant[1236]: wlp2s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Aug 08 23:30:59 localhost.localdomain avahi-daemon[1104]: Interface wlp2s0.IPv4 no longer relevant for mDNS.
Aug 08 23:30:59 localhost.localdomain NetworkManager[1107]: <info>  [1533796259.3416] device (wlp2s0): set-hw-addr: set MAC address to 5E:3B:66:F9:2E:7E (scanning)
Aug 08 23:30:59 localhost.localdomain kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
Aug 08 23:30:59 localhost.localdomain NetworkManager[1107]: <warn>  [1533796259.3462] sup-iface[0x5571b9fa0170,wlp2s0]: connection disconnected (reason -3)
Aug 08 23:30:59 localhost.localdomain NetworkManager[1107]: <info>  [1533796259.3463] device (wlp2s0): supplicant interface state: completed -> disconnected
Aug 08 23:30:59 localhost.localdomain NetworkManager[1107]: <info>  [1533796259.3473] device (wlp2s0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Aug 08 23:30:59 localhost.localdomain nm-dispatcher[2334]: req:2 'down' [wlp2s0]: new request (5 scripts)
Aug 08 23:30:59 localhost.localdomain NetworkManager[1107]: <info>  [1533796259.3498] device (wlp2s0): set-hw-addr: reset MAC address to 7C:5C:F8:48:B2:32 (unmanage)
Aug 08 23:30:59 localhost.localdomain wpa_supplicant[1236]: nl80211: deinit ifname=p2p-dev-wlp2s0 disabled_11b_rates=0
Aug 08 23:30:59 localhost.localdomain systemd[1]: Reached target Sleep.
Aug 08 23:30:59 localhost.localdomain systemd[1]: Starting Suspend...
Aug 08 23:30:59 localhost.localdomain systemd-sleep[2343]: Suspending system...
Aug 08 23:30:59 localhost.localdomain kernel: PM: suspend entry (deep)


More information about the Alsa-devel mailing list