* Re: function_profile_enabled not showing Time info
[not found] ` <20200811205411.2f7748ac@oasis.local.home>
@ 2020-08-12 9:46 ` Ahmed Zaki
0 siblings, 0 replies; only message in thread
From: Ahmed Zaki @ 2020-08-12 9:46 UTC (permalink / raw)
To: Steven Rostedt; +Cc: linux-trace-users, linux-trace-devel
On Wed, 12 Aug 2020 at 02:54, Steven Rostedt <rostedt@goodmis.org> wrote:
>
> On Wed, 12 Aug 2020 00:35:24 +0200
> Ahmed Zaki <anzaki@gmail.com> wrote:
>
> > Hello,
> >
> > I am trying to use function_profile_enabled on a MIPS machine but only
> > the HITS column is showing numbers. Time and Avg are showing all
> > Zeros.
>
> What clock are you using?
>
> cat /sys/kernel/debug/tracing/trace_clock
>
> And also supply the dmesg output, so I can see if there's an issue with
> the clock being used.
>
> -- Steve
>
>
> >
> > This is my .config file:
> >
> > CONFIG_FTRACE=y
> > CONFIG_FUNCTION_TRACER=y
> > CONFIG_FUNCTION_GRAPH_TRACER=y
> > CONFIG_IRQSOFF_TRACER=y
> > CONFIG_SCHED_TRACER=y
> > CONFIG_DYNAMIC_FTRACE=y
> >
> > Am I missing something?
> >
> > Thanks.
>
# cat /sys/kernel/debug/tracing/trace_clock
[local] global counter uptime perf mono mono_raw boot
I tried all of them and still getting zeros in TIme and Avg
And below is dmesg, thanks.
[ 0.000000] Linux version 4.14.171 (ahmed@ahmed-VirtualBox) (gcc
version 5.4.0 (Buildroot 2017.02.4-g3e5f6d5bc-dirty)) #1 Tue Aug 11
13:52:20 MDT 2020
[ 0.000000] bootconsole [early0] enabled
[ 0.000000] CPU0 revision is: 00019750 (MIPS 74Kc)
[ 0.000000] MIPS: machine is UBNT-UAP-AC-M
[ 0.000000] SoC: Qualcomm Atheros QCA956X ver 1 rev 0
[ 0.000000] Determined physical RAM map:
[ 0.000000] memory: 08000000 @ 00000000 (usable)
[ 0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
[ 0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases,
linesize 32 bytes
[ 0.000000] Zone ranges:
[ 0.000000] Normal [mem 0x0000000000000000-0x0000000007ffffff]
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000000000000-0x0000000007ffffff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
[ 0.000000] On node 0 totalpages: 32768
[ 0.000000] free_area_init_node: node 0, pgdat 80640220,
node_mem_map 81007540
[ 0.000000] Normal zone: 256 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 32768 pages, LIFO batch:7
[ 0.000000] random: get_random_bytes called from
start_kernel+0xa0/0x47c with crng_init=0
[ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 32512
[ 0.000000] Kernel command line: console=ttyS0,115200n8 rootfstype=jffs2
[ 0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[ 0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[ 0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.000000] Writing ErrCtl register=00000000
[ 0.000000] Readback ErrCtl register=00000000
[ 0.000000] Memory: 121536K/131072K available (4158K kernel code,
261K rwdata, 1616K rodata, 1380K init, 525K bss, 9536K reserved, 0K
cma-reserved)
[ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] ftrace: allocating 15925 entries in 32 pages
[ 0.000000] NR_IRQS: 51
[ 0.000000] CPU clock: 775.000 MHz
[ 0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles:
0xffffffff, max_idle_ns: 4932285024 ns
[ 0.000007] sched_clock: 32 bits at 387MHz, resolution 2ns, wraps
every 5541893118ns
[ 0.008231] Calibrating delay loop... 385.02 BogoMIPS (lpj=192512)
[ 0.025759] pid_max: default: 32768 minimum: 301
[ 0.030890] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.037891] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.049414] clocksource: jiffies: mask: 0xffffffff max_cycles:
0xffffffff, max_idle_ns: 1911260446275000 ns
[ 0.059741] futex hash table entries: 256 (order: 0, 7168 bytes)
[ 0.066201] pinctrl core: initialized pinctrl subsystem
[ 0.072820] NET: Registered protocol family 16
[ 0.084554] PCI host bridge /ahb/pcie-controller@18250000 ranges:
[ 0.091038] MEM 0x0000000012000000..0x0000000013ffffff
[ 0.096559] IO 0x0000000000000000..0x0000000000000000
[ 0.122978] PCI host bridge to bus 0000:00
[ 0.127348] pci_bus 0000:00: root bus resource [mem 0x12000000-0x13ffffff]
[ 0.134602] pci_bus 0000:00: root bus resource [io 0x0000]
[ 0.140485] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[ 0.147649] pci_bus 0000:00: No busn resource found for root bus,
will use [bus 00-ff]
[ 0.156043] pci 0000:00:00.0: [168c:003c] type 00 class 0x028000
[ 0.156102] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x001fffff 64bit]
[ 0.156158] pci 0000:00:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
[ 0.156240] pci 0000:00:00.0: supports D1
[ 0.156249] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
[ 0.156456] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
[ 0.156482] pci 0000:00:00.0: BAR 0: assigned [mem
0x12000000-0x121fffff 64bit]
[ 0.164244] pci 0000:00:00.0: BAR 6: assigned [mem
0x12200000-0x1220ffff pref]
[ 0.172461] clocksource: Switched to clocksource MIPS
[ 0.217220] NET: Registered protocol family 2
[ 0.222606] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.230012] TCP bind hash table entries: 1024 (order: 2, 20480 bytes)
[ 0.236904] TCP: Hash tables configured (established 1024 bind 1024)
[ 0.243771] UDP hash table entries: 256 (order: 1, 12288 bytes)
[ 0.250128] UDP-Lite hash table entries: 256 (order: 1, 12288 bytes)
[ 0.257157] NET: Registered protocol family 1
[ 0.261846] PCI: CLS 0 bytes, default 32
[ 0.268183] workingset: timestamp_bits=30 max_order=15 bucket_order=0
[ 0.279278] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA)
(CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[ 0.299629] io scheduler noop registered
[ 0.303803] io scheduler deadline registered (default)
[ 0.310903] pinctrl-single 1804002c.pinmux: 544 pins at pa b804002c size 68
[ 0.319276] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[ 0.326750] console [ttyS0] disabled
[ 0.330606] 18020000.uart: ttyS0 at MMIO 0x18020000 (irq = 9,
base_baud = 1562500) is a 16550A
[ 0.339719] console [ttyS0] enabled
[ 0.347287] bootconsole [early0] disabled
[ 0.362510] m25p80 spi0.0: mx25l12805d (16384 Kbytes)
[ 0.367804] 5 fixed-partitions partitions found on MTD device spi0.0
[ 0.374384] Creating 5 MTD partitions on "spi0.0":
[ 0.379357] 0x000000000000-0x000000060000 : "u-boot"
[ 0.385291] 0x000000060000-0x000000070000 : "u-boot-env"
[ 0.391662] 0x000000070000-0x000000f70000 : "firmware"
[ 0.401096] 2 uimage-fw partitions found on MTD device firmware
[ 0.407258] Creating 2 MTD partitions on "firmware":
[ 0.412409] 0x000000000000-0x000000200000 : "kernel"
[ 0.418280] 0x000000200000-0x000000f00000 : "rootfs"
[ 0.424167] mtd: device 4 (rootfs) set to be root filesystem
[ 0.430107] 0x000000f70000-0x000000ff0000 : "cfg"
[ 0.435786] 0x000000ff0000-0x000001000000 : "art"
[ 0.442294] libphy: Fixed MDIO Bus: probed
[ 0.530457] random: fast init done
[ 1.071833] libphy: ag71xx_mdio: probed
[ 1.131902] ag71xx 19000000.eth: connected to PHY at mdio-bus.0:04
[uid=004dd074, driver=Atheros 8031 ethernet]
[ 1.142955] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode: sgmii
[ 1.150433] Netfilter messages via NETLINK v0.30.
[ 1.155670] nf_conntrack version 0.5.0 (2048 buckets, 8192 max)
[ 1.162775] NET: Registered protocol family 10
[ 1.171904] Segment Routing with IPv6
[ 1.175834] NET: Registered protocol family 17
[ 1.180555] Bridge firewalling registered
[ 1.184733] 8021q: 802.1Q VLAN Support v1.8
[ 4.259284] jffs2: notice: (1) jffs2_build_xattr_subsystem:
complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan)
and 0 of xref (0 dead, 0 orphan) found.
[ 4.275688] VFS: Mounted root (jffs2 filesystem) readonly on device 31:4.
[ 4.301258] Freeing unused kernel memory: 1380K
[ 4.305970] This architecture does not have kernel memory protection.
[ 5.310549] jffs2: notice: (308) jffs2_build_xattr_subsystem:
complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan)
and 0 of xref (0 dead, 0 orphan) found.
[ 6.330613] random: dd: uninitialized urandom read (512 bytes read)
[ 6.525170] random: avahi-daemon: uninitialized urandom read (4 bytes read)
[ 6.547278] random: avahi-daemon: uninitialized urandom read (4 bytes read)
[ 13.699503] random: crng init done
[ 14.526615] Loading modules backported from Linux version
v4.19.98-0-gd183c8e2647a
[ 14.534488] Backport generated by backports.git v4.19.98-1-0-g8204eb99
[ 18.071321] PCI: Enabling device 0000:00:00.0 (0000 -> 0002)
[ 18.077401] ath10k_pci 0000:00:00.0: pci irq legacy oper_irq_mode 1
irq_mode 0 reset_mode 0
[ 18.253583] ath10k_pci 0000:00:00.0: Direct firmware load for
ath10k/pre-cal-pci-0000:00:00.0.bin failed with error -2
[ 18.264673] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 18.282503] ath10k_pci 0000:00:00.0: Direct firmware load for
ath10k/QCA988X/hw2.0/firmware-6.bin failed with error -2
[ 18.293580] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 18.564289] ath10k_pci 0000:00:00.0: qca988x hw2.0 target
0x4100016c chip_id 0x043222ff sub 0000:0000
[ 18.573883] ath10k_pci 0000:00:00.0: kconfig debug 1 debugfs 1
tracing 1 dfs 0 testmode 0
[ 18.587016] ath10k_pci 0000:00:00.0: firmware ver 10.2.4.70.70 api
5 features no-p2p,raw-mode,mfp,allows-mesh-bcast crc32 3a226daa
[ 18.663342] ath10k_pci 0000:00:00.0: Direct firmware load for
ath10k/QCA988X/hw2.0/board-2.bin failed with error -2
[ 18.674165] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 18.692404] ath10k_pci 0000:00:00.0: board_file api 1 bmi_id N/A
crc32 bebc7c08
[ 19.742442] ath10k_pci 0000:00:00.0: htt-ver 2.1 wmi-op 5 htt-op 2
cal file max-sta 128 raw 0 hwcrypto 1
[ 19.833153] ath: EEPROM regdomain: 0x0
[ 19.833164] ath: EEPROM indicates default country code should be used
[ 19.833168] ath: doing EEPROM country->regdmn map search
[ 19.833181] ath: country maps to regdmn code: 0x3a
[ 19.833186] ath: Country alpha2 being used: US
[ 19.833190] ath: Regpair used: 0x3a
[ 20.758095] ath: EEPROM regdomain: 0x0
[ 20.758106] ath: EEPROM indicates default country code should be used
[ 20.758110] ath: doing EEPROM country->regdmn map search
[ 20.758125] ath: country maps to regdmn code: 0x3a
[ 20.758130] ath: Country alpha2 being used: US
[ 20.758134] ath: Regpair used: 0x3a
^ permalink raw reply [flat|nested] only message in thread