lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [<thread-prev] [thread-next>] [day] [month] [year] [list]
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

Powered by Openwall GNU/*/Linux Powered by OpenVZ