From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1756861AbcFAAH6 (ORCPT ); Tue, 31 May 2016 20:07:58 -0400 Received: from arcturus.aphlor.org ([188.246.204.175]:44836 "EHLO arcturus.aphlor.org" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1752788AbcFAAH4 (ORCPT ); Tue, 31 May 2016 20:07:56 -0400 Date: Tue, 31 May 2016 20:07:43 -0400 From: Dave Jones To: Linux Kernel Mailing List Cc: Omer Peleg , David Woodhouse Subject: Re: iommu/iova: introduce per-cpu caching to iova allocation Message-ID: <20160601000743.GA15077@codemonkey.org.uk> Mail-Followup-To: Dave Jones , Linux Kernel Mailing List , Omer Peleg , David Woodhouse References: <20160527215007.814FA6611FF@gitolite.kernel.org> MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline In-Reply-To: <20160527215007.814FA6611FF@gitolite.kernel.org> User-Agent: Mutt/1.6.0 (2016-04-01) X-Spam-Flag: skipped (authorised relay user) Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org On Fri, May 27, 2016 at 09:50:07PM +0000, Linux Kernel wrote: > Web: https://git.kernel.org/torvalds/c/9257b4a206fc0229dd5f84b78e4d1ebf3f91d270 > Commit: 9257b4a206fc0229dd5f84b78e4d1ebf3f91d270 > Parent: 2aac630429d986a43ac59525a4cff47a624dc58e > Refname: refs/heads/master > Author: Omer Peleg > AuthorDate: Wed Apr 20 11:34:11 2016 +0300 > Committer: David Woodhouse > CommitDate: Wed Apr 20 15:42:24 2016 -0400 > > iommu/iova: introduce per-cpu caching to iova allocation Since this commit, I'm seeing various traces from alloc_iova_fast spew this.. [ 11.403098] BUG: using smp_processor_id() in preemptible [00000000] code: systemd-udevd/331 [ 11.403101] caller is debug_smp_processor_id+0x17/0x20 [ 11.403103] CPU: 1 PID: 331 Comm: systemd-udevd Not tainted 4.7.0-rc1-think+ #5 [ 11.403106] ffff8804fc7d8040 0000000021902647 ffff8804fef6b578 ffffffffa83b7467 [ 11.403107] 0000000000000001 ffffffffa8c310f3 ffff8804fef6b5a8 ffffffffa83d6d2a [ 11.403108] ffff8804fe75e398 0000000000000000 0000000000000001 00000000000fffff [ 11.403108] Call Trace: [ 11.403111] [] dump_stack+0x68/0xa1 [ 11.403112] [] check_preemption_disabled+0xda/0xe0 [ 11.403113] [] debug_smp_processor_id+0x17/0x20 [ 11.403115] [] alloc_iova_fast+0x11b/0x250 [ 11.403117] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 11.403119] [] intel_alloc_iova+0x86/0xe0 [ 11.403120] [] __intel_map_single+0x98/0x190 [ 11.403121] [] intel_map_page+0x39/0x40 [ 11.403124] [] usb_hcd_map_urb_for_dma+0x4ab/0x5b0 [ 11.403125] [] ? __intel_map_single+0x190/0x190 [ 11.403126] [] usb_hcd_submit_urb+0x36d/0xb40 [ 11.403128] [] ? add_lock_to_list.isra.29.constprop.46+0x77/0xb0 [ 11.403129] [] ? __lock_acquire+0xc43/0x1200 [ 11.403131] [] usb_submit_urb+0x2f4/0x550 [ 11.403133] [] usb_serial_generic_submit_read_urb+0x4c/0xc0 [ 11.403134] [] ? trace_hardirqs_on_caller+0xed/0x1b0 [ 11.403135] [] usb_serial_generic_submit_read_urbs+0x1f/0x80 [ 11.403137] [] ? _raw_spin_unlock_irqrestore+0x42/0x70 [ 11.403137] [] usb_serial_generic_open+0x4e/0x60 [ 11.403139] [] usb_console_setup+0x1ff/0x3c0 [ 11.403140] [] ? _raw_spin_unlock+0x31/0x50 [ 11.403141] [] register_console+0x29a/0x380 [ 11.403142] [] usb_serial_console_init+0x22/0x40 [ 11.403143] [] usb_serial_probe+0x1175/0x11c0 [ 11.403144] [] ? debug_smp_processor_id+0x17/0x20 [ 11.403145] [] ? get_lock_stats+0x19/0x50 [ 11.403146] [] ? debug_smp_processor_id+0x17/0x20 [ 11.403147] [] ? get_lock_stats+0x19/0x50 [ 11.403148] [] ? _raw_spin_unlock_irqrestore+0x57/0x70 [ 11.403149] [] ? trace_hardirqs_on+0xd/0x10 [ 11.403150] [] ? _raw_spin_unlock_irqrestore+0x42/0x70 [ 11.403151] [] usb_probe_interface+0x122/0x2e0 [ 11.403153] [] driver_probe_device+0x245/0x450 [ 11.403154] [] __driver_attach+0xd5/0x100 [ 11.403155] [] ? driver_probe_device+0x450/0x450 [ 11.403156] [] bus_for_each_dev+0x73/0xc0 [ 11.403157] [] driver_attach+0x1e/0x20 [ 11.403158] [] usb_serial_register_drivers+0x266/0x4d0 [ 11.403159] [] ? 0xffffffffc0301000 [ 11.403161] [] usb_serial_module_init+0x1e/0x1000 [usb_debug] [ 11.403163] [] do_one_initcall+0xf7/0x180 [ 11.403164] [] ? rcu_read_lock_sched_held+0x6c/0x80 [ 11.403165] [] ? kmem_cache_alloc_trace+0x2c3/0x360 [ 11.403167] [] ? do_init_module+0x27/0x1da [ 11.403169] [] do_init_module+0x5f/0x1da [ 11.403171] [] load_module+0x21ee/0x27d0 [ 11.403172] [] ? disable_ro_nx+0x50/0x50 [ 11.403174] [] ? show_coresize+0x30/0x30 [ 11.403175] [] SYSC_finit_module+0xe6/0x120 [ 11.403177] [] SyS_finit_module+0xe/0x10 [ 11.403178] [] do_syscall_64+0x61/0x170 [ 11.403179] [] entry_SYSCALL64_slow_path+0x25/0x25 [ 11.403269] BUG: using smp_processor_id() in preemptible [00000000] code: systemd-udevd/331 [ 11.403270] caller is debug_smp_processor_id+0x17/0x20 [ 11.403272] CPU: 1 PID: 331 Comm: systemd-udevd Not tainted 4.7.0-rc1-think+ #5 [ 11.403273] ffff8804fc7d8040 0000000021902647 ffff8804fef6b578 ffffffffa83b7467 [ 11.403274] 0000000000000001 ffffffffa8c310f3 ffff8804fef6b5a8 ffffffffa83d6d2a [ 11.403276] ffff8804fe75e398 0000000000000000 0000000000000001 00000000000fffff [ 11.403276] Call Trace: [ 11.403277] [] dump_stack+0x68/0xa1 [ 11.403278] [] check_preemption_disabled+0xda/0xe0 [ 11.403279] [] debug_smp_processor_id+0x17/0x20 [ 11.403280] [] alloc_iova_fast+0x11b/0x250 [ 11.403281] [] ? mark_held_locks+0x76/0xa0 [ 11.403282] [] intel_alloc_iova+0x86/0xe0 [ 11.403283] [] __intel_map_single+0x98/0x190 [ 11.403284] [] intel_map_page+0x39/0x40 [ 11.403286] [] usb_hcd_map_urb_for_dma+0x4ab/0x5b0 [ 11.403287] [] ? __intel_map_single+0x190/0x190 [ 11.403288] [] usb_hcd_submit_urb+0x36d/0xb40 [ 11.403289] [] ? add_lock_to_list.isra.29.constprop.46+0x77/0xb0 [ 11.403290] [] ? __lock_acquire+0xc43/0x1200 [ 11.403292] [] usb_submit_urb+0x2f4/0x550 [ 11.403293] [] usb_serial_generic_submit_read_urb+0x4c/0xc0 [ 11.403294] [] ? trace_hardirqs_on_caller+0xed/0x1b0 [ 11.403296] [] usb_serial_generic_submit_read_urbs+0x33/0x80 [ 11.403296] [] ? _raw_spin_unlock_irqrestore+0x42/0x70 [ 11.403297] [] usb_serial_generic_open+0x4e/0x60 [ 11.403298] [] usb_console_setup+0x1ff/0x3c0 [ 11.403299] [] ? _raw_spin_unlock+0x31/0x50 [ 11.403300] [] register_console+0x29a/0x380 [ 11.403301] [] usb_serial_console_init+0x22/0x40 [ 11.403302] [] usb_serial_probe+0x1175/0x11c0 [ 11.403304] [] ? debug_smp_processor_id+0x17/0x20 [ 11.403305] [] ? get_lock_stats+0x19/0x50 [ 11.403305] [] ? debug_smp_processor_id+0x17/0x20 [ 11.403306] [] ? get_lock_stats+0x19/0x50 [ 11.403307] [] ? _raw_spin_unlock_irqrestore+0x57/0x70 [ 11.403308] [] ? trace_hardirqs_on+0xd/0x10 [ 11.403309] [] ? _raw_spin_unlock_irqrestore+0x42/0x70 [ 11.403310] [] usb_probe_interface+0x122/0x2e0 [ 11.403311] [] driver_probe_device+0x245/0x450 [ 11.403312] [] __driver_attach+0xd5/0x100 [ 11.403313] [] ? driver_probe_device+0x450/0x450 [ 11.403314] [] bus_for_each_dev+0x73/0xc0 [ 11.403316] [] driver_attach+0x1e/0x20 [ 11.403317] [] usb_serial_register_drivers+0x266/0x4d0 [ 11.403317] [] ? 0xffffffffc0301000 [ 11.403319] [] usb_serial_module_init+0x1e/0x1000 [usb_debug] [ 11.403320] [] do_one_initcall+0xf7/0x180 [ 11.403321] [] ? rcu_read_lock_sched_held+0x6c/0x80 [ 11.403322] [] ? kmem_cache_alloc_trace+0x2c3/0x360 [ 11.403324] [] ? do_init_module+0x27/0x1da [ 11.403325] [] do_init_module+0x5f/0x1da [ 11.403327] [] load_module+0x21ee/0x27d0 [ 11.403328] [] ? disable_ro_nx+0x50/0x50 [ 11.403329] [] ? show_coresize+0x30/0x30 [ 11.403331] [] SYSC_finit_module+0xe6/0x120 [ 11.403333] [] SyS_finit_module+0xe/0x10 [ 11.403333] [] do_syscall_64+0x61/0x170 [ 11.403334] [] entry_SYSCALL64_slow_path+0x25/0x25 [ 14.168745] BUG: using smp_processor_id() in preemptible [00000000] code: ip/515 [ 14.179279] caller is debug_smp_processor_id+0x17/0x20 [ 14.189976] CPU: 0 PID: 515 Comm: ip Not tainted 4.7.0-rc1-think+ #5 [ 14.200781] ffff8804ee641c40 00000000e708bd44 ffff8804ff93f530 ffffffffa83b7467 [ 14.211564] 0000000000000000 ffffffffa8c310f3 ffff8804ff93f560 ffffffffa83d6d2a [ 14.222374] ffff8804fe75ac98 0000000000000000 0000000000000001 00000000000fffff [ 14.233184] Call Trace: [ 14.243917] [] dump_stack+0x68/0xa1 [ 14.254816] [] check_preemption_disabled+0xda/0xe0 [ 14.265673] [] debug_smp_processor_id+0x17/0x20 [ 14.276538] [] alloc_iova_fast+0x11b/0x250 [ 14.287469] [] intel_alloc_iova+0xac/0xe0 [ 14.298304] [] __intel_map_single+0x98/0x190 [ 14.309183] [] intel_alloc_coherent+0xbd/0x130 [ 14.320143] [] e1000e_setup_tx_resources+0xc0/0x140 [ 14.331061] [] e1000e_open+0x6d/0x460 [ 14.341926] [] __dev_open+0xc6/0x140 [ 14.352871] [] __dev_change_flags+0x9d/0x160 [ 14.363700] [] dev_change_flags+0x29/0x60 [ 14.374564] [] do_setlink+0x684/0xba0 [ 14.385376] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 14.396263] [] ? __lock_acquire+0x7a5/0x1200 [ 14.407172] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 14.418120] [] ? debug_smp_processor_id+0x17/0x20 [ 14.429058] [] ? nla_parse+0x31/0x120 [ 14.440012] [] rtnl_newlink+0x5cf/0x880 [ 14.450911] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 14.461809] [] ? ns_capable+0x3d/0x70 [ 14.472693] [] rtnetlink_rcv_msg+0xe6/0x220 [ 14.483545] [] ? mutex_lock_nested+0x296/0x410 [ 14.494376] [] ? rtnetlink_rcv+0x1b/0x40 [ 14.505289] [] ? rtnetlink_rcv+0x1b/0x40 [ 14.516054] [] ? rtnetlink_rcv+0x40/0x40 [ 14.526816] [] netlink_rcv_skb+0xa4/0xc0 [ 14.537573] [] rtnetlink_rcv+0x2a/0x40 [ 14.548265] [] netlink_unicast+0x22f/0x2f0 [ 14.559030] [] ? netlink_unicast+0x17e/0x2f0 [ 14.569901] [] netlink_sendmsg+0x2b0/0x380 [ 14.580673] [] ? __might_fault+0x4c/0xa0 [ 14.591530] [] ___sys_sendmsg+0x2b5/0x2e0 [ 14.602300] [] ? debug_smp_processor_id+0x17/0x20 [ 14.613037] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 14.623795] [] ? __lock_acquire+0x7a5/0x1200 [ 14.634537] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 14.645321] [] ? __lock_acquire+0x7a5/0x1200 [ 14.656204] [] ? debug_smp_processor_id+0x17/0x20 [ 14.666996] [] ? get_lock_stats+0x19/0x50 [ 14.677657] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 14.688459] [] ? __fget_light+0x66/0x90 [ 14.699099] [] __sys_sendmsg+0x54/0x90 [ 14.709720] [] SyS_sendmsg+0x12/0x20 [ 14.720292] [] do_syscall_64+0x61/0x170 [ 14.730800] [] entry_SYSCALL64_slow_path+0x25/0x25 [ 14.744397] BUG: using smp_processor_id() in preemptible [00000000] code: ip/515 [ 14.755074] caller is debug_smp_processor_id+0x17/0x20 [ 14.765629] CPU: 0 PID: 515 Comm: ip Not tainted 4.7.0-rc1-think+ #5 [ 14.776129] ffff8804ee641c40 00000000e708bd44 ffff8804ff93f530 ffffffffa83b7467 [ 14.786495] 0000000000000000 ffffffffa8c310f3 ffff8804ff93f560 ffffffffa83d6d2a [ 14.796850] ffff8804fe75ac98 0000000000000001 0000000000000002 00000000000fffff [ 14.807219] Call Trace: [ 14.817546] [] dump_stack+0x68/0xa1 [ 14.827857] [] check_preemption_disabled+0xda/0xe0 [ 14.838156] [] debug_smp_processor_id+0x17/0x20 [ 14.848263] [] alloc_iova_fast+0x11b/0x250 [ 14.858326] [] intel_alloc_iova+0xac/0xe0 [ 14.868371] [] __intel_map_single+0x98/0x190 [ 14.878425] [] intel_alloc_coherent+0xbd/0x130 [ 14.888611] [] e1000e_setup_rx_resources+0x170/0x1d0 [ 14.898699] [] e1000e_open+0x84/0x460 [ 14.908771] [] __dev_open+0xc6/0x140 [ 14.918793] [] __dev_change_flags+0x9d/0x160 [ 14.928666] [] dev_change_flags+0x29/0x60 [ 14.938566] [] do_setlink+0x684/0xba0 [ 14.948297] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 14.958032] [] ? __lock_acquire+0x7a5/0x1200 [ 14.967779] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 14.977428] [] ? debug_smp_processor_id+0x17/0x20 [ 14.987251] [] ? nla_parse+0x31/0x120 [ 14.996878] [] rtnl_newlink+0x5cf/0x880 [ 15.006575] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.016177] [] ? ns_capable+0x3d/0x70 [ 15.025645] [] rtnetlink_rcv_msg+0xe6/0x220 [ 15.035195] [] ? mutex_lock_nested+0x296/0x410 [ 15.044627] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.054092] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.063367] [] ? rtnetlink_rcv+0x40/0x40 [ 15.072693] [] netlink_rcv_skb+0xa4/0xc0 [ 15.081908] [] rtnetlink_rcv+0x2a/0x40 [ 15.091080] [] netlink_unicast+0x22f/0x2f0 [ 15.100287] [] ? netlink_unicast+0x17e/0x2f0 [ 15.109414] [] netlink_sendmsg+0x2b0/0x380 [ 15.118617] [] ? __might_fault+0x4c/0xa0 [ 15.127774] [] ___sys_sendmsg+0x2b5/0x2e0 [ 15.136958] [] ? debug_smp_processor_id+0x17/0x20 [ 15.146050] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.155162] [] ? __lock_acquire+0x7a5/0x1200 [ 15.164227] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.173381] [] ? __lock_acquire+0x7a5/0x1200 [ 15.182480] [] ? debug_smp_processor_id+0x17/0x20 [ 15.191585] [] ? get_lock_stats+0x19/0x50 [ 15.200633] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.209649] [] ? __fget_light+0x66/0x90 [ 15.218686] [] __sys_sendmsg+0x54/0x90 [ 15.227607] [] SyS_sendmsg+0x12/0x20 [ 15.236564] [] do_syscall_64+0x61/0x170 [ 15.245382] [] entry_SYSCALL64_slow_path+0x25/0x25 [ 15.357655] BUG: using smp_processor_id() in preemptible [00000000] code: ip/515 [ 15.366871] caller is debug_smp_processor_id+0x17/0x20 [ 15.366874] CPU: 0 PID: 515 Comm: ip Not tainted 4.7.0-rc1-think+ #5 [ 15.366876] ffff8804ee641c40 00000000e708bd44 ffff8804ff93f4c8 ffffffffa83b7467 [ 15.366877] 0000000000000000 ffffffffa8c310f3 ffff8804ff93f4f8 ffffffffa83d6d2a [ 15.366878] ffff8804fe75ac98 0000000000000000 0000000000000001 00000000000fffff [ 15.366879] Call Trace: [ 15.366881] [] dump_stack+0x68/0xa1 [ 15.366882] [] check_preemption_disabled+0xda/0xe0 [ 15.366883] [] debug_smp_processor_id+0x17/0x20 [ 15.366886] [] alloc_iova_fast+0x11b/0x250 [ 15.366887] [] intel_alloc_iova+0xac/0xe0 [ 15.366889] [] __intel_map_single+0x98/0x190 [ 15.366890] [] intel_map_page+0x39/0x40 [ 15.366891] [] e1000_alloc_rx_buffers+0x119/0x2f0 [ 15.366893] [] ? __intel_map_single+0x190/0x190 [ 15.366894] [] e1000_configure+0x78a/0xc00 [ 15.366895] [] ? pm_qos_update_target+0x347/0x380 [ 15.366896] [] e1000e_open+0xff/0x460 [ 15.366898] [] __dev_open+0xc6/0x140 [ 15.366900] [] __dev_change_flags+0x9d/0x160 [ 15.366901] [] dev_change_flags+0x29/0x60 [ 15.366903] [] do_setlink+0x684/0xba0 [ 15.366904] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.366907] [] ? __lock_acquire+0x7a5/0x1200 [ 15.366908] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.366909] [] ? debug_smp_processor_id+0x17/0x20 [ 15.366911] [] ? nla_parse+0x31/0x120 [ 15.366912] [] rtnl_newlink+0x5cf/0x880 [ 15.366914] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.366916] [] ? ns_capable+0x3d/0x70 [ 15.366917] [] rtnetlink_rcv_msg+0xe6/0x220 [ 15.366919] [] ? mutex_lock_nested+0x296/0x410 [ 15.366920] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.366921] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.366922] [] ? rtnetlink_rcv+0x40/0x40 [ 15.366924] [] netlink_rcv_skb+0xa4/0xc0 [ 15.366925] [] rtnetlink_rcv+0x2a/0x40 [ 15.366926] [] netlink_unicast+0x22f/0x2f0 [ 15.366927] [] ? netlink_unicast+0x17e/0x2f0 [ 15.366928] [] netlink_sendmsg+0x2b0/0x380 [ 15.366930] [] ? __might_fault+0x4c/0xa0 [ 15.366931] [] ___sys_sendmsg+0x2b5/0x2e0 [ 15.366932] [] ? debug_smp_processor_id+0x17/0x20 [ 15.366933] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.366934] [] ? __lock_acquire+0x7a5/0x1200 [ 15.366935] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.366936] [] ? __lock_acquire+0x7a5/0x1200 [ 15.366937] [] ? debug_smp_processor_id+0x17/0x20 [ 15.366938] [] ? get_lock_stats+0x19/0x50 [ 15.366939] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.366941] [] ? __fget_light+0x66/0x90 [ 15.366942] [] __sys_sendmsg+0x54/0x90 [ 15.366944] [] SyS_sendmsg+0x12/0x20 [ 15.366945] [] do_syscall_64+0x61/0x170 [ 15.366945] [] entry_SYSCALL64_slow_path+0x25/0x25 [ 15.369319] BUG: using smp_processor_id() in preemptible [00000000] code: ip/515 [ 15.369321] caller is debug_smp_processor_id+0x17/0x20 [ 15.369323] CPU: 2 PID: 515 Comm: ip Not tainted 4.7.0-rc1-think+ #5 [ 15.369325] ffff8804ee641c40 00000000e708bd44 ffff8804ff93f4c8 ffffffffa83b7467 [ 15.369326] 0000000000000002 ffffffffa8c310f3 ffff8804ff93f4f8 ffffffffa83d6d2a [ 15.369327] ffff8804fe75ac98 0000000000000000 0000000000000001 00000000000fffff [ 15.369328] Call Trace: [ 15.369329] [] dump_stack+0x68/0xa1 [ 15.369330] [] check_preemption_disabled+0xda/0xe0 [ 15.369331] [] debug_smp_processor_id+0x17/0x20 [ 15.369333] [] alloc_iova_fast+0x11b/0x250 [ 15.369334] [] intel_alloc_iova+0xac/0xe0 [ 15.369335] [] __intel_map_single+0x98/0x190 [ 15.369336] [] intel_map_page+0x39/0x40 [ 15.369338] [] e1000_alloc_rx_buffers+0x119/0x2f0 [ 15.369339] [] ? __intel_map_single+0x190/0x190 [ 15.369340] [] e1000_configure+0x78a/0xc00 [ 15.369341] [] ? pm_qos_update_target+0x347/0x380 [ 15.369342] [] e1000e_open+0xff/0x460 [ 15.369344] [] __dev_open+0xc6/0x140 [ 15.369345] [] __dev_change_flags+0x9d/0x160 [ 15.369346] [] dev_change_flags+0x29/0x60 [ 15.369348] [] do_setlink+0x684/0xba0 [ 15.369349] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369350] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369351] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369352] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369359] [] ? nla_parse+0x31/0x120 [ 15.369361] [] rtnl_newlink+0x5cf/0x880 [ 15.369363] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369364] [] ? ns_capable+0x3d/0x70 [ 15.369366] [] rtnetlink_rcv_msg+0xe6/0x220 [ 15.369367] [] ? mutex_lock_nested+0x296/0x410 [ 15.369369] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.369370] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.369371] [] ? rtnetlink_rcv+0x40/0x40 [ 15.369372] [] netlink_rcv_skb+0xa4/0xc0 [ 15.369373] [] rtnetlink_rcv+0x2a/0x40 [ 15.369374] [] netlink_unicast+0x22f/0x2f0 [ 15.369375] [] ? netlink_unicast+0x17e/0x2f0 [ 15.369376] [] netlink_sendmsg+0x2b0/0x380 [ 15.369393] [] ? __might_fault+0x4c/0xa0 [ 15.369394] [] ___sys_sendmsg+0x2b5/0x2e0 [ 15.369395] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369396] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369397] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369398] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369399] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369400] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369401] [] ? get_lock_stats+0x19/0x50 [ 15.369402] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369404] [] ? __fget_light+0x66/0x90 [ 15.369405] [] __sys_sendmsg+0x54/0x90 [ 15.369406] [] SyS_sendmsg+0x12/0x20 [ 15.369407] [] do_syscall_64+0x61/0x170 [ 15.369408] [] entry_SYSCALL64_slow_path+0x25/0x25 [ 15.369502] BUG: using smp_processor_id() in preemptible [00000000] code: ip/515 [ 15.369503] caller is debug_smp_processor_id+0x17/0x20 [ 15.369505] CPU: 2 PID: 515 Comm: ip Not tainted 4.7.0-rc1-think+ #5 [ 15.369506] ffff8804ee641c40 00000000e708bd44 ffff8804ff93f4c8 ffffffffa83b7467 [ 15.369520] 0000000000000002 ffffffffa8c310f3 ffff8804ff93f4f8 ffffffffa83d6d2a [ 15.369521] ffff8804fe75ac98 0000000000000001 0000000000000002 00000000000fffff [ 15.369521] Call Trace: [ 15.369522] [] dump_stack+0x68/0xa1 [ 15.369523] [] check_preemption_disabled+0xda/0xe0 [ 15.369524] [] debug_smp_processor_id+0x17/0x20 [ 15.369525] [] alloc_iova_fast+0x11b/0x250 [ 15.369526] [] intel_alloc_iova+0xac/0xe0 [ 15.369527] [] __intel_map_single+0x98/0x190 [ 15.369528] [] intel_map_page+0x39/0x40 [ 15.369530] [] e1000_alloc_rx_buffers+0x119/0x2f0 [ 15.369531] [] ? __intel_map_single+0x190/0x190 [ 15.369532] [] e1000_configure+0x78a/0xc00 [ 15.369532] [] ? pm_qos_update_target+0x347/0x380 [ 15.369534] [] e1000e_open+0xff/0x460 [ 15.369540] [] __dev_open+0xc6/0x140 [ 15.369543] [] __dev_change_flags+0x9d/0x160 [ 15.369544] [] dev_change_flags+0x29/0x60 [ 15.369546] [] do_setlink+0x684/0xba0 [ 15.369547] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369548] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369549] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369550] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369551] [] ? nla_parse+0x31/0x120 [ 15.369552] [] rtnl_newlink+0x5cf/0x880 [ 15.369554] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369555] [] ? ns_capable+0x3d/0x70 [ 15.369556] [] rtnetlink_rcv_msg+0xe6/0x220 [ 15.369558] [] ? mutex_lock_nested+0x296/0x410 [ 15.369559] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.369560] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.369561] [] ? rtnetlink_rcv+0x40/0x40 [ 15.369562] [] netlink_rcv_skb+0xa4/0xc0 [ 15.369563] [] rtnetlink_rcv+0x2a/0x40 [ 15.369564] [] netlink_unicast+0x22f/0x2f0 [ 15.369564] [] ? netlink_unicast+0x17e/0x2f0 [ 15.369566] [] netlink_sendmsg+0x2b0/0x380 [ 15.369567] [] ? __might_fault+0x4c/0xa0 [ 15.369568] [] ___sys_sendmsg+0x2b5/0x2e0 [ 15.369569] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369570] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369571] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369572] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369573] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369574] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369575] [] ? get_lock_stats+0x19/0x50 [ 15.369576] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369577] [] ? __fget_light+0x66/0x90 [ 15.369578] [] __sys_sendmsg+0x54/0x90 [ 15.369580] [] SyS_sendmsg+0x12/0x20 [ 15.369580] [] do_syscall_64+0x61/0x170 [ 15.369581] [] entry_SYSCALL64_slow_path+0x25/0x25 [ 15.369645] BUG: using smp_processor_id() in preemptible [00000000] code: ip/515 [ 15.369646] caller is debug_smp_processor_id+0x17/0x20 [ 15.369647] CPU: 2 PID: 515 Comm: ip Not tainted 4.7.0-rc1-think+ #5 [ 15.369648] ffff8804ee641c40 00000000e708bd44 ffff8804ff93f4c8 ffffffffa83b7467 [ 15.369649] 0000000000000002 ffffffffa8c310f3 ffff8804ff93f4f8 ffffffffa83d6d2a [ 15.369650] ffff8804fe75ac98 0000000000000001 0000000000000002 00000000000fffff [ 15.369651] Call Trace: [ 15.369652] [] dump_stack+0x68/0xa1 [ 15.369652] [] check_preemption_disabled+0xda/0xe0 [ 15.369653] [] debug_smp_processor_id+0x17/0x20 [ 15.369654] [] alloc_iova_fast+0x11b/0x250 [ 15.369656] [] intel_alloc_iova+0xac/0xe0 [ 15.369657] [] __intel_map_single+0x98/0x190 [ 15.369658] [] intel_map_page+0x39/0x40 [ 15.369659] [] e1000_alloc_rx_buffers+0x119/0x2f0 [ 15.369666] [] ? __intel_map_single+0x190/0x190 [ 15.369668] [] e1000_configure+0x78a/0xc00 [ 15.369668] [] ? pm_qos_update_target+0x347/0x380 [ 15.369670] [] e1000e_open+0xff/0x460 [ 15.369671] [] __dev_open+0xc6/0x140 [ 15.369672] [] __dev_change_flags+0x9d/0x160 [ 15.369673] [] dev_change_flags+0x29/0x60 [ 15.369674] [] do_setlink+0x684/0xba0 [ 15.369676] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369677] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369678] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369678] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369680] [] ? nla_parse+0x31/0x120 [ 15.369681] [] rtnl_newlink+0x5cf/0x880 [ 15.369683] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369684] [] ? ns_capable+0x3d/0x70 [ 15.369685] [] rtnetlink_rcv_msg+0xe6/0x220 [ 15.369686] [] ? mutex_lock_nested+0x296/0x410 [ 15.369687] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.369688] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.369689] [] ? rtnetlink_rcv+0x40/0x40 [ 15.369690] [] netlink_rcv_skb+0xa4/0xc0 [ 15.369691] [] rtnetlink_rcv+0x2a/0x40 [ 15.369692] [] netlink_unicast+0x22f/0x2f0 [ 15.369693] [] ? netlink_unicast+0x17e/0x2f0 [ 15.369694] [] netlink_sendmsg+0x2b0/0x380 [ 15.369695] [] ? __might_fault+0x4c/0xa0 [ 15.369696] [] ___sys_sendmsg+0x2b5/0x2e0 [ 15.369697] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369698] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369699] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369700] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369701] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369702] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369703] [] ? get_lock_stats+0x19/0x50 [ 15.369704] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369705] [] ? __fget_light+0x66/0x90 [ 15.369706] [] __sys_sendmsg+0x54/0x90 [ 15.369708] [] SyS_sendmsg+0x12/0x20 [ 15.369708] [] do_syscall_64+0x61/0x170 [ 15.369709] [] entry_SYSCALL64_slow_path+0x25/0x25 [ 15.369783] BUG: using smp_processor_id() in preemptible [00000000] code: ip/515 [ 15.369784] caller is debug_smp_processor_id+0x17/0x20 [ 15.369785] CPU: 2 PID: 515 Comm: ip Not tainted 4.7.0-rc1-think+ #5 [ 15.369786] ffff8804ee641c40 00000000e708bd44 ffff8804ff93f4c8 ffffffffa83b7467 [ 15.369787] 0000000000000002 ffffffffa8c310f3 ffff8804ff93f4f8 ffffffffa83d6d2a [ 15.369788] ffff8804fe75ac98 0000000000000000 0000000000000001 00000000000fffff [ 15.369789] Call Trace: [ 15.369790] [] dump_stack+0x68/0xa1 [ 15.369790] [] check_preemption_disabled+0xda/0xe0 [ 15.369791] [] debug_smp_processor_id+0x17/0x20 [ 15.369792] [] alloc_iova_fast+0x11b/0x250 [ 15.369794] [] intel_alloc_iova+0xac/0xe0 [ 15.369795] [] __intel_map_single+0x98/0x190 [ 15.369796] [] intel_map_page+0x39/0x40 [ 15.369797] [] e1000_alloc_rx_buffers+0x119/0x2f0 [ 15.369798] [] ? __intel_map_single+0x190/0x190 [ 15.369805] [] e1000_configure+0x78a/0xc00 [ 15.369810] [] ? pm_qos_update_target+0x347/0x380 [ 15.369821] [] e1000e_open+0xff/0x460 [ 15.369833] [] __dev_open+0xc6/0x140 [ 15.369835] [] __dev_change_flags+0x9d/0x160 [ 15.369836] [] dev_change_flags+0x29/0x60 [ 15.369843] [] do_setlink+0x684/0xba0 [ 15.369844] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369845] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369846] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369847] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369848] [] ? nla_parse+0x31/0x120 [ 15.369849] [] rtnl_newlink+0x5cf/0x880 [ 15.369851] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369852] [] ? ns_capable+0x3d/0x70 [ 15.369853] [] rtnetlink_rcv_msg+0xe6/0x220 [ 15.369855] [] ? mutex_lock_nested+0x296/0x410 [ 15.369855] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.369857] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.369858] [] ? rtnetlink_rcv+0x40/0x40 [ 15.369859] [] netlink_rcv_skb+0xa4/0xc0 [ 15.369860] [] rtnetlink_rcv+0x2a/0x40 [ 15.369860] [] netlink_unicast+0x22f/0x2f0 [ 15.369861] [] ? netlink_unicast+0x17e/0x2f0 [ 15.369862] [] netlink_sendmsg+0x2b0/0x380 [ 15.369865] [] ? __might_fault+0x4c/0xa0 [ 15.369866] [] ___sys_sendmsg+0x2b5/0x2e0 [ 15.369867] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369868] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369869] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369870] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369871] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369871] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369872] [] ? get_lock_stats+0x19/0x50 [ 15.369873] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369877] [] ? __fget_light+0x66/0x90 [ 15.369878] [] __sys_sendmsg+0x54/0x90 [ 15.369880] [] SyS_sendmsg+0x12/0x20 [ 15.369882] [] do_syscall_64+0x61/0x170 [ 15.369884] [] entry_SYSCALL64_slow_path+0x25/0x25 [ 15.369920] BUG: using smp_processor_id() in preemptible [00000000] code: ip/515 [ 15.369920] caller is debug_smp_processor_id+0x17/0x20 [ 15.369921] CPU: 2 PID: 515 Comm: ip Not tainted 4.7.0-rc1-think+ #5 [ 15.369923] ffff8804ee641c40 00000000e708bd44 ffff8804ff93f4c8 ffffffffa83b7467 [ 15.369924] 0000000000000002 ffffffffa8c310f3 ffff8804ff93f4f8 ffffffffa83d6d2a [ 15.369925] ffff8804fe75ac98 0000000000000000 0000000000000001 00000000000fffff [ 15.369925] Call Trace: [ 15.369926] [] dump_stack+0x68/0xa1 [ 15.369927] [] check_preemption_disabled+0xda/0xe0 [ 15.369928] [] debug_smp_processor_id+0x17/0x20 [ 15.369929] [] alloc_iova_fast+0x11b/0x250 [ 15.369930] [] intel_alloc_iova+0xac/0xe0 [ 15.369931] [] __intel_map_single+0x98/0x190 [ 15.369932] [] intel_map_page+0x39/0x40 [ 15.369933] [] e1000_alloc_rx_buffers+0x119/0x2f0 [ 15.369934] [] ? __intel_map_single+0x190/0x190 [ 15.369935] [] e1000_configure+0x78a/0xc00 [ 15.369936] [] ? pm_qos_update_target+0x347/0x380 [ 15.369937] [] e1000e_open+0xff/0x460 [ 15.369938] [] __dev_open+0xc6/0x140 [ 15.369939] [] __dev_change_flags+0x9d/0x160 [ 15.369940] [] dev_change_flags+0x29/0x60 [ 15.369942] [] do_setlink+0x684/0xba0 [ 15.369943] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369944] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369945] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369946] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369947] [] ? nla_parse+0x31/0x120 [ 15.369948] [] rtnl_newlink+0x5cf/0x880 [ 15.369950] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369951] [] ? ns_capable+0x3d/0x70 [ 15.369952] [] rtnetlink_rcv_msg+0xe6/0x220 [ 15.369963] [] ? mutex_lock_nested+0x296/0x410 [ 15.369964] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.369966] [] ? rtnetlink_rcv+0x1b/0x40 [ 15.369967] [] ? rtnetlink_rcv+0x40/0x40 [ 15.369967] [] netlink_rcv_skb+0xa4/0xc0 [ 15.369969] [] rtnetlink_rcv+0x2a/0x40 [ 15.369969] [] netlink_unicast+0x22f/0x2f0 [ 15.369970] [] ? netlink_unicast+0x17e/0x2f0 [ 15.369971] [] netlink_sendmsg+0x2b0/0x380 [ 15.369972] [] ? __might_fault+0x4c/0xa0 [ 15.369974] [] ___sys_sendmsg+0x2b5/0x2e0 [ 15.369974] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369975] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369976] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369977] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369978] [] ? __lock_acquire+0x7a5/0x1200 [ 15.369979] [] ? debug_smp_processor_id+0x17/0x20 [ 15.369980] [] ? get_lock_stats+0x19/0x50 [ 15.369981] [] ? debug_lockdep_rcu_enabled+0x1d/0x20 [ 15.369982] [] ? __fget_light+0x66/0x90 [ 15.369983] [] __sys_sendmsg+0x54/0x90 [ 15.369985] [] SyS_sendmsg+0x12/0x20 [ 15.369986] [] do_syscall_64+0x61/0x170 [ 15.369986] [] entry_SYSCALL64_slow_path+0x25/0x25