[<prev] [next>] [<thread-prev] [thread-next>] [day] [month] [year] [list]
Message-ID: <20101020154045.GA18353@elte.hu>
Date: Wed, 20 Oct 2010 17:40:45 +0200
From: Ingo Molnar <mingo@...e.hu>
To: Steven Rostedt <rostedt@...dmis.org>,
Jason Baron <jbaron@...hat.com>
Cc: LKML <linux-kernel@...r.kernel.org>,
Andrew Morton <akpm@...ux-foundation.org>,
Frederic Weisbecker <fweisbec@...il.com>,
Thomas Gleixner <tglx@...utronix.de>,
"H. Peter Anvin" <hpa@...or.com>,
Peter Zijlstra <a.p.zijlstra@...llo.nl>,
Arnaldo Carvalho de Melo <acme@...hat.com>
Subject: Re: [PATCH][GIT PULL] tracing: Fix compile issue for
trace_sched_wakeup.c
FYI, there's a new mystery hang (sometimes crash) that triggers in -tip - and which
seems to be tracing related. See the crashlog below - config attached.
It's not bisectable - small changes in the kernel make the bug come/go. (might be a
race of some sorts)
Thanks,
Ingo
[ .... ]
[ 33.996015] bus: 'pci': add driver S3 SonicVibes
[ 34.000059] initcall alsa_card_sonicvibes_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.004009] calling alsa_card_via82xx_init+0x0/0x16 @ 1
[ 34.008015] bus: 'pci': add driver VIA 82xx Modem
[ 34.012059] initcall alsa_card_via82xx_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.016009] calling alsa_ac97_init+0x0/0x7 @ 1
[ 34.020005] initcall alsa_ac97_init+0x0/0x7 returned 0 after 0 usecs
[ 34.024006] calling snd_asihpi_init+0x0/0x1b @ 1
[ 34.028008] ASIHPI driver 4.04.01
[ 34.032053] bus: 'pci': add driver asihpi
[ 34.036060] initcall snd_asihpi_init+0x0/0x1b returned 0 after 7812 usecs
[ 34.040009] calling alsa_card_vortex_init+0x0/0x16 @ 1
[ 34.044015] bus: 'pci': add driver au8810
[ 34.048058] initcall alsa_card_vortex_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.052009] calling alsa_card_vortex_init+0x0/0x16 @ 1
[ 34.056016] bus: 'pci': add driver au8820
[ 34.060064] initcall alsa_card_vortex_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.064009] calling alsa_card_vortex_init+0x0/0x16 @ 1
[ 34.068016] bus: 'pci': add driver au8830
[ 34.072061] initcall alsa_card_vortex_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.076009] calling alsa_card_ca0106_init+0x0/0x16 @ 1
[ 34.080015] bus: 'pci': add driver CA0106
[ 34.084060] initcall alsa_card_ca0106_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.088009] calling alsa_card_cs46xx_init+0x0/0x16 @ 1
[ 34.092016] bus: 'pci': add driver Sound Fusion CS46xx
[ 34.100044] initcall alsa_card_cs46xx_init+0x0/0x16 returned 0 after 7812 usecs
[ 34.104005] calling alsa_card_cs5535audio_init+0x0/0x16 @ 1
[ 34.108016] bus: 'pci': add driver cs5535audio
[ 34.112070] initcall alsa_card_cs5535audio_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.116006] calling alsa_card_echo_init+0x0/0x16 @ 1
[ 34.120017] bus: 'pci': add driver Echoaudio Gina20
[ 34.124068] initcall alsa_card_echo_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.128006] calling alsa_card_echo_init+0x0/0x16 @ 1
[ 34.132016] bus: 'pci': add driver Echoaudio Layla20
[ 34.136066] initcall alsa_card_echo_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.140006] calling alsa_card_echo_init+0x0/0x16 @ 1
[ 34.144016] bus: 'pci': add driver Echoaudio Darla24
[ 34.148067] initcall alsa_card_echo_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.152009] calling alsa_card_echo_init+0x0/0x16 @ 1
[ 34.156016] bus: 'pci': add driver Echoaudio Mona
[ 34.160067] initcall alsa_card_echo_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.164006] calling alsa_card_echo_init+0x0/0x16 @ 1
[ 34.168016] bus: 'pci': add driver Echoaudio Mia
[ 34.172066] initcall alsa_card_echo_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.176006] calling alsa_card_echo_init+0x0/0x16 @ 1
[ 34.180017] bus: 'pci': add driver Echoaudio Indigo
[ 34.184067] initcall alsa_card_echo_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.188006] calling alsa_card_echo_init+0x0/0x16 @ 1
[ 34.192020] bus: 'pci': add driver Echoaudio Indigo IOx
[ 34.196067] initcall alsa_card_echo_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.200006] calling alsa_card_echo_init+0x0/0x16 @ 1
[ 34.204017] bus: 'pci': add driver Echoaudio Indigo DJx
[ 34.208067] initcall alsa_card_echo_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.212006] calling alsa_card_emu10k1_init+0x0/0x16 @ 1
[ 34.216016] bus: 'pci': add driver EMU10K1_Audigy
[ 34.220066] initcall alsa_card_emu10k1_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.224006] calling alsa_card_emu10k1x_init+0x0/0x16 @ 1
[ 34.228016] bus: 'pci': add driver EMU10K1X
[ 34.232075] initcall alsa_card_emu10k1x_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.236006] calling alsa_card_ice1724_init+0x0/0x16 @ 1
[ 34.240017] bus: 'pci': add driver ICE1724
[ 34.244067] initcall alsa_card_ice1724_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.248006] calling alsa_ice1712_akm4xxx_module_init+0x0/0x7 @ 1
[ 34.252006] initcall alsa_ice1712_akm4xxx_module_init+0x0/0x7 returned 0 after 0 usecs
[ 34.256009] calling alsa_card_mixart_init+0x0/0x16 @ 1
[ 34.260017] bus: 'pci': add driver Digigram miXart
[ 34.264063] initcall alsa_card_mixart_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.268009] calling alsa_card_oxygen_init+0x0/0x16 @ 1
[ 34.272020] bus: 'pci': add driver CMI8788
[ 34.276063] initcall alsa_card_oxygen_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.280009] calling alsa_card_xonar_init+0x0/0x16 @ 1
[ 34.284016] bus: 'pci': add driver AV200
[ 34.288063] initcall alsa_card_xonar_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.292009] calling pcxhr_module_init+0x0/0x16 @ 1
[ 34.296017] bus: 'pci': add driver Digigram pcxhr
[ 34.300065] initcall pcxhr_module_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.304009] calling alsa_card_hammerfall_init+0x0/0x16 @ 1
[ 34.308017] bus: 'pci': add driver RME Digi9652 (Hammerfall)
[ 34.312067] initcall alsa_card_hammerfall_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.316009] calling alsa_card_trident_init+0x0/0x16 @ 1
[ 34.320017] bus: 'pci': add driver Trident4DWaveAudio
[ 34.324063] initcall alsa_card_trident_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.328009] calling alsa_card_ymfpci_init+0x0/0x16 @ 1
[ 34.332017] bus: 'pci': add driver Yamaha DS-1 PCI
[ 34.336063] initcall alsa_card_ymfpci_init+0x0/0x16 returned 0 after 3906 usecs
[ 34.340010] calling alsa_util_mem_init+0x0/0x7 @ 1
[ 34.344006] initcall alsa_util_mem_init+0x0/0x7 returned 0 after 0 usecs
[ 34.348005] calling init_vxpocket+0x0/0xf @ 1
[ 34.352015] bus: 'pcmcia': add driver snd-vxpocket
[ 34.356036] initcall init_vxpocket+0x0/0xf returned 0 after 3906 usecs
[ 34.360006] calling snd_soc_init+0x0/0x40 @ 1
[ 34.364040] bus: 'platform': add driver soc-audio
[ 34.368042] initcall snd_soc_init+0x0/0x40 returned 0 after 3906 usecs
[ 34.372006] calling alsa_sound_last_init+0x0/0x50 @ 1
[ 34.376008] ALSA device list:
[ 34.380004] #0: NVidia CK804 with ALC850 at irq 27
[ 34.384006] initcall alsa_sound_last_init+0x0/0x50 returned 0 after 7812 usecs
[ 34.388010] calling flow_cache_init_global+0x0/0x141 @ 1
[ 34.392197] initcall flow_cache_init_global+0x0/0x141 returned 0 after 0 usecs
[ 34.396006] calling pg_init+0x0/0x2d4 @ 1
[ 34.400009] pktgen: Packet Generator for packet performance testing. Version: 2.74
[ 34.408087] initcall pg_init+0x0/0x2d4 returned 0 after 7812 usecs
[ 34.412010] calling llc_init+0x0/0x1b @ 1
[ 34.416009] initcall llc_init+0x0/0x1b returned 0 after 0 usecs
[ 34.420005] calling llc2_init+0x0/0xb5 @ 1
[ 34.424039] NET: Registered protocol family 26
[ 34.428006] initcall llc2_init+0x0/0xb5 returned 0 after 3906 usecs
[ 34.432009] calling snap_init+0x0/0x31 @ 1
[ 34.436034] initcall snap_init+0x0/0x31 returned 0 after 0 usecs
[ 34.440006] calling rif_init+0x0/0x71 @ 1
[ 34.444016] initcall rif_init+0x0/0x71 returned 0 after 0 usecs
[ 34.448009] calling sysctl_ipv4_init+0x0/0x71 @ 1
[ 34.452019] initcall sysctl_ipv4_init+0x0/0x71 returned 0 after 0 usecs
[ 34.456006] calling ipip_init+0x0/0x50 @ 1
[ 34.460007] IPv4 over IPv4 tunneling driver
[ 34.464028] device: 'tunl0': device_add
[ 34.468118] PM: Adding info for No Bus:tunl0
[ 34.472127] initcall ipip_init+0x0/0x50 returned 0 after 11718 usecs
[ 34.476006] calling init_syncookies+0x0/0x16 @ 1
[ 34.480057] initcall init_syncookies+0x0/0x16 returned 0 after 0 usecs
[ 34.484009] calling ah4_init+0x0/0x5a @ 1
[ 34.488009] initcall ah4_init+0x0/0x5a returned 0 after 0 usecs
[ 34.492005] calling esp4_init+0x0/0x5a @ 1
[ 34.496011] initcall esp4_init+0x0/0x5a returned 0 after 0 usecs
[ 34.500005] calling ipcomp4_init+0x0/0x5a @ 1
[ 34.504027] initcall ipcomp4_init+0x0/0x5a returned 0 after 0 usecs
[ 34.508009] calling ipip_init+0x0/0x88 @ 1
[ 34.512013] initcall ipip_init+0x0/0x88 returned 0 after 0 usecs
[ 34.516006] calling xfrm4_beet_init+0x0/0x14 @ 1
[ 34.520010] initcall xfrm4_beet_init+0x0/0x14 returned 0 after 0 usecs
[ 34.524006] calling tunnel4_init+0x0/0x5a @ 1
[ 34.528007] initcall tunnel4_init+0x0/0x5a returned 0 after 0 usecs
[ 34.532009] calling xfrm4_mode_tunnel_init+0x0/0x14 @ 1
[ 34.536007] initcall xfrm4_mode_tunnel_init+0x0/0x14 returned 0 after 0 usecs
[ 34.540006] calling inet_diag_init+0x0/0xbd @ 1
[ 34.544033] initcall inet_diag_init+0x0/0xbd returned 0 after 0 usecs
[ 34.548006] calling tcp_diag_init+0x0/0xf @ 1
[ 34.552033] initcall tcp_diag_init+0x0/0xf returned 0 after 0 usecs
[ 34.556009] calling cubictcp_register+0x0/0x77 @ 1
[ 34.560005] TCP cubic registered
[ 34.564006] initcall cubictcp_register+0x0/0x77 returned 0 after 3906 usecs
[ 34.568009] calling inet6_init+0x0/0x27f @ 1
[ 34.572568] NET: Registered protocol family 10
[ 34.576437] lo: Disabled Privacy Extensions
[ 34.584305] tunl0: Disabled Privacy Extensions
[ 34.588132] initcall inet6_init+0x0/0x27f returned 0 after 15625 usecs
[ 34.592013] calling esp6_init+0x0/0x5a @ 1
[ 34.596009] initcall esp6_init+0x0/0x5a returned 0 after 0 usecs
[ 34.600005] calling xfrm6_transport_init+0x0/0x14 @ 1
[ 34.604029] initcall xfrm6_transport_init+0x0/0x14 returned 0 after 0 usecs
[ 34.608005] calling mip6_init+0x0/0xa7 @ 1
[ 34.612004] Mobile IPv6
[ 34.616010] initcall mip6_init+0x0/0xa7 returned 0 after 3906 usecs
[ 34.620005] calling sit_init+0x0/0x50 @ 1
[ 34.624004] IPv6 over IPv4 tunneling driver
[ 34.628025] device: 'sit0': device_add
[ 34.632120] PM: Adding info for No Bus:sit0
[ 34.636100] sit0: Disabled Privacy Extensions
[ 34.640079] initcall sit_init+0x0/0x50 returned 0 after 15625 usecs
[ 34.644006] calling packet_init+0x0/0x39 @ 1
[ 34.648006] NET: Registered protocol family 17
[ 34.652021] initcall packet_init+0x0/0x39 returned 0 after 3906 usecs
[ 34.656005] calling ipsec_pfkey_init+0x0/0x6b @ 1
[ 34.660013] NET: Registered protocol family 15
[ 34.664029] initcall ipsec_pfkey_init+0x0/0x6b returned 0 after 3906 usecs
[ 34.668005] calling ipx_init+0x0/0xd2 @ 1
[ 34.672028] NET: Registered protocol family 4
[ 34.688037] initcall ipx_init+0x0/0xd2 returned 0 after 15625 usecs
[ 34.692005] calling atalk_init+0x0/0x77 @ 1
[ 34.696006] NET: Registered protocol family 5
[ 34.720038] initcall atalk_init+0x0/0x77 returned 0 after 23437 usecs
[ 34.724009] calling can_init+0x0/0xc6 @ 1
[ 34.728004] can: controller area network core (rev 20090105 abi 8)
[ 34.732069] NET: Registered protocol family 29
[ 34.736013] initcall can_init+0x0/0xc6 returned 0 after 7812 usecs
[ 34.740005] calling raw_module_init+0x0/0x31 @ 1
[ 34.744004] can: raw protocol (rev 20090105)
[ 34.748036] initcall raw_module_init+0x0/0x31 returned 0 after 3906 usecs
[ 34.752009] calling bcm_module_init+0x0/0x4a @ 1
[ 34.756004] can: broadcast manager protocol (rev 20090105 t)
[ 34.760015] initcall bcm_module_init+0x0/0x4a returned 0 after 3906 usecs
[ 34.764005] calling af_rxrpc_init+0x0/0x170 @ 1
[ 34.768081] NET: Registered protocol family 33
[ 34.772054] initcall af_rxrpc_init+0x0/0x170 returned 0 after 3906 usecs
[ 34.780006] calling rxkad_init+0x0/0x2f @ 1
[ 34.784894] RxRPC: Registered security type 2 'rxkad'
[ 34.788009] initcall rxkad_init+0x0/0x2f returned 0 after 3906 usecs
[ 34.792028] cryptomgr_probe used greatest stack depth: 6940 bytes left
[ 34.796032] calling l2tp_init+0x0/0x2c @ 1
[ 34.800011] L2TP core driver, V2.0
[ 34.804023] initcall l2tp_init+0x0/0x2c returned 0 after 3906 usecs
[ 34.808019] calling pppol2tp_init+0x0/0x88 @ 1
[ 34.812017] PPPoL2TP kernel driver, V2.0
[ 34.816029] initcall pppol2tp_init+0x0/0x88 returned 0 after 3906 usecs
[ 34.820013] calling l2tp_nl_init+0x0/0x28 @ 1
[ 34.824005] L2TP netlink interface
[ 34.827416] initcall l2tp_nl_init+0x0/0x28 returned 0 after 0 usecs
[ 34.828006] calling decnet_init+0x0/0x77 @ 1
[ 34.832011] NET4: DECnet for Linux: V.2.5.68s (C) 1995-2003 Linux DECnet Project Team
[ 34.836257] DECnet: Routing cache hash table of 1024 buckets, 44Kbytes
[ 34.840055] NET: Registered protocol family 12
[ 34.844025] initcall decnet_init+0x0/0x77 returned 0 after 11718 usecs
[ 34.848006] calling econet_proto_init+0x0/0x2f @ 1
[ 34.852007] NET: Registered protocol family 19
[ 34.856011] initcall econet_proto_init+0x0/0x2f returned 0 after 3906 usecs
[ 34.860006] calling phonet_init+0x0/0x70 @ 1
[ 34.864017] NET: Registered protocol family 35
[ 34.868062] initcall phonet_init+0x0/0x70 returned 0 after 3906 usecs
[ 34.872009] calling pep_register+0x0/0x14 @ 1
[ 34.876149] initcall pep_register+0x0/0x14 returned 0 after 0 usecs
[ 34.880010] calling vlan_proto_init+0x0/0x9d @ 1
[ 34.884005] 802.1Q VLAN Support v1.8 Ben Greear <greearb@...delatech.com>
[ 34.888004] All bugs added by David S. Miller <davem@...hat.com>
[ 34.892072] initcall vlan_proto_init+0x0/0x9d returned 0 after 7812 usecs
[ 34.896006] calling dccp_init+0x0/0x319 @ 1
[ 34.906804] CCID: Activated CCID 2 (TCP-like)
[ 34.908014] initcall dccp_init+0x0/0x319 returned 0 after 7812 usecs
[ 34.912011] calling dccp_v4_init+0x0/0x72 @ 1
[ 34.916343] initcall dccp_v4_init+0x0/0x72 returned 0 after 0 usecs
[ 34.920006] calling dccp_v6_init+0x0/0x72 @ 1
[ 34.924301] initcall dccp_v6_init+0x0/0x72 returned 0 after 0 usecs
[ 34.928010] calling dccp_diag_init+0x0/0xf @ 1
[ 34.932007] initcall dccp_diag_init+0x0/0xf returned 0 after 0 usecs
[ 34.936006] calling sctp_init+0x0/0x7b9 @ 1
[ 34.944859] SCTP: Hash tables configured (established 21845 bind 23831)
[ 34.948388] sctp_init_sock(sk: f54bc000)
[ 34.952052] initcall sctp_init+0x0/0x7b9 returned 0 after 11718 usecs
[ 34.956005] calling rds_init+0x0/0xa6 @ 1
[ 34.960270] NET: Registered protocol family 21
[ 34.964008] initcall rds_init+0x0/0xa6 returned 0 after 3906 usecs
[ 34.972006] calling rds_rdma_init+0x0/0xf2 @ 1
[ 34.976158] Registered RDS/iwarp transport
[ 34.980043] Registered RDS/infiniband transport
[ 34.984007] initcall rds_rdma_init+0x0/0xf2 returned 0 after 7812 usecs
[ 34.992005] calling rds_tcp_init+0x0/0x80 @ 1
[ 34.996031] Registered RDS/tcp transport
[ 35.000198] initcall rds_tcp_init+0x0/0x80 returned 0 after 3906 usecs
[ 35.008024] calling tipc_init+0x0/0xab @ 1
[ 35.012026] TIPC: Activated (version 2.0.0 compiled Oct 20 2010 08:00:42)
[ 35.016550] NET: Registered protocol family 30
[ 35.024010] TIPC: Started in single node mode
[ 35.028009] initcall tipc_init+0x0/0xab returned 0 after 15625 usecs
[ 35.032006] calling caif_device_init+0x0/0x6b @ 1
[ 35.036054] initcall caif_device_init+0x0/0x6b returned 0 after 3906 usecs
[ 35.044006] calling caif_sktinit_module+0x0/0x132 @ 1
[ 35.052129] NET: Registered protocol family 37
[ 35.056006] initcall caif_sktinit_module+0x0/0x132 returned 0 after 3906 usecs
[ 35.060005] calling dcbnl_init+0x0/0x2d @ 1
[ 35.068006] initcall dcbnl_init+0x0/0x2d returned 0 after 0 usecs
[ 35.072005] calling af_ieee802154_init+0x0/0x63 @ 1
[ 35.076037] NET: Registered protocol family 36
[ 35.080007] initcall af_ieee802154_init+0x0/0x63 returned 0 after 3906 usecs
[ 35.088005] calling init_dns_resolver+0x0/0xfb @ 1
[ 35.092004] Registering the dns_resolver key type
[ 35.100123] initcall init_dns_resolver+0x0/0xfb returned 0 after 7812 usecs
[ 35.104007] calling update_mp_table+0x0/0x567 @ 1
[ 35.112027] initcall update_mp_table+0x0/0x567 returned 0 after 0 usecs
[ 35.116006] calling lapic_insert_resource+0x0/0x35 @ 1
[ 35.124007] initcall lapic_insert_resource+0x0/0x35 returned 0 after 0 usecs
[ 35.128005] calling print_ipi_mode+0x0/0x26 @ 1
[ 35.132004] Using IPI No-Shortcut mode
[ 35.136006] initcall print_ipi_mode+0x0/0x26 returned 0 after 3906 usecs
[ 35.144006] calling init_lapic_nmi_sysfs+0x0/0x33 @ 1
[ 35.148006] initcall init_lapic_nmi_sysfs+0x0/0x33 returned 0 after 0 usecs
[ 35.156006] calling io_apic_bug_finalize+0x0/0x1a @ 1
[ 35.160006] initcall io_apic_bug_finalize+0x0/0x1a returned 0 after 0 usecs
[ 35.168006] calling check_early_ioremap_leak+0x0/0x5c @ 1
[ 35.172006] initcall check_early_ioremap_leak+0x0/0x5c returned 0 after 0 usecs
[ 35.180006] calling pat_memtype_list_init+0x0/0x2e @ 1
[ 35.188017] initcall pat_memtype_list_init+0x0/0x2e returned 0 after 0 usecs
[ 35.192006] calling init_oops_id+0x0/0x3f @ 1
[ 35.196012] initcall init_oops_id+0x0/0x3f returned 0 after 0 usecs
[ 35.204006] calling printk_late_init+0x0/0x44 @ 1
[ 35.208008] initcall printk_late_init+0x0/0x44 returned 0 after 0 usecs
[ 35.216022] calling pm_qos_power_init+0x0/0xae @ 1
[ 35.220012] device: 'cpu_dma_latency': device_add
[ 35.224282] PM: Adding info for No Bus:cpu_dma_latency
[ 35.232031] device: 'network_latency': device_add
[ 35.236114] PM: Adding info for No Bus:network_latency
[ 35.240024] device: 'network_throughput': device_add
[ 35.244114] PM: Adding info for No Bus:network_throughput
[ 35.252022] initcall pm_qos_power_init+0x0/0xae returned 0 after 31250 usecs
[ 35.260006] calling taskstats_init+0x0/0x7d @ 1
[ 35.264012] registered taskstats version 1
[ 35.268006] initcall taskstats_init+0x0/0x7d returned 0 after 3906 usecs
[ 35.272006] calling clear_boot_tracer+0x0/0x27 @ 1
[ 35.280008] initcall clear_boot_tracer+0x0/0x27 returned 0 after 0 usecs
[ 35.284005] calling event_trace_self_tests_init+0x0/0x39 @ 1
[ 35.292004] Running tests on trace events:
[ 35.296004] Testing event kfree_skb: OK
[ 35.316691] Testing event consume_skb: OK
[ 35.336608] Testing event skb_copy_datagram_iovec: OK
[ 35.356583] Testing event net_dev_xmit: OK
[ 35.376602] Testing event net_dev_queue: OK
[ 35.396587] Testing event netif_receive_skb: OK
[ 35.416597] Testing event netif_rx: OK
[ 35.436580] Testing event napi_poll: OK
[ 35.456599] Testing event scsi_dispatch_cmd_start: OK
[ 35.476585] Testing event scsi_dispatch_cmd_error: OK
[ 35.496587] Testing event scsi_dispatch_cmd_done: OK
[ 35.516596] Testing event scsi_dispatch_cmd_timeout: OK
[ 35.536580] Testing event scsi_eh_wakeup: OK
[ 35.556583] Testing event drm_vblank_event: OK
[ 35.576584] Testing event drm_vblank_event_queued: OK
[ 35.596585] Testing event drm_vblank_event_delivered: OK
[ 35.616614] Testing event lock_kernel: OK
[ 35.636580] Testing event unlock_kernel: OK
[ 35.656582] Testing event block_rq_abort: OK
[ 35.676583] Testing event block_rq_requeue: OK
[ 35.696585] Testing event block_rq_complete: OK
[ 35.716595] Testing event block_rq_insert: OK
[ 35.736580] Testing event block_rq_issue: OK
[ 35.756581] Testing event block_bio_bounce: OK
[ 35.776583] Testing event block_bio_complete: OK
[ 35.796568] Testing event block_bio_backmerge: OK
[ 35.816595] Testing event block_bio_frontmerge: OK
[ 35.836580] Testing event block_bio_queue: OK
[ 35.856581] Testing event block_getrq: OK
[ 35.876583] Testing event block_sleeprq: OK
[ 35.896584] Testing event block_plug: OK
[ 35.916596] Testing event block_unplug_timer: OK
[ 35.936580] Testing event block_unplug_io: OK
[ 35.956607] Testing event block_split: OK
[ 35.976582] Testing event block_remap: OK
[ 35.996602] Testing event block_rq_remap: OK
[ 36.016577] Testing event writeback_nothread: OK
[ 36.036578] Testing event writeback_queue: OK
[ 36.056580] Testing event writeback_exec: OK
[ 36.076583] Testing event writeback_pages_written: OK
[ 36.096583] Testing event writeback_nowork: OK
[ 36.116595] Testing event writeback_wake_thread: OK
[ 36.136578] Testing event writeback_wake_forker_thread: OK
[ 36.156582] Testing event writeback_bdi_register: OK
[ 36.176582] Testing event writeback_bdi_unregister: OK
[ 36.196584] Testing event writeback_thread_start: OK
[ 36.216595] Testing event writeback_thread_stop: OK
[ 36.236587] Testing event wbc_writeback_start: OK
[ 36.256580] Testing event wbc_writeback_written: OK
[ 36.276581] Testing event wbc_writeback_wait: OK
[ 36.296585] Testing event wbc_balance_dirty_start: OK
[ 36.316593] Testing event wbc_balance_dirty_written: OK
[ 36.336588] Testing event wbc_balance_dirty_wait: OK
[ 36.356579] Testing event wbc_writepage: OK
[ 36.376581] Testing event kmalloc: OK
[ 36.477101] Testing event kmem_cache_alloc: OK
[ 36.496583] Testing event kmalloc_node: OK
[ 36.516620] Testing event kmem_cache_alloc_node: OK
[ 36.536559] Testing event kfree: OK
[ 36.552584] Testing event kmem_cache_free: OK
[ 36.572586] Testing event mm_page_free_direct: OK
[ 36.592588] Testing event mm_pagevec_free: OK
[ 36.612580] Testing event mm_page_alloc: OK
[ 36.632583] Testing event mm_page_alloc_zone_locked: OK
[ 36.652583] Testing event mm_page_pcpu_drain: OK
[ 36.672586] Testing event mm_page_alloc_extfrag: OK
[ 36.692578] Testing event mm_vmscan_kswapd_sleep: OK
[ 36.712580] Testing event mm_vmscan_kswapd_wake: OK
[ 36.732582] Testing event mm_vmscan_wakeup_kswapd: OK
[ 36.752583] Testing event mm_vmscan_direct_reclaim_begin: OK
[ 36.772585] Testing event mm_vmscan_memcg_reclaim_begin: OK
[ 36.792562] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK
[ 36.812562] Testing event mm_vmscan_direct_reclaim_end: OK
[ 36.832582] Testing event mm_vmscan_memcg_reclaim_end: OK
[ 36.852567] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK
[ 36.872568] Testing event mm_vmscan_lru_isolate: OK
[ 36.892587] Testing event mm_vmscan_memcg_isolate: OK
[ 36.912563] Testing event mm_vmscan_writepage: OK
[ 36.932580] Testing event power_start: OK
[ 36.952643] Testing event power_frequency: OK
[ 36.972585] Testing event power_end: OK
[ 36.992604] Testing event clock_enable: OK
[ 37.012563] Testing event clock_disable: OK
[ 37.032563] Testing event clock_set_rate: OK
[ 37.052566] Testing event power_domain_target: OK
[ 37.072569] Testing event lock_acquire: OK
[ 37.092639] Testing event lock_release: OK
[ 37.112639] Testing event lock_contended: OK
[ 37.132581] Testing event lock_acquired: OK
[ 37.152633] Testing event workqueue_execute_start: OK
[ 37.172602] Testing event workqueue_execute_end: OK
[ 37.192603] Testing event signal_generate: OK
[ 37.212578] Testing event signal_deliver: OK
[ 37.232581] Testing event signal_overflow_fail: OK
[ 37.252582] Testing event signal_lose_info: OK
[ 37.272583] Testing event timer_init: OK
[ 37.292585] Testing event timer_start: OK
[ 37.312656] Testing event timer_expire_entry: OK
[ 37.332588] Testing event timer_expire_exit: OK
[ 37.352581] Testing event timer_cancel: OK
[ 37.372713] Testing event hrtimer_init: OK
[ 37.392585] Testing event hrtimer_start: OK
[ 37.412587] Testing event hrtimer_expire_entry: OK
[ 37.432579] Testing event hrtimer_expire_exit: OK
[ 37.452581] Testing event hrtimer_cancel: OK
[ 37.472644] Testing event itimer_state: OK
[ 37.492602] Testing event itimer_expire: OK
[ 37.512604] Testing event irq_handler_entry: OK
[ 37.532580] Testing event irq_handler_exit: OK
[ 37.552581] Testing event softirq_entry: OK
[ 37.572582] Testing event softirq_exit: OK
[ 37.592584] Testing event softirq_raise: OK
[ 37.612941] Testing event sched_kthread_stop: OK
[ 37.632580] Testing event sched_kthread_stop_ret: OK
[ 37.652582] Testing event sched_wakeup: OK
[ 37.672565] Testing event sched_wakeup_new: OK
[ 37.692557] Testing event sched_switch: OK
[ 37.712567] Testing event sched_migrate_task: OK
[ 37.732587] Testing event sched_process_free: OK
[ 37.752580] Testing event sched_process_exit: OK
[ 37.772581] Testing event sched_wait_task: OK
[ 37.792583] Testing event sched_process_wait: OK
[ 37.812585] Testing event sched_process_fork: OK
[ 37.832587] Testing event sched_stat_wait: OK
[ 37.852588] Testing event sched_stat_sleep: OK
[ 37.872581] Testing event sched_stat_iowait: OK
[ 37.892583] Testing event sched_stat_runtime: OK
[ 37.912586] Testing event sched_pi_setprio: OK
[ 37.932587] Testing event sys_enter: OK
[ 37.952598] Testing event sys_exit: OK
[ 37.972599] Running tests on trace event systems:
[ 37.976007] Testing event system skb: OK
[ 37.996725] Testing event system net: OK
[ 38.016762] Testing event system napi: OK
[ 38.036615] Testing event system scsi: OK
[ 38.056766] Testing event system drm: OK
[ 38.076707] Testing event system bkl: OK
[ 38.096638] Testing event system block: OK
[ 38.117367] Testing event system writeback: OK
[ 38.137352] Testing event system kmem: OK
[ 38.244779] Testing event system vmscan: OK
[ 38.264972] Testing event system power: OK
[ 38.284852] Testing event system lock: OK
[ 38.304853] Testing event system workqueue: OK
[ 38.324646] Testing event system signal: OK
[ 38.344726] Testing event system timer: OK
[ 38.365403] Testing event system irq: OK
[ 38.385144] Testing event system sched: OK
[ 38.405199] Testing event system raw_syscalls: OK
[ 38.424656] Running tests on all trace events:
[ 38.428006] Testing all events: OK
[ 38.604658] Running tests again, along with the function tracer
[ 38.608024] Running tests on trace events:
[ 38.612134] Testing event kfree_skb: OK
[ 38.633441] Testing event consume_skb: OK
[ 38.653288] Testing event skb_copy_datagram_iovec: OK
[ 38.673296] Testing event net_dev_xmit: OK
[ 38.693370] Testing event net_dev_queue: OK
[ 38.713292] Testing event netif_receive_skb: OK
[ 38.733295] Testing event netif_rx: OK
[ 38.753295] Testing event napi_poll: OK
[ 38.773367] Testing event scsi_dispatch_cmd_start: OK
[ 38.793290] Testing event scsi_dispatch_cmd_error: OK
[ 38.813302] Testing event scsi_dispatch_cmd_done: OK
[ 38.833293] Testing event scsi_dispatch_cmd_timeout: OK
[ 38.853296] Testing event scsi_eh_wakeup:
[ 38.872207] test-events used greatest stack depth: 6920 bytes left
[ 38.876528] OK
[ 38.880025] Testing event drm_vblank_event: OK
[ 38.901299] Testing event drm_vblank_event_queued: OK
[ 38.921290] Testing event drm_vblank_event_delivered: OK
[ 38.941369] Testing event lock_kernel: OK
[ 38.961294] Testing event unlock_kernel: OK
[ 38.981288] Testing event block_rq_abort: OK
[ 39.001297] Testing event block_rq_requeue: OK
[ 39.021289] Testing event block_rq_complete: OK
[ 39.041293] Testing event block_rq_insert: OK
[ 39.061295] Testing event block_rq_issue: OK
[ 39.081294] Testing event block_bio_bounce: OK
[ 39.101289] Testing event block_bio_complete: OK
[ 39.121211] Testing event block_bio_backmerge: OK
[ 39.141292] Testing event block_bio_frontmerge: OK
[ 39.161294] Testing event block_bio_queue: OK
[ 39.181295] Testing event block_getrq: OK
[ 39.201288] Testing event block_sleeprq: OK
[ 39.221290] Testing event block_plug: OK
[ 39.241292] Testing event block_unplug_timer: OK
[ 39.261293] Testing event block_unplug_io: OK
[ 39.281372] Testing event block_split: OK
[ 39.301287] Testing event block_remap: OK
[ 39.321367] Testing event block_rq_remap: OK
[ 39.341213] Testing event writeback_nothread: OK
[ 39.361293] Testing event writeback_queue: OK
[ 39.381295] Testing event writeback_exec: OK
[ 39.401289] Testing event writeback_pages_written: OK
[ 39.421297] Testing event writeback_nowork: OK
[ 39.441290] Testing event writeback_wake_thread: OK
[ 39.461294] Testing event writeback_wake_forker_thread: OK
[ 39.481295] Testing event writeback_bdi_register: OK
[ 39.501288] Testing event writeback_bdi_unregister: OK
[ 39.521287] Testing event writeback_thread_start: OK
[ 39.541291] Testing event writeback_thread_stop: OK
[ 39.561292] Testing event wbc_writeback_start: OK
[ 39.581286] Testing event wbc_writeback_written: OK
[ 39.601295] Testing event wbc_writeback_wait: OK
[ 39.621290] Testing event wbc_balance_dirty_start: OK
[ 39.641289] Testing event wbc_balance_dirty_written: OK
[ 39.661292] Testing event wbc_balance_dirty_wait: OK
[ 39.681292] Testing event wbc_writepage: OK
[ 39.701294] Testing event kmalloc: OK
[ 40.057226] Testing event kmem_cache_alloc: OK
[ 40.077287] Testing event kmalloc_node: OK
[ 40.097368] Testing event kmem_cache_alloc_node: OK
[ 40.117274] Testing event kfree: OK
[ 40.137292] Testing event kmem_cache_free: OK
[ 40.157293] Testing event mm_page_free_direct: OK
[ 40.177287] Testing event mm_pagevec_free: OK
[ 40.197297] Testing event mm_page_alloc: OK
[ 40.217353] Testing event mm_page_alloc_zone_locked: OK
[ 40.237294] Testing event mm_page_pcpu_drain: OK
[ 40.257287] Testing event mm_page_alloc_extfrag: OK
[ 40.277297] Testing event mm_vmscan_kswapd_sleep: OK
[ 40.297288] Testing event mm_vmscan_kswapd_wake: OK
[ 40.317352] Testing event mm_vmscan_wakeup_kswapd: OK
[ 40.337293] Testing event mm_vmscan_direct_reclaim_begin: OK
[ 40.357293] Testing event mm_vmscan_memcg_reclaim_begin: OK
[ 40.377219] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK
[ 40.401215] Testing event mm_vmscan_direct_reclaim_end: OK
[ 40.421294] Testing event mm_vmscan_memcg_reclaim_end: OK
[ 40.441211] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK
[ 40.465215] Testing event mm_vmscan_lru_isolate: OK
[ 40.485293] Testing event mm_vmscan_memcg_isolate: OK
[ 40.505219] Testing event mm_vmscan_writepage: OK
[ 40.525289] Testing event power_start: OK
[ 40.545523] Testing event power_frequency: OK
[ 40.565293] Testing event power_end: OK
[ 40.585373] Testing event clock_enable: OK
[ 40.605220] Testing event clock_disable: OK
[ 40.625220] Testing event clock_set_rate: OK
[ 40.645231] Testing event power_domain_target: OK
[ 40.665216] Testing event lock_acquire: OK
[ 40.685459] Testing event lock_release: OK
[ 40.705443] Testing event lock_contended: OK
[ 40.725299] Testing event lock_acquired: OK
[ 40.745421] Testing event workqueue_execute_start: OK
[ 40.765293] Testing event workqueue_execute_end: OK
[ 40.785303] Testing event signal_generate: OK
[ 40.805339] Testing event signal_deliver: OK
[ 40.825296] Testing event signal_overflow_fail: OK
[ 40.845290] Testing event signal_lose_info: OK
[ 40.865300] Testing event timer_init: OK
[ 40.885295] Testing event timer_start: OK
[ 40.905608] Testing event timer_expire_entry: OK
[ 40.925298] Testing event timer_expire_exit: OK
[ 40.945297] Testing event timer_cancel: OK
[ 40.965838] Testing event hrtimer_init: OK
[ 40.985293] Testing event hrtimer_start: OK
[ 41.005294] Testing event hrtimer_expire_entry: OK
[ 41.025295] Testing event hrtimer_expire_exit: OK
[ 41.045289] Testing event hrtimer_cancel: OK
[ 41.065524] Testing event itimer_state: OK
[ 41.085379] Testing event itimer_expire: OK
[ 41.105373] Testing event irq_handler_entry: OK
[ 41.125297] Testing event irq_handler_exit: OK
[ 41.145297] Testing event softirq_entry: OK
[ 41.165291] Testing event softirq_exit: OK
[ 41.185292] Testing event softirq_raise: OK
[ 41.206755] Testing event sched_kthread_stop: OK
[ 41.229300] Testing event sched_kthread_stop_ret: OK
[ 41.249291] Testing event sched_wakeup: OK
[ 41.269217] Testing event sched_wakeup_new: OK
[ 41.289210] Testing event sched_switch: OK
[ 41.309221] Testing event sched_migrate_task: OK
[ 41.329291] Testing event sched_process_free: OK
[ 41.349301] Testing event sched_process_exit: OK
[ 41.369293] Testing event sched_wait_task: OK
[ 41.389296] Testing event sched_process_wait: OK
[ 41.409296] Testing event sched_process_fork: OK
[ 41.429299] Testing event sched_stat_wait: OK
[ 41.449293] Testing event sched_stat_sleep: OK
[ 41.469294] Testing event sched_stat_iowait: OK
[ 41.489293] Testing event sched_stat_runtime: OK
[ 41.509296] Testing event sched_pi_setprio: OK
[ 41.529290] Testing event sys_enter: OK
[ 41.549302] Testing event sys_exit: OK
[ 41.569302] Running tests on trace event systems:
[ 41.572028] Testing event system skb: OK
[ 41.593855] Testing event system net: OK
[ 41.613960] Testing event system napi: OK
[ 41.633415] Testing event system scsi: OK
[ 41.654041] Testing event system drm: OK
[ 41.673775] Testing event system bkl: OK
[ 41.693516] Testing event system block: OK
[ 41.720625] Testing event system writeback: OK
[ 41.748519] Testing event system kmem: OK
[ 42.114329] Testing event system vmscan: OK
[ 42.138950] Testing event system power: OK
[ 42.162412] Testing event system lock: OK
[ 42.186206] Testing event system workqueue: OK
[ 42.205518] Testing event system signal: OK
[ 42.225874] Testing event system timer: OK
[ 42.252634] Testing event system irq: OK
[ 42.275528] Testing event system sched: OK
[ 42.299855] Testing event system raw_syscalls: OK
[ 42.321523] Running tests on all trace events:
[ 42.324027] Testing all events:
[ 245.668090] INFO: task swapper:1 blocked for more than 120 seconds.
[ 245.672051] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 245.676026] swapper D f6420b40 6544 1 0 0x00000000
[ 245.684051] f6437dac 00000046 f694aac0 f6420b40 f6438000 f6437d74 f6438294 f6438290
[ 245.692237] c2192ac0 c204e6c0 c2192ac0 c2192ac0 f6438290 00000000 f6438000 ff2ffa7d
[ 245.701068] 00000009 f6420b40 f6437e5c 7fffffff f6438000 f6437dfc f6437e5c 7fffffff
[ 245.709071] Call Trace:
[ 245.711551] [<c1a7f561>] schedule_timeout+0x1c/0x1e7
[ 245.712036] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 245.716037] [<c1027f2d>] ? sub_preempt_count+0x4/0x98
[ 245.720061] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 245.724036] [<c1027fb4>] ? sub_preempt_count+0x8b/0x98
[ 245.728036] [<c1a7e76b>] wait_for_common+0xc1/0x11a
[ 245.732062] [<c102de32>] ? default_wake_function+0x0/0x12
[ 245.736041] [<c1a7e863>] wait_for_completion+0x17/0x19
[ 245.740069] [<c10667a2>] __stop_cpus+0xdd/0x103
[ 245.744072] [<c1a7e6db>] ? wait_for_common+0x31/0x11a
[ 245.748040] [<c10665a4>] ? stop_machine_cpu_stop+0x0/0x9a
[ 245.752040] [<c106683d>] stop_cpus+0x2c/0x3f
[ 245.756069] [<c10668af>] __stop_machine+0x5f/0x67
[ 245.760186] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 245.764040] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 245.768071] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 245.772040] [<c10060fd>] text_poke_smp+0x3a/0x42
[ 245.776039] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 245.780098] [<c1005b9c>] arch_jump_label_transform+0x53/0x67
[ 245.784042] [<c104ef0d>] jump_label_update+0x49/0x98
[ 245.788041] [<c1070b33>] tracepoint_update_probe_range+0xec/0x116
[ 245.792070] [<c1070c74>] tracepoint_probe_unregister+0x51/0x6b
[ 245.796040] [<c107f3c0>] ftrace_event_reg+0x48/0x4c
[ 245.800041] [<c107e87c>] ftrace_event_enable_disable+0x39/0x8a
[ 245.804070] [<c107ebdf>] __ftrace_set_clr_event+0x91/0xbc
[ 245.808043] [<c1fbee72>] event_trace_self_tests+0x1cd/0x239
[ 245.812040] [<c1fbf025>] event_trace_self_tests_init+0x2a/0x39
[ 245.816230] [<c1fab282>] do_one_initcall+0x47/0x113
[ 245.820069] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 245.824040] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 245.828069] [<c1fab477>] kernel_init+0x10d/0x18e
[ 245.832040] [<c1fab36a>] ? kernel_init+0x0/0x18e
[ 245.836040] [<c1002c7e>] kernel_thread_helper+0x6/0x10
[ 245.840060] 5 locks held by swapper/1:
[ 245.844028] #0: (event_mutex){......}, at: [<c107eb6a>] __ftrace_set_clr_event+0x1c/0xbc
[ 245.852058] #1: (tracepoints_mutex){......}, at: [<c1070a6e>] tracepoint_update_probe_range+0x27/0x116
[ 245.860195] #2: (jump_label_mutex){......}, at: [<c104eee3>] jump_label_update+0x1f/0x98
[ 245.872028] #3: (text_mutex){......}, at: [<c1005b8d>] arch_jump_label_transform+0x44/0x67
[ 245.880028] #4: (stop_cpus_mutex){......}, at: [<c1066831>] stop_cpus+0x20/0x3f
[ 324.576593] kworker/u:0 used greatest stack depth: 6484 bytes left
[ 365.888113] INFO: task swapper:1 blocked for more than 120 seconds.
[ 365.892050] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 365.896026] swapper D f6420b40 6544 1 0 0x00000000
[ 365.904026] f6437dac 00000046 f694aac0 f6420b40 f6438000 f6437d74 f6438294 f6438290
[ 365.912254] c2192ac0 c204e6c0 c2192ac0 c2192ac0 f6438290 00000000 f6438000 ff2ffa7d
[ 365.921041] 00000009 f6420b40 f6437e5c 7fffffff f6438000 f6437dfc f6437e5c 7fffffff
[ 365.929044] Call Trace:
[ 365.931527] [<c1a7f561>] schedule_timeout+0x1c/0x1e7
[ 365.932060] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 365.936061] [<c1027f2d>] ? sub_preempt_count+0x4/0x98
[ 365.940036] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 365.944036] [<c1027fb4>] ? sub_preempt_count+0x8b/0x98
[ 365.948061] [<c1a7e76b>] wait_for_common+0xc1/0x11a
[ 365.952036] [<c102de32>] ? default_wake_function+0x0/0x12
[ 365.956035] [<c1a7e863>] wait_for_completion+0x17/0x19
[ 365.960060] [<c10667a2>] __stop_cpus+0xdd/0x103
[ 365.964038] [<c1a7e6db>] ? wait_for_common+0x31/0x11a
[ 365.968035] [<c10665a4>] ? stop_machine_cpu_stop+0x0/0x9a
[ 365.972085] [<c106683d>] stop_cpus+0x2c/0x3f
[ 365.976035] [<c10668af>] __stop_machine+0x5f/0x67
[ 365.980035] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 365.984061] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 365.988036] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 365.992035] [<c10060fd>] text_poke_smp+0x3a/0x42
[ 365.996069] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 366.000324] [<c1005b9c>] arch_jump_label_transform+0x53/0x67
[ 366.004042] [<c104ef0d>] jump_label_update+0x49/0x98
[ 366.008070] [<c1070b33>] tracepoint_update_probe_range+0xec/0x116
[ 366.012069] [<c1070c74>] tracepoint_probe_unregister+0x51/0x6b
[ 366.016040] [<c107f3c0>] ftrace_event_reg+0x48/0x4c
[ 366.020070] [<c107e87c>] ftrace_event_enable_disable+0x39/0x8a
[ 366.024041] [<c107ebdf>] __ftrace_set_clr_event+0x91/0xbc
[ 366.028042] [<c1fbee72>] event_trace_self_tests+0x1cd/0x239
[ 366.032069] [<c1fbf025>] event_trace_self_tests_init+0x2a/0x39
[ 366.036040] [<c1fab282>] do_one_initcall+0x47/0x113
[ 366.040040] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 366.044069] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 366.048040] [<c1fab477>] kernel_init+0x10d/0x18e
[ 366.052068] [<c1fab36a>] ? kernel_init+0x0/0x18e
[ 366.056069] [<c1002c7e>] kernel_thread_helper+0x6/0x10
[ 366.060031] 5 locks held by swapper/1:
[ 366.064029] #0: (event_mutex){......}, at: [<c107eb6a>] __ftrace_set_clr_event+0x1c/0xbc
[ 366.072029] #1: (tracepoints_mutex){......}, at: [<c1070a6e>] tracepoint_update_probe_range+0x27/0x116
[ 366.080058] #2: (jump_label_mutex){......}, at: [<c104eee3>] jump_label_update+0x1f/0x98
[ 366.092086] #3: (text_mutex){......}, at: [<c1005b8d>] arch_jump_label_transform+0x44/0x67
[ 366.100166] #4: (stop_cpus_mutex){......}, at: [<c1066831>] stop_cpus+0x20/0x3f
[ 486.108087] INFO: task swapper:1 blocked for more than 120 seconds.
[ 486.112027] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 486.116051] swapper D f6420b40 6544 1 0 0x00000000
[ 486.124026] f6437dac 00000046 f694aac0 f6420b40 f6438000 f6437d74 f6438294 f6438290
[ 486.132232] c2192ac0 c204e6c0 c2192ac0 c2192ac0 f6438290 00000000 f6438000 ff2ffa7d
[ 486.140411] 00000009 f6420b40 f6437e5c 7fffffff f6438000 f6437dfc f6437e5c 7fffffff
[ 486.149040] Call Trace:
[ 486.151516] [<c1a7f561>] schedule_timeout+0x1c/0x1e7
[ 486.152061] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 486.156035] [<c1027f2d>] ? sub_preempt_count+0x4/0x98
[ 486.160035] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 486.164061] [<c1027fb4>] ? sub_preempt_count+0x8b/0x98
[ 486.168035] [<c1a7e76b>] wait_for_common+0xc1/0x11a
[ 486.172036] [<c102de32>] ? default_wake_function+0x0/0x12
[ 486.176061] [<c1a7e863>] wait_for_completion+0x17/0x19
[ 486.180060] [<c10667a2>] __stop_cpus+0xdd/0x103
[ 486.184037] [<c1a7e6db>] ? wait_for_common+0x31/0x11a
[ 486.188060] [<c10665a4>] ? stop_machine_cpu_stop+0x0/0x9a
[ 486.192035] [<c106683d>] stop_cpus+0x2c/0x3f
[ 486.196035] [<c10668af>] __stop_machine+0x5f/0x67
[ 486.200060] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 486.204035] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 486.208041] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 486.212070] [<c10060fd>] text_poke_smp+0x3a/0x42
[ 486.216039] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 486.220070] [<c1005b9c>] arch_jump_label_transform+0x53/0x67
[ 486.224070] [<c104ef0d>] jump_label_update+0x49/0x98
[ 486.228041] [<c1070b33>] tracepoint_update_probe_range+0xec/0x116
[ 486.232041] [<c1070c74>] tracepoint_probe_unregister+0x51/0x6b
[ 486.236069] [<c107f3c0>] ftrace_event_reg+0x48/0x4c
[ 486.240179] [<c107e87c>] ftrace_event_enable_disable+0x39/0x8a
[ 486.244041] [<c107ebdf>] __ftrace_set_clr_event+0x91/0xbc
[ 486.248071] [<c1fbee72>] event_trace_self_tests+0x1cd/0x239
[ 486.252041] [<c1fbf025>] event_trace_self_tests_init+0x2a/0x39
[ 486.256040] [<c1fab282>] do_one_initcall+0x47/0x113
[ 486.260098] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 486.264040] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 486.268041] [<c1fab477>] kernel_init+0x10d/0x18e
[ 486.272069] [<c1fab36a>] ? kernel_init+0x0/0x18e
[ 486.276040] [<c1002c7e>] kernel_thread_helper+0x6/0x10
[ 486.280031] 5 locks held by swapper/1:
[ 486.284058] #0: (event_mutex){......}, at: [<c107eb6a>] __ftrace_set_clr_event+0x1c/0xbc
[ 486.292029] #1: (tracepoints_mutex){......}, at: [<c1070a6e>] tracepoint_update_probe_range+0x27/0x116
[ 486.300057] #2: (jump_label_mutex){......}, at: [<c104eee3>] jump_label_update+0x1f/0x98
[ 486.311742] #3: (text_mutex){......}, at: [<c1005b8d>] arch_jump_label_transform+0x44/0x67
[ 486.320058] #4: (stop_cpus_mutex){......}, at: [<c1066831>] stop_cpus+0x20/0x3f
[ 606.328087] INFO: task swapper:1 blocked for more than 120 seconds.
[ 606.332051] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 606.336026] swapper D f6420b40 6544 1 0 0x00000000
[ 606.344051] f6437dac 00000046 f694aac0 f6420b40 f6438000 f6437d74 f6438294 f6438290
[ 606.352229] c2192ac0 c204e6c0 c2192ac0 c2192ac0 f6438290 00000000 f6438000 ff2ffa7d
[ 606.360229] 00000009 f6420b40 f6437e5c 7fffffff f6438000 f6437dfc f6437e5c 7fffffff
[ 606.368255] Call Trace:
[ 606.370731] [<c1a7f561>] schedule_timeout+0x1c/0x1e7
[ 606.372035] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 606.376035] [<c1027f2d>] ? sub_preempt_count+0x4/0x98
[ 606.380187] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 606.384036] [<c1027fb4>] ? sub_preempt_count+0x8b/0x98
[ 606.388035] [<c1a7e76b>] wait_for_common+0xc1/0x11a
[ 606.392062] [<c102de32>] ? default_wake_function+0x0/0x12
[ 606.396061] [<c1a7e863>] wait_for_completion+0x17/0x19
[ 606.400034] [<c10667a2>] __stop_cpus+0xdd/0x103
[ 606.404063] [<c1a7e6db>] ? wait_for_common+0x31/0x11a
[ 606.408035] [<c10665a4>] ? stop_machine_cpu_stop+0x0/0x9a
[ 606.412035] [<c106683d>] stop_cpus+0x2c/0x3f
[ 606.416061] [<c10668af>] __stop_machine+0x5f/0x67
[ 606.420035] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 606.424035] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 606.428070] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 606.432040] [<c10060fd>] text_poke_smp+0x3a/0x42
[ 606.436068] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 606.440070] [<c1005b9c>] arch_jump_label_transform+0x53/0x67
[ 606.444041] [<c104ef0d>] jump_label_update+0x49/0x98
[ 606.448041] [<c1070b33>] tracepoint_update_probe_range+0xec/0x116
[ 606.452070] [<c1070c74>] tracepoint_probe_unregister+0x51/0x6b
[ 606.456040] [<c107f3c0>] ftrace_event_reg+0x48/0x4c
[ 606.460041] [<c107e87c>] ftrace_event_enable_disable+0x39/0x8a
[ 606.464070] [<c107ebdf>] __ftrace_set_clr_event+0x91/0xbc
[ 606.468042] [<c1fbee72>] event_trace_self_tests+0x1cd/0x239
[ 606.472041] [<c1fbf025>] event_trace_self_tests_init+0x2a/0x39
[ 606.476098] [<c1fab282>] do_one_initcall+0x47/0x113
[ 606.480179] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 606.484040] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 606.488070] [<c1fab477>] kernel_init+0x10d/0x18e
[ 606.492040] [<c1fab36a>] ? kernel_init+0x0/0x18e
[ 606.496040] [<c1002c7e>] kernel_thread_helper+0x6/0x10
[ 606.500060] 5 locks held by swapper/1:
[ 606.504029] #0: (event_mutex){......}, at: [<c107eb6a>] __ftrace_set_clr_event+0x1c/0xbc
[ 606.512058] #1: (tracepoints_mutex){......}, at: [<c1070a6e>] tracepoint_update_probe_range+0x27/0x116
[ 606.520029] #2: (jump_label_mutex){......}, at: [<c104eee3>] jump_label_update+0x1f/0x98
[ 606.531719] #3: (text_mutex){......}, at: [<c1005b8d>] arch_jump_label_transform+0x44/0x67
[ 606.540029] #4: (stop_cpus_mutex){......}, at: [<c1066831>] stop_cpus+0x20/0x3f
[ 726.548112] INFO: task swapper:1 blocked for more than 120 seconds.
[ 726.552025] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 726.556026] swapper D f6420b40 6544 1 0 0x00000000
[ 726.564026] f6437dac 00000046 f694aac0 f6420b40 f6438000 f6437d74 f6438294 f6438290
[ 726.572254] c2192ac0 c204e6c0 c2192ac0 c2192ac0 f6438290 00000000 f6438000 ff2ffa7d
[ 726.580256] 00000009 f6420b40 f6437e5c 7fffffff f6438000 f6437dfc f6437e5c 7fffffff
[ 726.589045] Call Trace:
[ 726.591527] [<c1a7f561>] schedule_timeout+0x1c/0x1e7
[ 726.592035] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 726.596061] [<c1027f2d>] ? sub_preempt_count+0x4/0x98
[ 726.600036] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 726.604036] [<c1027fb4>] ? sub_preempt_count+0x8b/0x98
[ 726.608061] [<c1a7e76b>] wait_for_common+0xc1/0x11a
[ 726.612036] [<c102de32>] ? default_wake_function+0x0/0x12
[ 726.616036] [<c1a7e863>] wait_for_completion+0x17/0x19
[ 726.620211] [<c10667a2>] __stop_cpus+0xdd/0x103
[ 726.624038] [<c1a7e6db>] ? wait_for_common+0x31/0x11a
[ 726.628035] [<c10665a4>] ? stop_machine_cpu_stop+0x0/0x9a
[ 726.632060] [<c106683d>] stop_cpus+0x2c/0x3f
[ 726.636035] [<c10668af>] __stop_machine+0x5f/0x67
[ 726.640035] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 726.644061] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 726.648041] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 726.652040] [<c10060fd>] text_poke_smp+0x3a/0x42
[ 726.656069] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 726.660070] [<c1005b9c>] arch_jump_label_transform+0x53/0x67
[ 726.664041] [<c104ef0d>] jump_label_update+0x49/0x98
[ 726.668070] [<c1070b33>] tracepoint_update_probe_range+0xec/0x116
[ 726.672041] [<c1070c74>] tracepoint_probe_unregister+0x51/0x6b
[ 726.676040] [<c107f3c0>] ftrace_event_reg+0x48/0x4c
[ 726.680070] [<c107e87c>] ftrace_event_enable_disable+0x39/0x8a
[ 726.684041] [<c107ebdf>] __ftrace_set_clr_event+0x91/0xbc
[ 726.688041] [<c1fbee72>] event_trace_self_tests+0x1cd/0x239
[ 726.692070] [<c1fbf025>] event_trace_self_tests_init+0x2a/0x39
[ 726.696040] [<c1fab282>] do_one_initcall+0x47/0x113
[ 726.700069] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 726.704070] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 726.708040] [<c1fab477>] kernel_init+0x10d/0x18e
[ 726.712040] [<c1fab36a>] ? kernel_init+0x0/0x18e
[ 726.716069] [<c1002c7e>] kernel_thread_helper+0x6/0x10
[ 726.720170] 5 locks held by swapper/1:
[ 726.724029] #0: (event_mutex){......}, at: [<c107eb6a>] __ftrace_set_clr_event+0x1c/0xbc
[ 726.732029] #1: (tracepoints_mutex){......}, at: [<c1070a6e>] tracepoint_update_probe_range+0x27/0x116
[ 726.740086] #2: (jump_label_mutex){......}, at: [<c104eee3>] jump_label_update+0x1f/0x98
[ 726.751716] #3: (text_mutex){......}, at: [<c1005b8d>] arch_jump_label_transform+0x44/0x67
[ 726.760029] #4: (stop_cpus_mutex){......}, at: [<c1066831>] stop_cpus+0x20/0x3f
[ 846.768087] INFO: task swapper:1 blocked for more than 120 seconds.
[ 846.772025] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 846.776051] swapper D f6420b40 6544 1 0 0x00000000
[ 846.784026] f6437dac 00000046 f694aac0 f6420b40 f6438000 f6437d74 f6438294 f6438290
[ 846.792228] c2192ac0 c204e6c0 c2192ac0 c2192ac0 f6438290 00000000 f6438000 ff2ffa7d
[ 846.800257] 00000009 f6420b40 f6437e5c 7fffffff f6438000 f6437dfc f6437e5c 7fffffff
[ 846.808228] Call Trace:
[ 846.810704] [<c1a7f561>] schedule_timeout+0x1c/0x1e7
[ 846.812061] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 846.816035] [<c1027f2d>] ? sub_preempt_count+0x4/0x98
[ 846.820061] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 846.824061] [<c1027fb4>] ? sub_preempt_count+0x8b/0x98
[ 846.828035] [<c1a7e76b>] wait_for_common+0xc1/0x11a
[ 846.832036] [<c102de32>] ? default_wake_function+0x0/0x12
[ 846.836061] [<c1a7e863>] wait_for_completion+0x17/0x19
[ 846.840034] [<c10667a2>] __stop_cpus+0xdd/0x103
[ 846.844037] [<c1a7e6db>] ? wait_for_common+0x31/0x11a
[ 846.848060] [<c10665a4>] ? stop_machine_cpu_stop+0x0/0x9a
[ 846.852035] [<c106683d>] stop_cpus+0x2c/0x3f
[ 846.856036] [<c10668af>] __stop_machine+0x5f/0x67
[ 846.860211] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 846.864035] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 846.868035] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 846.872061] [<c10060fd>] text_poke_smp+0x3a/0x42
[ 846.876039] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 846.880041] [<c1005b9c>] arch_jump_label_transform+0x53/0x67
[ 846.884070] [<c104ef0d>] jump_label_update+0x49/0x98
[ 846.888041] [<c1070b33>] tracepoint_update_probe_range+0xec/0x116
[ 846.892041] [<c1070c74>] tracepoint_probe_unregister+0x51/0x6b
[ 846.896069] [<c107f3c0>] ftrace_event_reg+0x48/0x4c
[ 846.900069] [<c107e87c>] ftrace_event_enable_disable+0x39/0x8a
[ 846.904041] [<c107ebdf>] __ftrace_set_clr_event+0x91/0xbc
[ 846.908071] [<c1fbee72>] event_trace_self_tests+0x1cd/0x239
[ 846.912041] [<c1fbf025>] event_trace_self_tests_init+0x2a/0x39
[ 846.916040] [<c1fab282>] do_one_initcall+0x47/0x113
[ 846.920069] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 846.928045] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 846.932066] [<c1fab477>] kernel_init+0x10d/0x18e
[ 846.936040] [<c1fab36a>] ? kernel_init+0x0/0x18e
[ 846.940069] [<c1002c7e>] kernel_thread_helper+0x6/0x10
[ 846.944060] 5 locks held by swapper/1:
[ 846.948029] #0: (event_mutex){......}, at: [<c107eb6a>] __ftrace_set_clr_event+0x1c/0xbc
[ 846.956058] #1: (tracepoints_mutex){......}, at: [<c1070a6e>] tracepoint_update_probe_range+0x27/0x116
[ 846.964029] #2: (jump_label_mutex){......}, at: [<c104eee3>] jump_label_update+0x1f/0x98
[ 846.975709] #3: (text_mutex){......}, at: [<c1005b8d>] arch_jump_label_transform+0x44/0x67
[ 846.984029] #4: (stop_cpus_mutex){......}, at: [<c1066831>] stop_cpus+0x20/0x3f
[ 966.992112] INFO: task swapper:1 blocked for more than 120 seconds.
[ 966.996025] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 967.000275] swapper D f6420b40 6544 1 0 0x00000000
[ 967.008026] f6437dac 00000046 f694aac0 f6420b40 f6438000 f6437d74 f6438294 f6438290
[ 967.017042] c2192ac0 c204e6c0 c2192ac0 c2192ac0 f6438290 00000000 f6438000 ff2ffa7d
[ 967.025044] 00000009 f6420b40 f6437e5c 7fffffff f6438000 f6437dfc f6437e5c 7fffffff
[ 967.033044] Call Trace:
[ 967.035520] [<c1a7f561>] schedule_timeout+0x1c/0x1e7
[ 967.036035] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 967.040061] [<c1027f2d>] ? sub_preempt_count+0x4/0x98
[ 967.044036] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 967.048036] [<c1027fb4>] ? sub_preempt_count+0x8b/0x98
[ 967.052061] [<c1a7e76b>] wait_for_common+0xc1/0x11a
[ 967.056036] [<c102de32>] ? default_wake_function+0x0/0x12
[ 967.060061] [<c1a7e863>] wait_for_completion+0x17/0x19
[ 967.064060] [<c10667a2>] __stop_cpus+0xdd/0x103
[ 967.068038] [<c1a7e6db>] ? wait_for_common+0x31/0x11a
[ 967.072035] [<c10665a4>] ? stop_machine_cpu_stop+0x0/0x9a
[ 967.076060] [<c106683d>] stop_cpus+0x2c/0x3f
[ 967.080035] [<c10668af>] __stop_machine+0x5f/0x67
[ 967.084035] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 967.088061] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 967.092041] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 967.096040] [<c10060fd>] text_poke_smp+0x3a/0x42
[ 967.100236] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 967.104041] [<c1005b9c>] arch_jump_label_transform+0x53/0x67
[ 967.108041] [<c104ef0d>] jump_label_update+0x49/0x98
[ 967.112070] [<c1070b33>] tracepoint_update_probe_range+0xec/0x116
[ 967.116041] [<c1070c74>] tracepoint_probe_unregister+0x51/0x6b
[ 967.120040] [<c107f3c0>] ftrace_event_reg+0x48/0x4c
[ 967.124070] [<c107e87c>] ftrace_event_enable_disable+0x39/0x8a
[ 967.128041] [<c107ebdf>] __ftrace_set_clr_event+0x91/0xbc
[ 967.132042] [<c1fbee72>] event_trace_self_tests+0x1cd/0x239
[ 967.136070] [<c1fbf025>] event_trace_self_tests_init+0x2a/0x39
[ 967.140069] [<c1fab282>] do_one_initcall+0x47/0x113
[ 967.144040] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 967.148070] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 967.152041] [<c1fab477>] kernel_init+0x10d/0x18e
[ 967.156040] [<c1fab36a>] ? kernel_init+0x0/0x18e
[ 967.164032] [<c1002c7e>] kernel_thread_helper+0x6/0x10
[ 967.168031] 5 locks held by swapper/1:
[ 967.172058] #0: (event_mutex){......}, at: [<c107eb6a>] __ftrace_set_clr_event+0x1c/0xbc
[ 967.180057] #1: (tracepoints_mutex){......}, at: [<c1070a6e>] tracepoint_update_probe_range+0x27/0x116
[ 967.188029] #2: (jump_label_mutex){......}, at: [<c104eee3>] jump_label_update+0x1f/0x98
[ 967.200158] #3: (text_mutex){......}, at: [<c1005b8d>] arch_jump_label_transform+0x44/0x67
[ 967.208058] #4: (stop_cpus_mutex){......}, at: [<c1066831>] stop_cpus+0x20/0x3f
[ 1087.216087] INFO: task swapper:1 blocked for more than 120 seconds.
[ 1087.220076] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1087.224026] swapper D f6420b40 6544 1 0 0x00000000
[ 1087.232051] f6437dac 00000046 f694aac0 f6420b40 f6438000 f6437d74 f6438294 f6438290
[ 1087.240358] c2192ac0 c204e6c0 c2192ac0 c2192ac0 f6438290 00000000 f6438000 ff2ffa7d
[ 1087.249034] 00000009 f6420b40 f6437e5c 7fffffff f6438000 f6437dfc f6437e5c 7fffffff
[ 1087.257071] Call Trace:
[ 1087.259553] [<c1a7f561>] schedule_timeout+0x1c/0x1e7
[ 1087.260060] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 1087.264035] [<c1027f2d>] ? sub_preempt_count+0x4/0x98
[ 1087.268061] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 1087.272037] [<c1027fb4>] ? sub_preempt_count+0x8b/0x98
[ 1087.276035] [<c1a7e76b>] wait_for_common+0xc1/0x11a
[ 1087.280061] [<c102de32>] ? default_wake_function+0x0/0x12
[ 1087.284036] [<c1a7e863>] wait_for_completion+0x17/0x19
[ 1087.288034] [<c10667a2>] __stop_cpus+0xdd/0x103
[ 1087.292063] [<c1a7e6db>] ? wait_for_common+0x31/0x11a
[ 1087.296035] [<c10665a4>] ? stop_machine_cpu_stop+0x0/0x9a
[ 1087.300060] [<c106683d>] stop_cpus+0x2c/0x3f
[ 1087.304060] [<c10668af>] __stop_machine+0x5f/0x67
[ 1087.308035] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 1087.312035] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 1087.316070] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 1087.320040] [<c10060fd>] text_poke_smp+0x3a/0x42
[ 1087.324039] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 1087.328070] [<c1005b9c>] arch_jump_label_transform+0x53/0x67
[ 1087.332041] [<c104ef0d>] jump_label_update+0x49/0x98
[ 1087.336041] [<c1070b33>] tracepoint_update_probe_range+0xec/0x116
[ 1087.340237] [<c1070c74>] tracepoint_probe_unregister+0x51/0x6b
[ 1087.344040] [<c107f3c0>] ftrace_event_reg+0x48/0x4c
[ 1087.348041] [<c107e87c>] ftrace_event_enable_disable+0x39/0x8a
[ 1087.352070] [<c107ebdf>] __ftrace_set_clr_event+0x91/0xbc
[ 1087.356042] [<c1fbee72>] event_trace_self_tests+0x1cd/0x239
[ 1087.360041] [<c1fbf025>] event_trace_self_tests_init+0x2a/0x39
[ 1087.364070] [<c1fab282>] do_one_initcall+0x47/0x113
[ 1087.368040] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 1087.372040] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 1087.376070] [<c1fab477>] kernel_init+0x10d/0x18e
[ 1087.380069] [<c1fab36a>] ? kernel_init+0x0/0x18e
[ 1087.384040] [<c1002c7e>] kernel_thread_helper+0x6/0x10
[ 1087.388060] 5 locks held by swapper/1:
[ 1087.392029] #0: (event_mutex){......}, at: [<c107eb6a>] __ftrace_set_clr_event+0x1c/0xbc
[ 1087.400058] #1: (tracepoints_mutex){......}, at: [<c1070a6e>] tracepoint_update_probe_range+0x27/0x116
[ 1087.408029] #2: (jump_label_mutex){......}, at: [<c104eee3>] jump_label_update+0x1f/0x98
[ 1087.419709] #3: (text_mutex){......}, at: [<c1005b8d>] arch_jump_label_transform+0x44/0x67
[ 1087.428029] #4: (stop_cpus_mutex){......}, at: [<c1066831>] stop_cpus+0x20/0x3f
[ 1207.436112] INFO: task swapper:1 blocked for more than 120 seconds.
[ 1207.440025] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1207.444026] swapper D f6420b40 6544 1 0 0x00000000
[ 1207.452025] f6437dac 00000046 f694aac0 f6420b40 f6438000 f6437d74 f6438294 f6438290
[ 1207.460254] c2192ac0 c204e6c0 c2192ac0 c2192ac0 f6438290 00000000 f6438000 ff2ffa7d
[ 1207.468228] 00000009 f6420b40 f6437e5c 7fffffff f6438000 f6437dfc f6437e5c 7fffffff
[ 1207.477044] Call Trace:
[ 1207.479519] [<c1a7f561>] schedule_timeout+0x1c/0x1e7
[ 1207.480161] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 1207.484061] [<c1027f2d>] ? sub_preempt_count+0x4/0x98
[ 1207.488061] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 1207.492036] [<c1027fb4>] ? sub_preempt_count+0x8b/0x98
[ 1207.496061] [<c1a7e76b>] wait_for_common+0xc1/0x11a
[ 1207.500036] [<c102de32>] ? default_wake_function+0x0/0x12
[ 1207.504036] [<c1a7e863>] wait_for_completion+0x17/0x19
[ 1207.508060] [<c10667a2>] __stop_cpus+0xdd/0x103
[ 1207.512037] [<c1a7e6db>] ? wait_for_common+0x31/0x11a
[ 1207.516035] [<c10665a4>] ? stop_machine_cpu_stop+0x0/0x9a
[ 1207.520060] [<c106683d>] stop_cpus+0x2c/0x3f
[ 1207.524035] [<c10668af>] __stop_machine+0x5f/0x67
[ 1207.528060] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 1207.532061] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 1207.536041] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 1207.540040] [<c10060fd>] text_poke_smp+0x3a/0x42
[ 1207.544069] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 1207.548041] [<c1005b9c>] arch_jump_label_transform+0x53/0x67
[ 1207.552041] [<c104ef0d>] jump_label_update+0x49/0x98
[ 1207.556070] [<c1070b33>] tracepoint_update_probe_range+0xec/0x116
[ 1207.560041] [<c1070c74>] tracepoint_probe_unregister+0x51/0x6b
[ 1207.564040] [<c107f3c0>] ftrace_event_reg+0x48/0x4c
[ 1207.568098] [<c107e87c>] ftrace_event_enable_disable+0x39/0x8a
[ 1207.572041] [<c107ebdf>] __ftrace_set_clr_event+0x91/0xbc
[ 1207.576042] [<c1fbee72>] event_trace_self_tests+0x1cd/0x239
[ 1207.580208] [<c1fbf025>] event_trace_self_tests_init+0x2a/0x39
[ 1207.584040] [<c1fab282>] do_one_initcall+0x47/0x113
[ 1207.588040] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 1207.592070] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 1207.596041] [<c1fab477>] kernel_init+0x10d/0x18e
[ 1207.600040] [<c1fab36a>] ? kernel_init+0x0/0x18e
[ 1207.604069] [<c1002c7e>] kernel_thread_helper+0x6/0x10
[ 1207.608060] 5 locks held by swapper/1:
[ 1207.612029] #0: (event_mutex){......}, at: [<c107eb6a>] __ftrace_set_clr_event+0x1c/0xbc
[ 1207.620029] #1: (tracepoints_mutex){......}, at: [<c1070a6e>] tracepoint_update_probe_range+0x27/0x116
[ 1207.628058] #2: (jump_label_mutex){......}, at: [<c104eee3>] jump_label_update+0x1f/0x98
[ 1207.639716] #3: (text_mutex){......}, at: [<c1005b8d>] arch_jump_label_transform+0x44/0x67
[ 1207.648057] #4: (stop_cpus_mutex){......}, at: [<c1066831>] stop_cpus+0x20/0x3f
[ 1327.656087] INFO: task swapper:1 blocked for more than 120 seconds.
[ 1327.660050] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1327.664051] swapper D f6420b40 6544 1 0 0x00000000
[ 1327.672026] f6437dac 00000046 f694aac0 f6420b40 f6438000 f6437d74 f6438294 f6438290
[ 1327.680229] c2192ac0 c204e6c0 c2192ac0 c2192ac0 f6438290 00000000 f6438000 ff2ffa7d
[ 1327.688257] 00000009 f6420b40 f6437e5c 7fffffff f6438000 f6437dfc f6437e5c 7fffffff
[ 1327.697044] Call Trace:
[ 1327.699526] [<c1a7f561>] schedule_timeout+0x1c/0x1e7
[ 1327.700086] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 1327.704035] [<c1027f2d>] ? sub_preempt_count+0x4/0x98
[ 1327.708035] [<c1a818b6>] ? _raw_spin_unlock_irq+0x2d/0x43
[ 1327.712062] [<c1027fb4>] ? sub_preempt_count+0x8b/0x98
[ 1327.716035] [<c1a7e76b>] wait_for_common+0xc1/0x11a
[ 1327.720162] [<c102de32>] ? default_wake_function+0x0/0x12
[ 1327.724061] [<c1a7e863>] wait_for_completion+0x17/0x19
[ 1327.728034] [<c10667a2>] __stop_cpus+0xdd/0x103
[ 1327.732037] [<c1a7e6db>] ? wait_for_common+0x31/0x11a
[ 1327.736060] [<c10665a4>] ? stop_machine_cpu_stop+0x0/0x9a
[ 1327.740060] [<c106683d>] stop_cpus+0x2c/0x3f
[ 1327.744035] [<c10668af>] __stop_machine+0x5f/0x67
[ 1327.748060] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 1327.752035] [<c1006240>] ? stop_machine_text_poke+0x0/0x43
[ 1327.756035] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 1327.760061] [<c10060fd>] text_poke_smp+0x3a/0x42
[ 1327.764039] [<c19f0a73>] ? cfdgml_create+0x2b/0xde
[ 1327.768041] [<c1005b9c>] arch_jump_label_transform+0x53/0x67
[ 1327.772070] [<c104ef0d>] jump_label_update+0x49/0x98
[ 1327.776041] [<c1070b33>] tracepoint_update_probe_range+0xec/0x116
[ 1327.780070] [<c1070c74>] tracepoint_probe_unregister+0x51/0x6b
[ 1327.784069] [<c107f3c0>] ftrace_event_reg+0x48/0x4c
[ 1327.788040] [<c107e87c>] ftrace_event_enable_disable+0x39/0x8a
[ 1327.792041] [<c107ebdf>] __ftrace_set_clr_event+0x91/0xbc
[ 1327.796071] [<c1fbee72>] event_trace_self_tests+0x1cd/0x239
[ 1327.800041] [<c1fbf025>] event_trace_self_tests_init+0x2a/0x39
[ 1327.804040] [<c1fab282>] do_one_initcall+0x47/0x113
[ 1327.808069] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 1327.812040] [<c1fbeffb>] ? event_trace_self_tests_init+0x0/0x39
[ 1327.816041] [<c1fab477>] kernel_init+0x10d/0x18e
[ 1327.820236] [<c1fab36a>] ? kernel_init+0x0/0x18e
[ 1327.824040] [<c1002c7e>] kernel_thread_helper+0x6/0x10
[ 1327.828031] 5 locks held by swapper/1:
[ 1327.832058] #0: (event_mutex){......}, at: [<c107eb6a>] __ftrace_set_clr_event+0x1c/0xbc
[ 1327.840029] #1: (tracepoints_mutex){......}, at: [<c1070a6e>] tracepoint_update_probe_range+0x27/0x116
[ 1327.848029] #2: (jump_label_mutex){......}, at: [<c104eee3>] jump_label_update+0x1f/0x98
[ 1327.859745] #3: (text_mutex){......}, at: [<c1005b8d>] arch_jump_label_transform+0x44/0x67
[ 1327.868058] #4: (stop_cpus_mutex){......}, at: [<c1066831>] stop_cpus+0x20/0x3f
View attachment "config-Wed_Oct_20_08_00_12_CEST_2010.bad" of type "text/plain" (73607 bytes)
Powered by blists - more mailing lists