[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