Bug 1413314 - 4.9.3 causes issues in Fedora buildvm's
Summary: 4.9.3 causes issues in Fedora buildvm's
Keywords:
Status: CLOSED CURRENTRELEASE
Alias: None
Product: Fedora
Classification: Fedora
Component: kernel
Version: 25
Hardware: Unspecified
OS: Unspecified
unspecified
unspecified
Target Milestone: ---
Assignee: Kernel Maintainer List
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2017-01-14 20:43 UTC by Kevin Fenzi
Modified: 2019-01-09 12:54 UTC (History)
9 users (show)

Fixed In Version:
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
Environment:
Last Closed: 2017-04-22 08:18:38 UTC
Type: Bug
Embargoed:


Attachments (Terms of Use)

Description Kevin Fenzi 2017-01-14 20:43:54 UTC
I moved our buildvm builders all to the kernel-4.9.3-200.fc25.x86_64 rpm and after a short time many of them started rebooting or failing build jobs. 

These buildvm's are setup as follows: 

* They are on rhel7.3 buildvmhosts. 
* Their storage is an iscsi volume from a netapp, setup on the host as a lvm pv.
* Each buildvm has their own lv
* There are 32 buildvm's with 8 per buildvmhost (4 buildvmhosts). 

No errors were seen on the hosts, only the vm's had any issues. 

I have now moved them back to 4.8.x kernel. 

I managed to get some output from 2 of them, but much of the other logs were gone due to journald crashing.

Comment 1 Kevin Fenzi 2017-01-14 20:44:34 UTC
[ 5211.249781] sched: RT throttling activated
[ 8502.021928] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 8617.467170]  0-...: (0 ticks this GP) idle=1fd/140000000000000/0 softirq=135836/135836 fqs=0
[ 8617.467978]  1-...: (0 ticks this GP) idle=056/0/0 softirq=144862/144862 fqs=0
[ 8617.468660]  2-...: (1 GPs behind) idle=00a/0/0 softirq=159890/159892 fqs=0
[ 8617.469314]  3-...: (1 GPs behind) idle=953/140000000000000/0 softirq=131397/131399 fqs=0
[ 8617.470075]  4-...: (1 GPs behind) idle=b9a/0/0 softirq=119912/119914 fqs=0
[ 8617.470729]  5-...: (1 GPs behind) idle=d35/1/0 softirq=195025/195026 fqs=0
[ 8617.471383]  (detected by 5, t=5 jiffies, g=213106, c=213105, q=0)
[ 8617.472010] Task dump for CPU 3:
[ 8617.472324] kojid           R  running task        0   574      1 0x00000000
[ 8617.473012]  ffffffffa5e0e500 ffff93536fe19600 ffffab61c20979b0 ffffffffa53fd214
[ 8617.473754]  ffffab61c2097a30 0000000000000000 ffffab61c2097a00 ffffffffc03613f5
[ 8617.474495]  000000000000000c ffff93536c93c200 0000000c09a20000 ffff9353484748e8
[ 8617.475237] Call Trace:
[ 8617.475543]  [<ffffffffa58169e5>] ? __schedule+0x225/0x6d0
[ 8617.476039]  [<ffffffffa5114b23>] ? hrtimer_start_range_ns+0x1b3/0x3d0
[ 8617.476644]  [<ffffffffa5816ec6>] schedule+0x36/0x80
[ 8617.477137]  [<ffffffffa581a855>] schedule_hrtimeout_range_clock+0xc5/0x1b0
[ 8617.477751]  [<ffffffffa51142c0>] ? __hrtimer_init+0xa0/0xa0
[ 8617.478243]  [<ffffffffa581a849>] ? schedule_hrtimeout_range_clock+0xb9/0x1b0
[ 8617.478853]  [<ffffffffa581a953>] schedule_hrtimeout_range+0x13/0x20
[ 8617.479407]  [<ffffffffa526eb85>] poll_schedule_timeout+0x45/0x70
[ 8617.480046]  [<ffffffffa5270233>] do_sys_poll+0x4a3/0x550
[ 8617.480574]  [<ffffffffa573decb>] ? ip_finish_output+0x12b/0x1d0
[ 8617.481090]  [<ffffffffa573e91e>] ? ip_output+0x6e/0xf0
[ 8617.481544]  [<ffffffffa573dda0>] ? __ip_flush_pending_frames.isra.41+0x80/0x80
[ 8617.482168]  [<ffffffffa5110f31>] ? lock_timer_base+0x81/0xa0
[ 8617.482664]  [<ffffffffa526ed50>] ? poll_select_copy_remaining+0x150/0x150
[ 8617.483256]  [<ffffffffa56d69c8>] ? sk_reset_timer+0x18/0x30
[ 8617.483768]  [<ffffffffa5758ba2>] ? tcp_connect+0x572/0x820
[ 8617.484265]  [<ffffffffa56e6fcf>] ? secure_tcp_sequence_number+0x7f/0xe0
[ 8617.484854]  [<ffffffffa579bf8b>] ? xfrm_lookup_route+0x1b/0x70
[ 8617.485379]  [<ffffffffa575e254>] ? tcp_v4_connect+0x2c4/0x480
[ 8617.485884]  [<ffffffffa5774e51>] ? __inet_stream_connect+0xb1/0x300
[ 8617.486417]  [<ffffffffa581b8ba>] ? _raw_spin_unlock_bh+0x1a/0x20
[ 8617.486934]  [<ffffffffa56d748c>] ? release_sock+0x8c/0xa0
[ 8617.487397]  [<ffffffffa57750e4>] ? inet_stream_connect+0x44/0x50
[ 8617.487904]  [<ffffffffa56d2f09>] ? SYSC_connect+0xd9/0x110
[ 8617.488375]  [<ffffffffa505cf5e>] ? kvm_clock_get_cycles+0x1e/0x20
[ 8617.488886]  [<ffffffffa5119e4f>] ? ktime_get_ts64+0x4f/0x100
[ 8617.489385]  [<ffffffffa52703d1>] SyS_poll+0x71/0x130
[ 8617.489817]  [<ffffffffa581bbf7>] entry_SYSCALL_64_fastpath+0x1a/0xa9
[ 8617.490352] Task dump for CPU 5:
[ 8617.490624] swapper/5       R  running task        0     0      1 0x00000008
[ 8617.491232]  ffff93536ff43df0 ffffffffa50d06e3 0000000000000005 ffffffffa5f58d00
[ 8617.491887]  ffff93536ff43e08 ffffffffa50d32e7 0000000000000005 ffff93536ff43e40
[ 8617.492547]  ffffffffa51c00b7 ffff93536ff5a400 ffffffffa5e500c0 ffffffffa5e500c0
[ 8617.493230] Call Trace:
[ 8617.493455]  <IRQ>
[ 8617.493641]  [<ffffffffa50d06e3>] sched_show_task+0xd3/0x140
[ 8617.494123]  [<ffffffffa50d32e7>] dump_cpu_task+0x37/0x40
[ 8617.494604]  [<ffffffffa51c00b7>] rcu_dump_cpu_stacks+0x9c/0xc0
[ 8617.495095]  [<ffffffffa510e3dc>] rcu_check_callbacks+0x88c/0x8e0
[ 8617.495638]  [<ffffffffa5124120>] ? tick_sched_do_timer+0x50/0x50
[ 8617.496168]  [<ffffffffa5113cff>] update_process_times+0x2f/0x60
[ 8617.496668]  [<ffffffffa5123a75>] tick_sched_handle.isra.15+0x25/0x60
[ 8617.497201]  [<ffffffffa512415d>] tick_sched_timer+0x3d/0x70
[ 8617.497671]  [<ffffffffa511477e>] __hrtimer_run_queues+0xee/0x260
[ 8617.498174]  [<ffffffffa5114f0a>] hrtimer_interrupt+0x9a/0x180
[ 8617.498663]  [<ffffffffa504bf28>] local_apic_timer_interrupt+0x38/0x60
[ 8617.499205]  [<ffffffffa581e7ad>] smp_apic_timer_interrupt+0x3d/0x50
[ 8617.499756]  [<ffffffffa581d96c>] apic_timer_interrupt+0x8c/0xa0
[ 8617.500282]  <EOI>
[ 8617.500450]  [<ffffffffa581b456>] ? native_safe_halt+0x6/0x10
[ 8617.500938]  [<ffffffffa581b190>] default_idle+0x20/0xd0
[ 8617.501386]  [<ffffffffa50310af>] arch_cpu_idle+0xf/0x20
[ 8617.501828]  [<ffffffffa581b5a3>] default_idle_call+0x23/0x30
[ 8617.502312]  [<ffffffffa50e7b20>] cpu_startup_entry+0x1d0/0x240
[ 8617.502828]  [<ffffffffa504a634>] start_secondary+0x154/0x190
[ 8928.063743] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 8942.362761]  0-...: (0 ticks this GP) idle=fa5/140000000000000/0 softirq=137480/137480 fqs=15000
[ 8942.363585]  (detected by 1, t=74304 jiffies, g=214157, c=214156, q=0)
[ 8942.364211] rcu_sched kthread starved for 14304 jiffies! g214157 c214156 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x100
[ 8942.365182] rcu_sched       W    0     7      2 0x00000000
[ 8942.365698]  ffff93536abe0880 0000000000000000 ffff93536de80000 ffff93536fe59600
[ 8942.366462]  ffff93536deb0000 ffffab61c18cbd78 ffffffffa58169e5 ffff93536fe50140
[ 8942.367261]  00000000ffffffff ffff93536fe59600 ffffab61c18cbdc0 ffff93536de80000
[ 8996.016116] Call Trace:
[ 8996.016366]  [<ffffffffa58169e5>] ? __schedule+0x225/0x6d0
[ 8996.016889]  [<ffffffffa5816ec6>] schedule+0x36/0x80
[ 8996.017375]  [<ffffffffa581a2bb>] schedule_timeout+0x1db/0x3f0
[ 8996.017933]  [<ffffffffa50cc820>] ? resched_cpu+0x60/0x70
[ 8996.018439]  [<ffffffffa51121b0>] ? del_timer_sync+0x50/0x50
[ 8996.018972]  [<ffffffffa510be89>] rcu_gp_kthread+0x4d9/0x960
[ 8996.019482]  [<ffffffffa510b9b0>] ? _synchronize_rcu_expedited.constprop.63+0x320/0x320
[ 8996.020176]  [<ffffffffa50c2569>] kthread+0xd9/0xf0
[ 8996.020657]  [<ffffffffa50c2490>] ? kthread_park+0x60/0x60
[ 8996.021213]  [<ffffffffa581be55>] ret_from_fork+0x25/0x30
[ 9057.028298] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 9057.959259]  (detected by 5, t=60934 jiffies, g=214169, c=214168, q=0)
[ 9057.963265] All QSes seen, last rcu_sched kthread activity 938 (4303724394-4303723456), jiffies_till_next_fqs=3, root ->qsmask 0x0
[ 9057.964359] swapper/5       R  running task        0     0      1 0x00000008
[ 9057.965046]  ffff93536ff43e40
[ 9084.921395] NMI watchdog: BUG: soft lockup - CPU#2 stuck for 22s! [crond:13048]
[ 9084.921396] Modules linked in:
[ 9084.921417]  xt_CHECKSUM
[ 9084.921418]  iptable_mangle
[ 9084.921429]  ipt_MASQUERADE
[ 9084.921429]  nf_nat_masquerade_ipv4
[ 9084.921430]  iptable_nat
[ 9084.921436]  nf_nat_ipv4
[ 9084.921437]  nf_nat
[ 9084.921437]  tun
[ 9084.921440]  bridge
[ 9084.921443]  stp
[ 9084.921445]  llc
[ 9084.921446]  ebtable_filter
[ 9084.921446]  ebtables
[ 9084.921449]  rpcsec_gss_krb5
[ 9084.921450]  nfsv4
[ 9084.921452]  dns_resolver
[ 9084.921453]  nfs
[ 9084.921455]  fscache
[ 9084.921456]  nf_conntrack_ipv4
[ 9084.921456]  nf_defrag_ipv4
[ 9084.921459]  ip6t_REJECT
[ 9084.921461]  nf_reject_ipv6
[ 9084.921462]  nf_conntrack_ipv6
[ 9084.921462]  nf_defrag_ipv6
[ 9084.921463]  xt_conntrack
[ 9084.921463]  nf_conntrack
[ 9084.921464]  ip6table_filter
[ 9084.921464]  ip6_tables
[ 9084.921467]  snd_hda_codec_generic
[ 9084.921471]  snd_hda_intel
[ 9084.921473]  snd_hda_codec
[ 9084.921476]  snd_hda_core
[ 9084.921479]  snd_hwdep
[ 9084.921481]  crct10dif_pclmul
[ 9084.921484]  snd_seq
[ 9084.921486]  crc32_pclmul
[ 9084.921489]  snd_seq_device
[ 9084.921491]  crc32c_intel
[ 9084.921496]  snd_pcm
[ 9084.921498]  ghash_clmulni_intel
[ 9084.921500]  snd_timer
[ 9084.921503]  joydev
[ 9084.921505]  ppdev
[ 9084.921507]  snd
[ 9084.921509]  soundcore
[ 9084.921511]  virtio_net
[ 9084.921513]  virtio_balloon
[ 9084.921516]  i2c_piix4
[ 9084.921516]  i6300esb
[ 9084.921518]  acpi_cpufreq
[ 9084.921521]  tpm_tis
[ 9084.921523]  tpm_tis_core
[ 9084.921525]  tpm
[ 9084.921528]  parport_pc
[ 9084.921530]  parport
[ 9084.921532]  nfsd
[ 9084.921534]  nfs_acl
[ 9084.921535]  lockd
[ 9084.921535]  grace
[ 9084.921536]  auth_rpcgss
[ 9084.921536]  sunrpc
[ 9084.921538]  virtio_console
[ 9084.921539]  virtio_blk
[ 9084.921541]  qxl
[ 9084.921543]  drm_kms_helper
[ 9084.921545]  serio_raw
[ 9084.921548]  ttm
[ 9084.921552]  drm
[ 9084.921554]  virtio_pci
[ 9084.921555]  virtio_ring
[ 9084.921557]  ata_generic
[ 9084.921559]  virtio
[ 9084.921561]  pata_acpi
[ 9084.921569] CPU: 2 PID: 13048 Comm: crond Not tainted 4.9.3-200.fc25.x86_64 #1
[ 9084.921570] Hardware name: Red Hat KVM, BIOS 1.9.1-5.el7 04/01/2014
[ 9084.921571] task: ffff93536b2d3d00 task.stack: ffffab61d285c000
[ 9084.921571] RIP: 0010:[<ffffffffa5129761>]
[ 9084.921580]  [<ffffffffa5129761>] smp_call_function_single+0xd1/0x130
[ 9084.921582] RSP: 0018:ffffab61d285fad0  EFLAGS: 00000202
[ 9084.921582] RAX: 0000000000000000 RBX: 0000000000000005 RCX: 0000000000000830
[ 9084.921583] RDX: 0000000000000001 RSI: 00000000000000fb RDI: 0000000000000830
[ 9084.921583] RBP: ffffab61d285fb18 R08: ffffffffffffffc0 R09: 0000000000000020
[ 9084.921584] R10: 0000000000000002 R11: ffff93537ffde000 R12: ffffffffa5069a60
[ 9084.921584] R13: ffffab61d285fb78 R14: 0000000000000005 R15: ffff93532665fa70
[ 9084.921585] FS:  0000000000000000(0000) GS:ffff93536fe80000(0000) knlGS:0000000000000000
[ 9084.921586] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 9084.921586] CR2: 00007fad8cf7fc30 CR3: 0000000369253000 CR4: 00000000001406e0
[ 9084.921591] Stack:
[ 9084.921593]  ffffab61d285fb78
[ 9084.921593]  ffffffffa522fac1
[ 9084.921593]  0000000000000000
[ 9084.921594]  ffffffffa5069a60
[ 9084.921594]  ffffab61d285fb78
[ 9084.921594]  0000000000000003
[ 9084.921595]  000000005c17609c
[ 9084.921595]  0000000000000002
[ 9084.921595]  ffffffffa5069a60
[ 9084.921596]  ffffab61d285fb60
[ 9084.921596]  ffffffffa5129cb7
[ 9084.921596]  0000000000000002
[ 9084.921596] Call Trace:
[ 9084.921609]  [<ffffffffa522fac1>] ? __slab_free+0xa1/0x2a0
[ 9084.921614]  [<ffffffffa5069a60>] ? do_flush_tlb_all+0x40/0x40
[ 9084.921615]  [<ffffffffa5069a60>] ? do_flush_tlb_all+0x40/0x40
[ 9084.921617]  [<ffffffffa5129cb7>] smp_call_function_many+0x207/0x250
[ 9084.921618]  [<ffffffffa5069fbd>] native_flush_tlb_others+0x12d/0x190
[ 9084.921619]  [<ffffffffa506a134>] flush_tlb_mm_range+0x64/0x150
[ 9084.921623]  [<ffffffffa51f856f>] tlb_flush_mmu_tlbonly+0x2f/0xc0
[ 9084.921629]  [<ffffffffa51f96b4>] tlb_finish_mmu+0x14/0x50
[ 9084.921631]  [<ffffffffa52050b6>] exit_mmap+0xd6/0x170
[ 9084.921637]  [<ffffffffa509e956>] mmput+0x66/0x110
[ 9084.921640]  [<ffffffffa5260882>] flush_old_exec+0x592/0x6c0
[ 9084.921645]  [<ffffffffa52b8e3f>] load_elf_binary+0x3af/0x1670
[ 9084.921651]  [<ffffffffa5379bc0>] ? selinux_inode_permission+0xe0/0x1a0
[ 9084.921652]  [<ffffffffa52600c4>] search_binary_handler+0xa4/0x1c0
[ 9084.921653]  [<ffffffffa52616fa>] do_execveat_common.isra.37+0x58a/0x770
[ 9084.921654]  [<ffffffffa5261b2a>] SyS_execve+0x3a/0x50
[ 9084.921661]  [<ffffffffa5003c17>] do_syscall_64+0x67/0x180
[ 9084.921664]  [<ffffffffa581bcab>] entry_SYSCALL64_slow_path+0x25/0x25
[ 9084.921665] Code:
[ 9084.921666] 25
[ 9084.921666] 28
[ 9084.921666] 00
[ 9084.921667] 00
[ 9084.921667] 00
[ 9084.921667] 75
[ 9084.921667] 70
[ 9084.921668] 48
[ 9084.921668] 83
[ 9084.921668] c4
[ 9084.921668] 38
[ 9084.921669] 5b
[ 9084.921669] 41
[ 9084.921669] 5c
[ 9084.921669] 5d
[ 9084.921670] c3
[ 9084.921670] 48
[ 9084.921670] 8d
[ 9084.921670] 75
[ 9084.921671] c8
[ 9084.921671] 48
[ 9084.921671] 89
[ 9084.921671] d1
[ 9084.921672] 89
[ 9084.921672] df
[ 9084.921672] 4c
[ 9084.921672] 89
[ 9084.921673] e2
[ 9084.921673] e8
[ 9084.921673] 19
[ 9084.921673] fe
[ 9084.921673] ff
[ 9084.921674] ff
[ 9084.921674] 8b
[ 9084.921674] 55
[ 9084.921674] e0
[ 9084.921675] 83
[ 9084.921675] e2
[ 9084.921675] 01
[ 9084.921675] 74
[ 9084.921676] 0a
[ 9084.921676] f3
[ 9084.921676] 90
[ 9084.921676] <8b>
[ 9084.921677] 55
[ 9084.921677] e0
[ 9084.921677] 83
[ 9084.921677] e2
[ 9084.921678] 01
[ 9084.921678] 75
[ 9084.921678] f6
[ 9084.921678] eb
[ 9084.921679] c3
[ 9084.921679] 8b
[ 9084.921679] 05
[ 9084.921679] 8f
[ 9084.921680] f5
[ 9084.921680] 01
[ 9084.921680] 01
[ 9084.921680] 85
[ 9084.921681] c0
[ 9084.921681] 75
[ 9084.921681] 85
[ 9084.921681] 80
[ 9112.921514] NMI watchdog: BUG: soft lockup - CPU#2 stuck for 23s! [crond:13048]
[ 9112.921515] Modules linked in:
[ 9112.921516]  xt_CHECKSUM
[ 9112.921516]  iptable_mangle
[ 9112.921517]  ipt_MASQUERADE
[ 9112.921518]  nf_nat_masquerade_ipv4
[ 9112.921518]  iptable_nat
[ 9112.921518]  nf_nat_ipv4
[ 9112.921519]  nf_nat
[ 9112.921519]  tun
[ 9112.921520]  bridge
[ 9112.921520]  stp
[ 9112.921521]  llc
[ 9112.921521]  ebtable_filter
[ 9112.921522]  ebtables
[ 9112.921522]  rpcsec_gss_krb5
[ 9112.921523]  nfsv4
[ 9112.921523]  dns_resolver
[ 9112.921524]  nfs
[ 9112.921524]  fscache
[ 9112.921525]  nf_conntrack_ipv4
[ 9112.921525]  nf_defrag_ipv4
[ 9112.921525]  ip6t_REJECT
[ 9112.921526]  nf_reject_ipv6
[ 9112.921527]  nf_conntrack_ipv6
[ 9112.921527]  nf_defrag_ipv6
[ 9112.921527]  xt_conntrack
[ 9112.921528]  nf_conntrack
[ 9112.921528]  ip6table_filter
[ 9112.921529]  ip6_tables
[ 9112.921529]  snd_hda_codec_generic
[ 9112.921530]  snd_hda_intel
[ 9112.921530]  snd_hda_codec
[ 9112.921531]  snd_hda_core
[ 9112.921532]  snd_hwdep
[ 9112.921532]  crct10dif_pclmul
[ 9112.921533]  snd_seq
[ 9112.921533]  crc32_pclmul
[ 9112.921534]  snd_seq_device
[ 9112.921534]  crc32c_intel
[ 9112.921535]  snd_pcm
[ 9112.921535]  ghash_clmulni_intel
[ 9112.921536]  snd_timer
[ 9112.921536]  joydev
[ 9112.921537]  ppdev
[ 9112.921537]  snd
[ 9112.921538]  soundcore
[ 9112.921538]  virtio_net
[ 9112.921539]  virtio_balloon
[ 9112.921539]  i2c_piix4
[ 9112.921539]  i6300esb
[ 9112.921540]  acpi_cpufreq
[ 9112.921540]  tpm_tis
[ 9112.921541]  tpm_tis_core
[ 9112.921541]  tpm
[ 9112.921542]  parport_pc
[ 9112.921542]  parport
[ 9112.921543]  nfsd
[ 9112.921543]  nfs_acl
[ 9112.921544]  lockd
[ 9112.921545]  grace
[ 9112.921545]  auth_rpcgss
[ 9112.921545]  sunrpc
[ 9112.921546]  virtio_console
[ 9112.921546]  virtio_blk
[ 9112.921547]  qxl
[ 9112.921547]  drm_kms_helper
[ 9112.921548]  serio_raw
[ 9112.921548]  ttm
[ 9112.921549]  drm
[ 9112.921564]  virtio_pci
[ 9112.921565]  virtio_ring
[ 9112.921565]  ata_generic
[ 9112.921565]  virtio
[ 9112.921566]  pata_acpi
[ 9112.921569] CPU: 2 PID: 13048 Comm: crond Tainted: G             L  4.9.3-200.fc25.x86_64 #1
[ 9112.921569] Hardware name: Red Hat KVM, BIOS 1.9.1-5.el7 04/01/2014
[ 9112.921570] task: ffff93536b2d3d00 task.stack: ffffab61d285c000
[ 9112.921571] RIP: 0010:[<ffffffffa5129761>]
[ 9112.921576]  [<ffffffffa5129761>] smp_call_function_single+0xd1/0x130
[ 9112.921577] RSP: 0018:ffffab61d285fad0  EFLAGS: 00000202
[ 9112.921577] RAX: 0000000000000000 RBX: 0000000000000005 RCX: 0000000000000830
[ 9112.921578] RDX: 0000000000000001 RSI: 00000000000000fb RDI: 0000000000000830
[ 9112.921578] RBP: ffffab61d285fb18 R08: ffffffffffffffc0 R09: 0000000000000020
[ 9112.921579] R10: 0000000000000002 R11: ffff93537ffde000 R12: ffffffffa5069a60
[ 9112.921579] R13: ffffab61d285fb78 R14: 0000000000000005 R15: ffff93532665fa70
[ 9112.921580] FS:  0000000000000000(0000) GS:ffff93536fe80000(0000) knlGS:0000000000000000
[ 9112.921580] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 9112.921581] CR2: 00007fad8cf7fc30 CR3: 0000000369253000 CR4: 00000000001406e0
[ 9112.921584] Stack:
[ 9112.921585]  ffffab61d285fb78
[ 9112.921586]  ffffffffa522fac1
[ 9112.921586]  0000000000000000
[ 9112.921586]  ffffffffa5069a60
[ 9112.921587]  ffffab61d285fb78
[ 9112.921587]  0000000000000003
[ 9112.921587]  000000005c17609c
[ 9112.921588]  0000000000000002
[ 9112.921588]  ffffffffa5069a60
[ 9112.921588]  ffffab61d285fb60
[ 9112.921589]  ffffffffa5129cb7
[ 9112.921589]  0000000000000002
[ 9112.921589] Call Trace:
[ 9112.921595]  [<ffffffffa522fac1>] ? __slab_free+0xa1/0x2a0
[ 9112.921596]  [<ffffffffa5069a60>] ? do_flush_tlb_all+0x40/0x40
[ 9112.921597]  [<ffffffffa5069a60>] ? do_flush_tlb_all+0x40/0x40
[ 9112.921599]  [<ffffffffa5129cb7>] smp_call_function_many+0x207/0x250
[ 9112.921600]  [<ffffffffa5069fbd>] native_flush_tlb_others+0x12d/0x190
[ 9112.921601]  [<ffffffffa506a134>] flush_tlb_mm_range+0x64/0x150
[ 9112.921603]  [<ffffffffa51f856f>] tlb_flush_mmu_tlbonly+0x2f/0xc0
[ 9112.921604]  [<ffffffffa51f96b4>] tlb_finish_mmu+0x14/0x50
[ 9112.921605]  [<ffffffffa52050b6>] exit_mmap+0xd6/0x170
[ 9112.921608]  [<ffffffffa509e956>] mmput+0x66/0x110
[ 9112.921610]  [<ffffffffa5260882>] flush_old_exec+0x592/0x6c0
[ 9112.921612]  [<ffffffffa52b8e3f>] load_elf_binary+0x3af/0x1670
[ 9112.921614]  [<ffffffffa5379bc0>] ? selinux_inode_permission+0xe0/0x1a0
[ 9112.921615]  [<ffffffffa52600c4>] search_binary_handler+0xa4/0x1c0
[ 9112.921616]  [<ffffffffa52616fa>] do_execveat_common.isra.37+0x58a/0x770
[ 9112.921618]  [<ffffffffa5261b2a>] SyS_execve+0x3a/0x50
[ 9112.921620]  [<ffffffffa5003c17>] do_syscall_64+0x67/0x180
[ 9112.921622]  [<ffffffffa581bcab>] entry_SYSCALL64_slow_path+0x25/0x25
[ 9112.921623] Code:
[ 9112.921623] 25
[ 9112.921623] 28
[ 9112.921624] 00
[ 9112.921624] 00
[ 9112.921624] 00
[ 9112.921624] 75
[ 9112.921625] 70
[ 9112.921625] 48
[ 9112.921625] 83
[ 9112.921625] c4
[ 9112.921626] 38
[ 9112.921626] 5b
[ 9112.921626] 41
[ 9112.921626] 5c
[ 9112.921626] 5d
[ 9112.921627] c3
[ 9112.921627] 48
[ 9112.921627] 8d
[ 9112.921627] 75
[ 9112.921628] c8
[ 9112.921628] 48
[ 9112.921628] 89
[ 9112.921628] d1
[ 9112.921629] 89
[ 9112.921629] df
[ 9112.921629] 4c
[ 9112.921629] 89
[ 9112.921630] e2
[ 9112.921630] e8
[ 9112.921630] 19
[ 9112.921630] fe
[ 9112.921631] ff
[ 9112.921631] ff
[ 9112.921631] 8b
[ 9112.921631] 55
[ 9112.921632] e0
[ 9112.921632] 83
[ 9112.921632] e2
[ 9112.921632] 01
[ 9112.921633] 74
[ 9112.921633] 0a
[ 9112.921633] f3
[ 9112.921633] 90
[ 9112.921634] <8b>
[ 9112.921634] 55
[ 9112.921634] e0
[ 9112.921634] 83
[ 9112.921635] e2
[ 9112.921635] 01
[ 9112.921635] 75
[ 9112.921635] f6
[ 9112.921636] eb
[ 9112.921636] c3
[ 9112.921636] 8b
[ 9112.921636] 05
[ 9112.921637] 8f
[ 9112.921637] f5
[ 9112.921637] 01
[ 9112.921643] 01
[ 9112.921644] 85
[ 9112.921644] c0
[ 9112.921644] 75
[ 9112.921644] 85
[ 9112.921645] 80
[ 9132.855800]  ffffffffa50d06e3
[ 9132.855976]  ffff93536ff5a400 ffffffffa5e500c0
[ 9132.856276]  ffff93536ff43ea8 ffffffffa510e420 0000000000000000 ffff93536ded8000
[ 9132.856987]  ffff93536ff5a400 ffffffff00000000 0000000000000000 ffff93536ded8000
[ 9132.857685] Call Trace:
[ 9132.857916]  <IRQ>
[ 9132.858101]  [<ffffffffa50d06e3>] sched_show_task+0xd3/0x140
[ 9132.858595]  [<ffffffffa510e420>] rcu_check_callbacks+0x8d0/0x8e0
[ 9132.859123]  [<ffffffffa5124120>] ? tick_sched_do_timer+0x50/0x50
[ 9132.859651]  [<ffffffffa5113cff>] update_process_times+0x2f/0x60
[ 9132.860153]  [<ffffffffa5123a75>] tick_sched_handle.isra.15+0x25/0x60
[ 9132.860685]  [<ffffffffa512415d>] tick_sched_timer+0x3d/0x70
[ 9132.861158]  [<ffffffffa511477e>] __hrtimer_run_queues+0xee/0x260
[ 9132.861661]  [<ffffffffa5114f0a>] hrtimer_interrupt+0x9a/0x180
[ 9132.862193]  [<ffffffffa504bf28>] local_apic_timer_interrupt+0x38/0x60
[ 9132.862733]  [<ffffffffa581e7ad>] smp_apic_timer_interrupt+0x3d/0x50
[ 9132.863261]  [<ffffffffa581d96c>] apic_timer_interrupt+0x8c/0xa0
[ 9132.863780]  <EOI>
[ 9132.863952]  [<ffffffffa581b456>] ? native_safe_halt+0x6/0x10
[ 9132.864463]  [<ffffffffa581b190>] default_idle+0x20/0xd0
[ 9132.864915]  [<ffffffffa50310af>] arch_cpu_idle+0xf/0x20
[ 9132.865356]  [<ffffffffa581b5a3>] default_idle_call+0x23/0x30
[ 9132.865836]  [<ffffffffa50e7b20>] cpu_startup_entry+0x1d0/0x240
[ 9132.866330]  [<ffffffffa504a634>] start_secondary+0x154/0x190
[ 9132.866810] rcu_sched kthread starved for 75833 jiffies! g214169 c214168 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x100
[ 9132.867634] rcu_sched       W    0     7      2 0x00000000
[ 9132.868181]  ffff93532665f700 0000000000000000 ffff93536de80000 ffff93536ff59600
[ 9132.868835]  ffff93536ded8000 ffffab61c18cbd78 ffffffffa58169e5 ffff93536ff50140
[ 9132.869530]  00000000ffffffff ffff93536ff59600 ffffab61c18cbdc0 ffff93536de80000
[ 9132.870186] Call Trace:
[ 9132.870395]  [<ffffffffa58169e5>] ? __schedule+0x225/0x6d0
[ 9132.870849]  [<ffffffffa5816ec6>] schedule+0x36/0x80
[ 9132.871265]  [<ffffffffa581a2bb>] schedule_timeout+0x1db/0x3f0
[ 9132.871745]  [<ffffffffa50cc820>] ? resched_cpu+0x60/0x70
[ 9132.872195]  [<ffffffffa51121b0>] ? del_timer_sync+0x50/0x50
[ 9132.872662]  [<ffffffffa510be89>] rcu_gp_kthread+0x4d9/0x960
[ 9132.873132]  [<ffffffffa510b9b0>] ? _synchronize_rcu_expedited.constprop.63+0x320/0x320
[ 9132.873884]  [<ffffffffa50c2569>] kthread+0xd9/0xf0
[ 9132.874329]  [<ffffffffa50c2490>] ? kthread_park+0x60/0x60
[ 9132.874801]  [<ffffffffa581be55>] ret_from_fork+0x25/0x30
[ 9161.986175] systemd-coredump[13051]: Failed to get COMM, falling back to the command line: No such process
[ 9168.514150] systemd-coredump[13051]: Failed to get EXE, ignoring: No such process
[ 9172.337612] systemd-journald[13053]: File /var/log/journal/f764c03834274b3a983b4685df7b9918/system.journal corrupted or uncleanly shut down, renaming and replacing.
[ 9485.070229] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 9490.363293]  (detected by 0, t=65297 jiffies, g=216186, c=216185, q=0)
[ 9490.363921] All QSes seen, last rcu_sched kthread activity 5297 (4304156793-4304151496), jiffies_till_next_fqs=3, root ->qsmask 0x0
[ 9490.365096] swapper/0       R  running task        0     0      0 0x00000008
[ 9490.365809]  ffff93536fe03e40 ffffffffa50d06e3 ffff93536fe1a400 ffffffffa5e500c0
[ 9490.366552]  ffff93536fe03ea8 ffffffffa510e420 0000000000000000 ffffffffa5e0e500
[ 9490.367236]  ffff93536fe1a400 ffffffff00000000 0000000000000000 ffffffffa5e0e500
[ 9490.367942] Call Trace:
[ 9490.368207]  <IRQ>
[ 9490.368412]  [<ffffffffa50d06e3>] sched_show_task+0xd3/0x140
[ 9490.368949]  [<ffffffffa510e420>] rcu_check_callbacks+0x8d0/0x8e0
[ 9490.369480]  [<ffffffffa5124120>] ? tick_sched_do_timer+0x50/0x50
[ 9490.370003]  [<ffffffffa5113cff>] update_process_times+0x2f/0x60
[ 9490.370561]  [<ffffffffa5123a75>] tick_sched_handle.isra.15+0x25/0x60
[ 9490.371145]  [<ffffffffa512415d>] tick_sched_timer+0x3d/0x70
[ 9490.371633]  [<ffffffffa511477e>] __hrtimer_run_queues+0xee/0x260
[ 9490.372225]  [<ffffffffa5114f0a>] hrtimer_interrupt+0x9a/0x180
[ 9490.372714]  [<ffffffffa504bf28>] local_apic_timer_interrupt+0x38/0x60
[ 9490.373264]  [<ffffffffa581e7ad>] smp_apic_timer_interrupt+0x3d/0x50
[ 9490.373792]  [<ffffffffa581d96c>] apic_timer_interrupt+0x8c/0xa0
[ 9490.374343]  <EOI>
[ 9490.374537]  [<ffffffffa581b456>] ? native_safe_halt+0x6/0x10
[ 9490.375049]  [<ffffffffa581b190>] default_idle+0x20/0xd0
[ 9490.375520]  [<ffffffffa50310af>] arch_cpu_idle+0xf/0x20
[ 9490.375962]  [<ffffffffa581b5a3>] default_idle_call+0x23/0x30
[ 9490.376447]  [<ffffffffa50e7b20>] cpu_startup_entry+0x1d0/0x240
[ 9490.376943]  [<ffffffffa580e2d7>] rest_init+0x77/0x80
[ 9490.377432]  [<ffffffffa5f81feb>] start_kernel+0x45f/0x480
[ 9490.377890]  [<ffffffffa5f81120>] ? early_idt_handler_array+0x120/0x120
[ 9490.378501]  [<ffffffffa5f812ca>] x86_64_start_reservations+0x24/0x26
[ 9490.379036]  [<ffffffffa5f81419>] x86_64_start_kernel+0x14d/0x170
[ 9490.379569] rcu_sched kthread starved for 5299 jiffies! g216186 c216185 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x100
[ 9490.380384] rcu_sched       W    0     7      2 0x00000000
[ 9490.380852]  ffff93536abe6600 0000000000000000 ffff93536de80000 ffff93536fe19600
[ 9490.381511]  ffffffffa5e0e500 ffffab61c18cbd78 ffffffffa58169e5 ffff93536fe10140
[ 9490.382196]  00000000ffffffff ffff93536fe19600 ffffab61c18cbdc0 ffff93536de80000
[ 9490.382903] Call Trace:
[ 9490.383116]  [<ffffffffa58169e5>] ? __schedule+0x225/0x6d0
[ 9490.383598]  [<ffffffffa5816ec6>] schedule+0x36/0x80
[ 9490.384013]  [<ffffffffa581a2bb>] schedule_timeout+0x1db/0x3f0
[ 9490.384526]  [<ffffffffa50cc820>] ? resched_cpu+0x60/0x70
[ 9490.384976]  [<ffffffffa51121b0>] ? del_timer_sync+0x50/0x50
[ 9490.385476]  [<ffffffffa510be89>] rcu_gp_kthread+0x4d9/0x960
[ 9490.385946]  [<ffffffffa510b9b0>] ? _synchronize_rcu_expedited.constprop.63+0x320/0x320
[ 9490.386638]  [<ffffffffa50c2569>] kthread+0xd9/0xf0
[ 9490.387045]  [<ffffffffa50c2490>] ? kthread_park+0x60/0x60
[ 9490.387555]  [<ffffffffa581be55>] ret_from_fork+0x25/0x30
[ 9550.392509] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 9610.574000]  (detected by 0, t=120185 jiffies, g=216187, c=216186, q=0)
[ 9610.574590] All QSes seen, last rcu_sched kthread activity 60185 (4304277003-4304216818), jiffies_till_next_fqs=3, root ->qsmask 0x0
[ 9610.575685] swapper/0       R  running task        0     0      0 0x00000008
[ 9610.576369]  ffff93536fe03e40 ffffffffa50d06e3 ffff93536fe1a400 ffffffffa5e500c0
[ 9610.577109]  ffff93536fe03ea8 ffffffffa510e420 0000000000000000 ffffffffa5e0e500
[ 9610.577798]  ffff93536fe1a400 ffffffff00000000 0000000000000000 ffffffffa5e0e500
[ 9610.578484] Call Trace:
[ 9610.578703]  <IRQ>
[ 9610.578907]  [<ffffffffa50d06e3>] sched_show_task+0xd3/0x140
[ 9610.579407]  [<ffffffffa510e420>] rcu_check_callbacks+0x8d0/0x8e0
[ 9610.579937]  [<ffffffffa5124120>] ? tick_sched_do_timer+0x50/0x50
[ 9610.580479]  [<ffffffffa5113cff>] update_process_times+0x2f/0x60
[ 9610.581010]  [<ffffffffa5123a75>] tick_sched_handle.isra.15+0x25/0x60
[ 9610.581568]  [<ffffffffa512415d>] tick_sched_timer+0x3d/0x70
[ 9610.582055]  [<ffffffffa511477e>] __hrtimer_run_queues+0xee/0x260
[ 9610.582591]  [<ffffffffa5114f0a>] hrtimer_interrupt+0x9a/0x180
[ 9610.583077]  [<ffffffffa504bf28>] local_apic_timer_interrupt+0x38/0x60
[ 9610.583636]  [<ffffffffa581e7ad>] smp_apic_timer_interrupt+0x3d/0x50
[ 9610.584179]  [<ffffffffa581d96c>] apic_timer_interrupt+0x8c/0xa0
[ 9610.584743]  <EOI>
[ 9610.584918]  [<ffffffffa581b456>] ? native_safe_halt+0x6/0x10
[ 9610.585432]  [<ffffffffa581b190>] default_idle+0x20/0xd0
[ 9610.585878]  [<ffffffffa50310af>] arch_cpu_idle+0xf/0x20
[ 9610.586321]  [<ffffffffa581b5a3>] default_idle_call+0x23/0x30
[ 9610.586803]  [<ffffffffa50e7b20>] cpu_startup_entry+0x1d0/0x240
[ 9610.587298]  [<ffffffffa580e2d7>] rest_init+0x77/0x80
[ 9610.587762]  [<ffffffffa5f81feb>] start_kernel+0x45f/0x480
[ 9610.588224]  [<ffffffffa5f81120>] ? early_idt_handler_array+0x120/0x120
[ 9610.588803]  [<ffffffffa5f812ca>] x86_64_start_reservations+0x24/0x26
[ 9610.589337]  [<ffffffffa5f81419>] x86_64_start_kernel+0x14d/0x170
[ 9610.589847] rcu_sched kthread starved for 60187 jiffies! g216187 c216186 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x100
[ 9610.590672] rcu_sched       W    0     7      2 0x00000000
[ 9610.591141]  ffff93536abe6600 0000000000000000 ffff93536de80000 ffff93536fe19600
[ 9610.591802]  ffffffffa5e0e500 ffffab61c18cbd78 ffffffffa58169e5 ffff93536fe10140
[ 9610.592486]  00000000ffffffff ffff93536fe19600 ffffab61c18cbdc0 ffff93536de80000
[ 9610.593169] Call Trace:
[ 9610.593380]  [<ffffffffa58169e5>] ? __schedule+0x225/0x6d0
[ 9610.593893]  [<ffffffffa5816ec6>] schedule+0x36/0x80
[ 9610.594306]  [<ffffffffa581a2bb>] schedule_timeout+0x1db/0x3f0
[ 9610.594869]  [<ffffffffa50cc820>] ? resched_cpu+0x60/0x70
[ 9610.595341]  [<ffffffffa51121b0>] ? del_timer_sync+0x50/0x50
[ 9610.595839]  [<ffffffffa510be89>] rcu_gp_kthread+0x4d9/0x960
[ 9610.596309]  [<ffffffffa510b9b0>] ? _synchronize_rcu_expedited.constprop.63+0x320/0x320
[ 9610.597000]  [<ffffffffa50c2569>] kthread+0xd9/0xf0
[ 9610.597409]  [<ffffffffa50c2490>] ? kthread_park+0x60/0x60
[ 9610.597871]  [<ffffffffa581be55>] ret_from_fork+0x25/0x30
[ 9742.852110] systemd-coredump[13184]: Detected coredump of the journal daemon or PID 1, diverted to /var/lib/systemd/coredump/core.systemd-journal.0.24b546b74619498e89c95b7296671db0.13053.1484421146000000.xz.
[ 9767.527443] systemd-journald[13186]: File /var/log/journal/f764c03834274b3a983b4685df7b9918/system.journal corrupted or uncleanly shut down, renaming and replacing.

Comment 2 Kevin Fenzi 2017-01-14 20:45:12 UTC
[    0.000000] setup_percpu: NR_CPUS:1024 nr_cpumask_bits:30 nr_cpu_ids:30 nr_node_ids:1
[    0.000000] percpu: Embedded 36 pages/cpu @ffff88552fe00000 s108888 r8192 d30376 u262144
[    0.000000] pcpu-alloc: s108888 r8192 d30376 u262144 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 00 01 02 03 04 05 06 07 [0] 08 09 10 11 12 13 14 15 
[    0.000000] pcpu-alloc: [0] 16 17 18 19 20 21 22 23 [0] 24 25 26 27 28 29 -- -- 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 3efe0d8c0
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 3870562
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.9.3-200.fc25.x86_64 root=UUID=a863faa4-2170-4e61-8491-80793acd4688 ro net.ifnames=0 console=ttyS0 LANG=en_US.UTF-8
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Memory: 15381200K/15728092K available (8323K kernel code, 1426K rwdata, 3392K rodata, 1688K init, 1464K bss, 346892K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=30, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] 	Build-time adjustment of leaf fanout to 64.
[    0.000000] 	RCU restricting CPUs from NR_CPUS=1024 to nr_cpu_ids=30.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=30
[    0.000000] NR_IRQS:65792 nr_irqs:664 16
[    0.000000] 	Offload RCU callbacks from all CPUs
[    0.000000] 	Offload RCU callbacks from CPUs: 0-29.
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [ttyS0] enabled
[    0.000000] tsc: Detected 2299.998 MHz processor
[    0.429661] Calibrating delay loop (skipped) preset value.. 4599.99 BogoMIPS (lpj=2299998)
[    0.430447] pid_max: default: 32768 minimum: 301
[    0.430876] ACPI: Core revision 20160831
[    0.432631] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.433350] Security Framework initialized
[    0.433809] Yama: becoming mindful.
[    0.434196] SELinux:  Initializing.
[    0.434522] SELinux:  Starting in permissive mode
[    0.435394] Dentry cache hash table entries: 2097152 (order: 12, 16777216 bytes)
[    0.439825] Inode-cache hash table entries: 1048576 (order: 11, 8388608 bytes)
[    0.442647] Mount-cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.443374] Mountpoint-cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.445051] mce: CPU supports 10 MCE banks
[    0.445542] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.446052] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.448074] Freeing SMP alternatives memory: 32K (ffffffff8510c000 - ffffffff85114000)
[    0.451741] ftrace: allocating 31090 entries in 122 pages
[    0.481637] smpboot: Max logical packages: 30
[    0.482201] x2apic enabled
[    0.482666] Switched APIC routing to physical x2apic.
[    0.484039] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.484876] TSC deadline timer enabled
[    0.484880] smpboot: CPU0: Intel Core Processor (Haswell, no TSX) (family: 0x6, model: 0x3c, stepping: 0x1)
[    0.486106] Performance Events: unsupported p6 CPU model 60 no PMU driver, software events only.
[    0.489106] x86: Booting SMP configuration:
[    0.489658] .... node  #0, CPUs:        #1
[    0.490281] kvm-clock: cpu 1, msr 3:fffd2041, secondary cpu clock
[    0.511258] KVM setup async PF for cpu 1
[    0.511731] kvm-stealtime: cpu 1, msr 3efe4d8c0
[    0.512593]   #2
[    0.513098] kvm-clock: cpu 2, msr 3:fffd2081, secondary cpu clock
[    0.533889] KVM setup async PF for cpu 2
[    0.534393] kvm-stealtime: cpu 2, msr 3efe8d8c0
[    0.535439]   #3
[    0.535798] kvm-clock: cpu 3, msr 3:fffd20c1, secondary cpu clock
[    0.556627] KVM setup async PF for cpu 3
[    0.557145] kvm-stealtime: cpu 3, msr 3efecd8c0
[    0.558273]   #4
[    0.558781] kvm-clock: cpu 4, msr 3:fffd2101, secondary cpu clock
[    0.579539] KVM setup async PF for cpu 4
[    0.584476] kvm-stealtime: cpu 4, msr 3eff0d8c0
[    0.585580]   #5
[    0.586035] kvm-clock: cpu 5, msr 3:fffd2141, secondary cpu clock
[    0.606808] KVM setup async PF for cpu 5
[    0.607237] kvm-stealtime: cpu 5, msr 3eff4d8c0
[    0.607741] x86: Booted up 1 node, 6 CPUs
[    0.608204] smpboot: Total of 6 processors activated (27599.97 BogoMIPS)
[    0.610906] devtmpfs: initialized
[    0.611450] x86/mm: Memory block size: 128MB
[    0.616713] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.617793] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[    0.618446] pinctrl core: initialized pinctrl subsystem
[    0.619102] RTC time: 16:30:48, date: 01/14/17
[    0.619875] NET: Registered protocol family 16
[    0.623370] cpuidle: using governor menu
[    0.624086] ACPI: bus type PCI registered
[    0.624546] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.625277] PCI: Using configuration type 1 for base access
[    0.630109] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.631578] ACPI: Added _OSI(Module Device)
[    0.632133] ACPI: Added _OSI(Processor Device)
[    0.632677] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.633292] ACPI: Added _OSI(Processor Aggregator Device)
[    0.634993] ACPI: Interpreter enabled
[    0.635353] ACPI: (supports S0 S5)
[    0.635699] ACPI: Using IOAPIC for interrupt routing
[    0.636169] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.641020] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.641578] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[    0.642368] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.642983] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.644377] acpiphp: Slot [3] registered
[    0.644801] acpiphp: Slot [4] registered
[    0.645175] acpiphp: Slot [5] registered
[    0.645578] acpiphp: Slot [6] registered
[    0.645992] acpiphp: Slot [8] registered
[    0.646429] acpiphp: Slot [9] registered
[    0.646788] acpiphp: Slot [10] registered
[    0.647203] acpiphp: Slot [11] registered
[    0.647578] acpiphp: Slot [12] registered
[    0.647972] acpiphp: Slot [13] registered
[    0.648388] acpiphp: Slot [14] registered
[    0.648792] acpiphp: Slot [15] registered
[    0.649176] acpiphp: Slot [16] registered
[    0.649563] acpiphp: Slot [17] registered
[    0.649943] acpiphp: Slot [18] registered
[    0.650322] acpiphp: Slot [19] registered
[    0.650724] acpiphp: Slot [20] registered
[    0.651130] acpiphp: Slot [21] registered
[    0.651508] acpiphp: Slot [22] registered
[    0.651941] acpiphp: Slot [23] registered
[    0.652388] acpiphp: Slot [24] registered
[    0.652770] acpiphp: Slot [25] registered
[    0.653144] acpiphp: Slot [26] registered
[    0.653546] acpiphp: Slot [27] registered
[    0.653942] acpiphp: Slot [28] registered
[    0.654304] acpiphp: Slot [29] registered
[    0.654700] acpiphp: Slot [30] registered
[    0.655115] acpiphp: Slot [31] registered
[    0.655526] PCI host bridge to bus 0000:00
[    0.655890] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.656511] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.657118] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.657807] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]
[    0.658447] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.658986] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.659324] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.659796] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.675399] pci 0000:00:01.1: reg 0x20: [io  0xc140-0xc14f]
[    0.680470] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.681106] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.681666] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.682311] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.683162] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    0.684811] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.685529] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.686426] pci 0000:00:02.0: [1b36:0100] type 00 class 0x030000
[    0.715331] pci 0000:00:02.0: reg 0x10: [mem 0xf4000000-0xf7ffffff]
[    0.728162] pci 0000:00:02.0: reg 0x14: [mem 0xf8000000-0xfbffffff]
[    0.740443] pci 0000:00:02.0: reg 0x18: [mem 0xfc094000-0xfc095fff]
[    0.755337] pci 0000:00:02.0: reg 0x1c: [io  0xc040-0xc05f]
[    0.793733] pci 0000:00:02.0: reg 0x30: [mem 0xfc080000-0xfc08ffff pref]
[    0.794335] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000
[    0.802149] pci 0000:00:03.0: reg 0x10: [io  0xc060-0xc07f]
[    0.812207] pci 0000:00:03.0: reg 0x14: [mem 0xfc096000-0xfc096fff]
[    0.839834] pci 0000:00:03.0: reg 0x20: [mem 0xfebec000-0xfebeffff 64bit pref]
[    0.849086] pci 0000:00:03.0: reg 0x30: [mem 0xfc000000-0xfc03ffff pref]
[    0.849612] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000
[    0.857246] pci 0000:00:04.0: reg 0x10: [io  0xc080-0xc09f]
[    0.865128] pci 0000:00:04.0: reg 0x14: [mem 0xfc097000-0xfc097fff]
[    0.889134] pci 0000:00:04.0: reg 0x20: [mem 0xfebf0000-0xfebf3fff 64bit pref]
[    0.897542] pci 0000:00:04.0: reg 0x30: [mem 0xfc040000-0xfc07ffff pref]
[    0.898076] pci 0000:00:05.0: [8086:2668] type 00 class 0x040300
[    0.900776] pci 0000:00:05.0: reg 0x10: [mem 0xfc090000-0xfc093fff]
[    0.919900] pci 0000:00:06.0: [1af4:1003] type 00 class 0x078000
[    0.927198] pci 0000:00:06.0: reg 0x10: [io  0xc0a0-0xc0bf]
[    0.937360] pci 0000:00:06.0: reg 0x14: [mem 0xfc098000-0xfc098fff]
[    0.962942] pci 0000:00:06.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref]
[    0.973342] pci 0000:00:07.0: [8086:2934] type 00 class 0x0c0300
[    0.985346] pci 0000:00:07.0: reg 0x20: [io  0xc0c0-0xc0df]
[    0.989378] pci 0000:00:07.1: [8086:2935] type 00 class 0x0c0300
[    1.005586] pci 0000:00:07.1: reg 0x20: [io  0xc0e0-0xc0ff]
[    1.010830] pci 0000:00:07.2: [8086:2936] type 00 class 0x0c0300
[    1.023496] pci 0000:00:07.2: reg 0x20: [io  0xc100-0xc11f]
[    1.027833] pci 0000:00:07.7: [8086:293a] type 00 class 0x0c0320
[    1.030035] pci 0000:00:07.7: reg 0x10: [mem 0xfc099000-0xfc099fff]
[    1.044250] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000
[    1.052457] pci 0000:00:08.0: reg 0x10: [io  0xc000-0xc03f]
[    1.059135] pci 0000:00:08.0: reg 0x14: [mem 0xfc09a000-0xfc09afff]
[    1.094182] pci 0000:00:08.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref]
[    1.107948] pci 0000:00:09.0: [1af4:1002] type 00 class 0x00ff00
[    1.112439] pci 0000:00:09.0: reg 0x10: [io  0xc120-0xc13f]
[    1.133092] pci 0000:00:09.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref]
[    1.137669] pci 0000:00:0a.0: [8086:25ab] type 00 class 0x088000
[    1.139975] pci 0000:00:0a.0: reg 0x10: [mem 0xfc09b000-0xfc09b00f]
[    1.158098] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    1.158811] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    1.159468] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    1.160236] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    1.160963] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    1.162468] ACPI: Enabled 3 GPEs in block 00 to 0F
[    1.163880] vgaarb: setting as boot device: PCI:0000:00:02.0
[    1.164384] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    1.165167] vgaarb: loaded
[    1.165435] vgaarb: bridge control possible 0000:00:02.0
[    1.166145] SCSI subsystem initialized
[    1.166736] libata version 3.00 loaded.
[    1.166787] ACPI: bus type USB registered
[    1.167194] usbcore: registered new interface driver usbfs
[    1.167739] usbcore: registered new interface driver hub
[    1.168253] usbcore: registered new device driver usb
[    1.169120] PCI: Using ACPI for IRQ routing
[    1.169511] PCI: pci_cache_line_size set to 64 bytes
[    1.169713] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    1.169717] e820: reserve RAM buffer [mem 0xbffd9000-0xbfffffff]
[    1.170018] NetLabel: Initializing
[    1.170340] NetLabel:  domain hash size = 128
[    1.170743] NetLabel:  protocols = UNLABELED CIPSOv4
[    1.171205] NetLabel:  unlabeled traffic allowed by default
[    1.172011] clocksource: Switched to clocksource kvm-clock
[    1.185601] VFS: Disk quotas dquot_6.6.0
[    1.186096] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.186986] pnp: PnP ACPI init
[    1.187331] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    1.187377] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    1.187397] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    1.187404] pnp 00:03: [dma 2]
[    1.187424] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    1.187523] pnp 00:04: Plug and Play ACPI device, IDs PNP0501 (active)
[    1.187690] pnp: PnP ACPI: found 5 devices
[    1.195018] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    1.195799] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    1.195801] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    1.195801] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    1.195802] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window]
[    1.195883] NET: Registered protocol family 2
[    1.196956] TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
[    1.199011] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[    1.201031] TCP: Hash tables configured (established 131072 bind 65536)
[    1.201853] UDP hash table entries: 8192 (order: 6, 262144 bytes)
[    1.202589] UDP-Lite hash table entries: 8192 (order: 6, 262144 bytes)
[    1.203803] NET: Registered protocol family 1
[    1.204276] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    1.204839] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    1.205391] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    1.205972] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    1.231962] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[    1.280145] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10
[    1.323477] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
[    1.372655] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11
[    1.394150] PCI: CLS 0 bytes, default 64
[    1.394281] Unpacking initramfs...
[    1.605975] Freeing initrd memory: 11524K (ffff88517696f000 - ffff8851774b0000)
[    1.609933] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    1.610496] software IO TLB [mem 0xbbfd9000-0xbffd9000] (64MB) mapped at [ffff8851fbfd9000-ffff8851fffd8fff]
[    1.614210] futex hash table entries: 8192 (order: 7, 524288 bytes)
[    1.615135] audit: initializing netlink subsys (disabled)
[    1.615693] audit: type=2000 audit(1484411449.413:1): initialized
[    1.616618] Initialise system trusted keyrings
[    1.617584] workingset: timestamp_bits=37 max_order=22 bucket_order=0
[    1.620127] zbud: loaded
[    1.621197] SELinux:  Registering netfilter hooks
[    1.669798] NET: Registered protocol family 38
[    1.670401] Key type asymmetric registered
[    1.670786] Asymmetric key parser 'x509' registered
[    1.671396] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    1.672257] io scheduler noop registered
[    1.672616] io scheduler deadline registered
[    1.673050] io scheduler cfq registered (default)
[    1.673681] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    1.674309] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    1.674964] intel_idle: does not run on family 6 model 60
[    1.675105] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[    1.675757] ACPI: Power Button [PWRF]
[    1.676515] GHES: HEST is not enabled!
[    1.677382] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
[    1.704989] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    1.711417] Non-volatile memory driver v1.3
[    1.711897] Linux agpgart interface v0.103
[    1.713243] ata_piix 0000:00:01.1: version 2.13
[    1.715019] scsi host0: ata_piix
[    1.715667] scsi host1: ata_piix
[    1.716105] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc140 irq 14
[    1.716799] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc148 irq 15
[    1.717485] libphy: Fixed MDIO Bus: probed
[    1.718134] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.718790] ehci-pci: EHCI PCI platform driver
[    1.740515] ehci-pci 0000:00:07.7: EHCI Host Controller
[    1.741138] ehci-pci 0000:00:07.7: new USB bus registered, assigned bus number 1
[    1.742015] ehci-pci 0000:00:07.7: irq 11, io mem 0xfc099000
[    1.749029] ehci-pci 0000:00:07.7: USB 2.0 started, EHCI 1.00
[    1.749621] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.750465] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.751201] usb usb1: Product: EHCI Host Controller
[    1.751698] usb usb1: Manufacturer: Linux 4.9.3-200.fc25.x86_64 ehci_hcd
[    1.752380] usb usb1: SerialNumber: 0000:00:07.7
[    1.753061] hub 1-0:1.0: USB hub found
[    1.753430] hub 1-0:1.0: 6 ports detected
[    1.754020] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    1.754653] ohci-pci: OHCI PCI platform driver
[    1.755134] uhci_hcd: USB Universal Host Controller Interface driver
[    1.777126] uhci_hcd 0000:00:07.0: UHCI Host Controller
[    1.777802] uhci_hcd 0000:00:07.0: new USB bus registered, assigned bus number 2
[    1.778528] uhci_hcd 0000:00:07.0: detected 2 ports
[    1.779041] uhci_hcd 0000:00:07.0: irq 11, io base 0x0000c0c0
[    1.779642] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001
[    1.780299] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.780989] usb usb2: Product: UHCI Host Controller
[    1.781442] usb usb2: Manufacturer: Linux 4.9.3-200.fc25.x86_64 uhci_hcd
[    1.782079] usb usb2: SerialNumber: 0000:00:07.0
[    1.782691] hub 2-0:1.0: USB hub found
[    1.783098] hub 2-0:1.0: 2 ports detected
[    1.804429] uhci_hcd 0000:00:07.1: UHCI Host Controller
[    1.805059] uhci_hcd 0000:00:07.1: new USB bus registered, assigned bus number 3
[    1.805789] uhci_hcd 0000:00:07.1: detected 2 ports
[    1.806337] uhci_hcd 0000:00:07.1: irq 10, io base 0x0000c0e0
[    1.806973] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001
[    1.807628] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.808318] usb usb3: Product: UHCI Host Controller
[    1.808783] usb usb3: Manufacturer: Linux 4.9.3-200.fc25.x86_64 uhci_hcd
[    1.809422] usb usb3: SerialNumber: 0000:00:07.1
[    1.810055] hub 3-0:1.0: USB hub found
[    1.810423] hub 3-0:1.0: 2 ports detected
[    1.831422] uhci_hcd 0000:00:07.2: UHCI Host Controller
[    1.832045] uhci_hcd 0000:00:07.2: new USB bus registered, assigned bus number 4
[    1.832762] uhci_hcd 0000:00:07.2: detected 2 ports
[    1.833267] uhci_hcd 0000:00:07.2: irq 10, io base 0x0000c100
[    1.833880] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001
[    1.834527] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.835220] usb usb4: Product: UHCI Host Controller
[    1.835683] usb usb4: Manufacturer: Linux 4.9.3-200.fc25.x86_64 uhci_hcd
[    1.836319] usb usb4: SerialNumber: 0000:00:07.2
[    1.836980] hub 4-0:1.0: USB hub found
[    1.837355] hub 4-0:1.0: 2 ports detected
[    1.837920] usbcore: registered new interface driver usbserial
[    1.838493] usbcore: registered new interface driver usbserial_generic
[    1.839144] usbserial: USB Serial support registered for generic
[    1.839739] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    1.841408] serio: i8042 KBD port at 0x60,0x64 irq 1
[    1.841883] serio: i8042 AUX port at 0x60,0x64 irq 12
[    1.842547] mousedev: PS/2 mouse device common for all mice
[    1.843328] rtc_cmos 00:00: RTC can wake from S4
[    1.844181] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[    1.845101] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[    1.845827] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram
[    1.846685] device-mapper: uevent: version 1.0.3
[    1.847488] device-mapper: ioctl: 4.35.0-ioctl (2016-06-23) initialised: dm-devel
[    1.848887] hidraw: raw HID events driver (C) Jiri Kosina
[    1.849639] usbcore: registered new interface driver usbhid
[    1.850172] usbhid: USB HID core driver
[    1.850850] drop_monitor: Initializing network drop monitor service
[    1.851872] ip_tables: (C) 2000-2006 Netfilter Core Team
[    1.852523] Initializing XFRM netlink socket
[    1.853120] NET: Registered protocol family 10
[    1.854127] mip6: Mobile IPv6
[    1.854477] NET: Registered protocol family 17
[    1.855330] microcode: sig=0x306c1, pf=0x1, revision=0x1
[    1.856287] microcode: Microcode Update Driver: v2.01 <tigran.co.uk>, Peter Oruba
[    1.857170] AVX2 version of gcm_enc/dec engaged.
[    1.857678] AES CTR mode by8 optimization enabled
[    1.863648] registered taskstats version 1
[    1.864139] Loading compiled-in X.509 certificates
[    1.866606] alg: No test for pkcs1pad(rsa,sha256) (pkcs1pad(rsa-generic,sha256))
[    1.868254] Loaded X.509 cert 'Fedora kernel signing key: 9657699d35991137bb18496272486651af5239ba'
[    1.869194] zswap: loaded using pool lzo/zbud
[    1.881002] Key type big_key registered
[    1.883639] Key type encrypted registered
[    1.884539]   Magic number: 1:196:540
[    1.884991]  node: hash matches
[    1.885549] rtc_cmos 00:00: setting system clock to 2017-01-14 16:30:49 UTC (1484411449)
[    1.886444] PM: Hibernation image not present or could not be loaded.
[    1.888395] Freeing unused kernel memory: 1688K (ffffffff84f66000 - ffffffff8510c000)
[    1.889253] Write protecting the kernel read-only data: 14336k
[    1.891261] Freeing unused kernel memory: 1900K (ffff885170825000 - ffff885170a00000)
[    1.893777] Freeing unused kernel memory: 704K (ffff885170d50000 - ffff885170e00000)
[    1.900449] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    1.906394] random: systemd: uninitialized urandom read (16 bytes read)
[    1.907347] random: systemd: uninitialized urandom read (16 bytes read)
[    1.908036] random: systemd: uninitialized urandom read (16 bytes read)
[    1.912125] systemd[1]: systemd 231 running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[    1.914076] systemd[1]: Detected virtualization kvm.
[    1.914656] systemd[1]: Detected architecture x86-64.
[    1.915187] systemd[1]: Running in initial RAM disk.
[    1.916320] systemd[1]: Set hostname to <buildvm-01.phx2.fedoraproject.org>.
[    1.959208] random: systemd: uninitialized urandom read (16 bytes read)
[    1.959881] random: systemd: uninitialized urandom read (16 bytes read)
[    1.960533] random: systemd: uninitialized urandom read (16 bytes read)
[    1.961217] random: systemd: uninitialized urandom read (16 bytes read)
[    1.962470] random: systemd: uninitialized urandom read (16 bytes read)
[    1.963351] random: systemd: uninitialized urandom read (16 bytes read)
[    1.964223] random: systemd: uninitialized urandom read (16 bytes read)
[    1.968084] systemd[1]: Reached target Local File Systems.
[    1.969309] systemd[1]: Reached target Timers.
[    1.970289] systemd[1]: Listening on udev Control Socket.
[    1.971416] systemd[1]: Listening on Journal Socket (/dev/log).
[    1.972512] systemd[1]: Listening on udev Kernel Socket.
[    2.022297] audit: type=1130 audit(1484411449.636:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    2.024998] audit: type=1130 audit(1484411449.638:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    2.028617] audit: type=1130 audit(1484411449.642:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    2.035423] audit: type=1130 audit(1484411449.649:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    2.067088] usb 1-1: new high-speed USB device number 2 using ehci-pci
[    2.073167] audit: type=1130 audit(1484411449.687:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    2.083857] audit: type=1130 audit(1484411449.697:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    2.103736] audit: type=1130 audit(1484411449.717:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=plymouth-start comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    2.130023] [drm] Initialized
[    2.190432] usb 1-1: New USB device found, idVendor=0627, idProduct=0001
[    2.191125] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=5
[    2.193663] usb 1-1: Product: QEMU USB Tablet
[    2.194091] usb 1-1: Manufacturer: QEMU
[    2.194463] usb 1-1: SerialNumber: 42
[    2.199663] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:07.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input4
[    2.200960] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Pointer [QEMU QEMU USB Tablet] on usb-0000:00:07.7-1/input0
[    2.244019]  vda: vda1 vda2
[    2.258696] [drm] Device Version 0.0
[    2.259141] [drm] Compression level 0 log level 0
[    2.259645] [drm] Currently using mode #0, list at 0x488
[    2.260210] [drm] 12286 io pages at offset 0x1000000
[    2.260843] [drm] 16777216 byte draw area at offset 0x0
[    2.261335] [drm] RAM header offset: 0x3ffe000
[    2.261852] [drm] rom modes offset 0x488 for 128 modes
[    2.262532] [TTM] Zone  kernel: Available graphics memory: 7698524 kiB
[    2.263164] [TTM] Zone   dma32: Available graphics memory: 2097152 kiB
[    2.263817] [TTM] Initializing pool allocator
[    2.264234] [TTM] Initializing DMA pool allocator
[    2.264690] [drm] qxl: 16M of VRAM memory size
[    2.265123] [drm] qxl: 63M of IO pages memory ready (VRAM domain)
[    2.265687] [drm] qxl: 64M of Surface memory size
[    2.268496] [drm] main mem slot 1 [f4000000,3ffe000]
[    2.269226] [drm] surface mem slot 2 [f8000000,4000000]
[    2.269722] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    2.270345] [drm] No driver support for vblank timestamp query.
[    2.271306] [drm] fb mappable at 0xF4000000, size 3145728
[    2.271874] [drm] fb: depth 24, pitch 4096, width 1024, height 768
[    2.272566] fbcon: qxldrmfb (fb0) is primary device
[    2.293911] Console: switching to colour frame buffer device 128x48
[    2.301673] qxl 0000:00:02.0: fb0: qxldrmfb frame buffer device
[    2.317099] [drm] Initialized qxl 0.1.0 20120117 for 0000:00:02.0 on minor 0
[    2.345152] random: fast init done
[    2.413240] audit: type=1130 audit(1484411450.027:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    2.440061] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null)
[    2.511165] audit: type=1130 audit(1484411450.125:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    2.601257] systemd-journald[202]: Received SIGTERM from PID 1 (systemd).
[    2.626097] tsc: Refined TSC clocksource calibration: 2300.002 MHz
[    2.626741] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x21273747cc1, max_idle_ns: 440795306113 ns
[    2.707133] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[    2.777044] systemd: 15 output lines suppressed due to ratelimiting
[    4.015345] SELinux: 32768 avtab hash slots, 106816 rules.
[    4.033641] SELinux: 32768 avtab hash slots, 106816 rules.
[    4.067935] SELinux:  8 users, 14 roles, 5070 types, 305 bools, 1 sens, 1024 cats
[    4.067938] SELinux:  94 classes, 106816 rules
[    4.071996] SELinux:  Permission validate_trans in class security not defined in policy.
[    4.075675] SELinux:  Permission module_load in class system not defined in policy.
[    4.076408] SELinux: the above unknown classes and permissions will be allowed
[    4.077063] SELinux:  Completing initialization.
[    4.077063] SELinux:  Setting up existing superblocks.
[    4.218590] systemd[1]: Successfully loaded SELinux policy in 335.875ms.
[    4.372814] systemd[1]: Relabelled /dev and /run in 20.210ms.
[    5.960395] EXT4-fs (vda1): re-mounted. Opts: (null)
[    5.981826] systemd-journald[341]: File /run/log/journal/26a321541dd247288885020aefc9054c/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    6.070813] systemd-journald[341]: File /var/log/journal/26a321541dd247288885020aefc9054c/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    6.185406] systemd-journald[341]: Received request to flush runtime journal from PID 1
[    6.239616] RPC: Registered named UNIX socket transport module.
[    6.240247] RPC: Registered udp transport module.
[    6.240668] RPC: Registered tcp transport module.
[    6.241126] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    6.338209] Installing knfsd (copyright (C) 1996 okir.de).
[    6.639256] audit_printk_skb: 132 callbacks suppressed
[    6.639779] audit: type=1130 audit(1484411454.253:51): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    6.814726] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[    6.819335] i6300esb: Intel 6300ESB WatchDog Timer Driver v0.05
[    6.820606] i6300esb: initialized (0xffff96a4818bd000). heartbeat=30 sec (nowayout=0)
[    6.870595] ppdev: user-space parallel port driver
[    7.030898] audit: type=1130 audit(1484411454.644:52): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=lvm2-monitor comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.061230] audit: type=1130 audit(1484411454.675:53): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dracut-shutdown comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.106885] audit: type=1130 audit(1484411454.720:54): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=plymouth-read-write comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.109869] audit: type=1131 audit(1484411454.723:55): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=plymouth-read-write comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.149705] audit: type=1130 audit(1484411454.763:56): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=nfs-config comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.152610] audit: type=1131 audit(1484411454.766:57): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=nfs-config comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.200436] Adding 134216700k swap on /dev/vda2.  Priority:-1 extents:1 across:134216700k FS
[    7.208082] audit: type=1130 audit(1484411454.822:58): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.216049] snd_hda_codec_generic hdaudioC0D0: autoconfig for Generic: line_outs=1 (0x3/0x0/0x0/0x0/0x0) type:line
[    7.217039] snd_hda_codec_generic hdaudioC0D0:    speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
[    7.217814] snd_hda_codec_generic hdaudioC0D0:    hp_outs=0 (0x0/0x0/0x0/0x0/0x0)
[    7.218534] snd_hda_codec_generic hdaudioC0D0:    mono: mono_out=0x0
[    7.219194] snd_hda_codec_generic hdaudioC0D0:    inputs:
[    7.219699] snd_hda_codec_generic hdaudioC0D0:      Line=0x5
[    7.310376] audit: type=1305 audit(1484411454.924:59): audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:auditd_t:s0 res=1
[    7.311789] audit: type=1305 audit(1484411454.925:60): audit_pid=453 old=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:auditd_t:s0 res=1
[    7.683674] ip6_tables: (C) 2000-2006 Netfilter Core Team
[    7.747069] nf_conntrack version 0.5.0 (65536 buckets, 262144 max)
[    9.438209] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    9.442264] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[   14.956395] FS-Cache: Loaded
[   14.993319] FS-Cache: Netfs 'nfs' registered for caching
[   15.005387] Key type dns_resolver registered
[   15.044898] NFS: Registering the id_resolver key type
[   15.045739] Key type id_resolver registered
[   15.046322] Key type id_legacy registered
[   20.930114] Ebtables v2.0 registered
[   34.204225] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[   34.254189] tun: Universal TUN/TAP device driver, 1.6
[   34.254653] tun: (C) 1999-2004 Max Krasnyansky <maxk>
[   34.300533] virbr0: port 1(virbr0-nic) entered blocking state
[   34.301144] virbr0: port 1(virbr0-nic) entered disabled state
[   34.301866] device virbr0-nic entered promiscuous mode
[   34.490394] virbr0: port 1(virbr0-nic) entered blocking state
[   34.518147] virbr0: port 1(virbr0-nic) entered listening state
[   34.634484] virbr0: port 1(virbr0-nic) entered disabled state
[  107.445504] random: crng init done
[ 8084.076179] sched: RT throttling activated
[ 9495.630304] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 9649.333441] 	(detected by 5, t=213710 jiffies, g=213098, c=213097, q=0)
[ 9649.337401] All QSes seen, last rcu_sched kthread activity 153710 (4304315742-4304162032), jiffies_till_next_fqs=3, root ->qsmask 0x0
[ 9649.338632] swapper/5       R  running task        0     0      1 0x00000008
[ 9649.339416]  ffff88552ff43e40 ffffffff840d06e3 ffff88552ff5a400 ffffffff84e500c0
[ 9649.340228]  ffff88552ff43ea8 ffffffff8410e420 0000000000000000 ffff88552dedbd00
[ 9649.340968]  ffff88552ff5a400 ffffffff00000000 0000000000000000 ffff88552dedbd00
[ 9649.341742] Call Trace:
[ 9649.341956]  <IRQ> 
[ 9649.342169]  [<ffffffff840d06e3>] sched_show_task+0xd3/0x140
[ 9649.342740]  [<ffffffff8410e420>] rcu_check_callbacks+0x8d0/0x8e0
[ 9649.343419]  [<ffffffff84124120>] ? tick_sched_do_timer+0x50/0x50
[ 9649.344228]  [<ffffffff84113cff>] update_process_times+0x2f/0x60
[ 9649.344956]  [<ffffffff84123a75>] tick_sched_handle.isra.15+0x25/0x60
[ 9649.345597]  [<ffffffff8412415d>] tick_sched_timer+0x3d/0x70
[ 9649.346171]  [<ffffffff8411477e>] __hrtimer_run_queues+0xee/0x260
[ 9649.346775]  [<ffffffff84114f0a>] hrtimer_interrupt+0x9a/0x180
[ 9649.347340]  [<ffffffff8404bf28>] local_apic_timer_interrupt+0x38/0x60
[ 9649.348038]  [<ffffffff8481e7ad>] smp_apic_timer_interrupt+0x3d/0x50
[ 9649.348726]  [<ffffffff8481d96c>] apic_timer_interrupt+0x8c/0xa0
[ 9649.349326]  <EOI> 
[ 9649.349561]  [<ffffffff8481b456>] ? native_safe_halt+0x6/0x10
[ 9649.350141]  [<ffffffff8481b190>] default_idle+0x20/0xd0
[ 9649.350669]  [<ffffffff840310af>] arch_cpu_idle+0xf/0x20
[ 9649.351256]  [<ffffffff8481b5a3>] default_idle_call+0x23/0x30
[ 9649.351873]  [<ffffffff840e7b20>] cpu_startup_entry+0x1d0/0x240
[ 9649.352466]  [<ffffffff8404a634>] start_secondary+0x154/0x190
[ 9649.353093] rcu_sched kthread starved for 153722 jiffies! g213098 c213097 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x100
[ 9649.354028] rcu_sched       W    0     7      2 0x00000000
[ 9649.354536]  ffff885507bacc80 0000000000000000 ffff88552de80000 ffff88552ff59600
[ 9649.355319]  ffff88552dedbd00 ffff96a4818cbd78 ffffffff848169e5 ffff88552ff50140
[ 9649.356093]  00000000ffffffff ffff88552ff59600 ffff96a4818cbdc0 ffff88552de80000
[ 9649.356807] Call Trace:
[ 9649.357026]  [<ffffffff848169e5>] ? __schedule+0x225/0x6d0
[ 9649.357505]  [<ffffffff84816ec6>] schedule+0x36/0x80
[ 9649.357935]  [<ffffffff8481a2bb>] schedule_timeout+0x1db/0x3f0
[ 9649.358455]  [<ffffffff840cc820>] ? resched_cpu+0x60/0x70
[ 9649.358973]  [<ffffffff841121b0>] ? del_timer_sync+0x50/0x50
[ 9649.359472]  [<ffffffff8410be89>] rcu_gp_kthread+0x4d9/0x960
[ 9649.359964]  [<ffffffff8410b9b0>] ? _synchronize_rcu_expedited.constprop.63+0x320/0x320
[ 9649.360661]  [<ffffffff840c2569>] kthread+0xd9/0xf0
[ 9649.361089]  [<ffffffff840c2490>] ? kthread_park+0x60/0x60
[ 9649.361571]  [<ffffffff8481be55>] ret_from_fork+0x25/0x30
[ 9763.038609] systemd-coredump[10869]: Detected coredump of the journal daemon or PID 1, diverted to /var/lib/systemd/coredump/core.systemd-journal.0.2615a1b9586b4b1fa6d7cfca112bf35b.341.1484421141000000.xz.
[ 9768.139445] systemd-journald[10871]: File /var/log/journal/26a321541dd247288885020aefc9054c/system.journal corrupted or uncleanly shut down, renaming and replacing.

Comment 3 Justin M. Forbes 2017-04-11 14:43:33 UTC
*********** MASS BUG UPDATE **************

We apologize for the inconvenience.  There is a large number of bugs to go through and several of them have gone stale.  Due to this, we are doing a mass bug update across all of the Fedora 25 kernel bugs.

Fedora 25 has now been rebased to 4.10.9-200.fc25.  Please test this kernel update (or newer) and let us know if you issue has been resolved or if it is still present with the newer kernel.

If you have moved on to Fedora 26, and are still experiencing this issue, please change the version to Fedora 26.

If you experience different issues, please open a new bug report for those.

Comment 4 Peter Robinson 2017-04-22 08:18:38 UTC
i think this is resolved nonw


Note You need to log in before you can comment on or make changes to this bug.