From owner-freebsd-arm@freebsd.org Tue Sep 22 18:09:45 2020 Return-Path: Delivered-To: freebsd-arm@mailman.nyi.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2610:1c1:1:606c::19:1]) by mailman.nyi.freebsd.org (Postfix) with ESMTP id 0E6B43F1BB7 for ; Tue, 22 Sep 2020 18:09:45 +0000 (UTC) (envelope-from mike@karels.net) Received: from mail.karels.net (mail.karels.net [216.160.39.52]) by mx1.freebsd.org (Postfix) with ESMTP id 4Bwq8R4dVSz4PtF for ; Tue, 22 Sep 2020 18:09:43 +0000 (UTC) (envelope-from mike@karels.net) Received: from mail.karels.net (localhost [127.0.0.1]) by mail.karels.net (8.15.2/8.15.2) with ESMTP id 08MI9bwX036997 for ; Tue, 22 Sep 2020 13:09:37 -0500 (CDT) (envelope-from mike@karels.net) Message-Id: <202009221809.08MI9bwX036997@mail.karels.net> To: freebsd-arm@freebsd.org From: Mike Karels Reply-to: mike@karels.net Subject: clock problems with BeagleBone Black on 12.2BETA2 MIME-Version: 1.0 Content-Type: text/plain; charset="us-ascii" Content-ID: <36995.1600798177.1@mail.karels.net> Content-Transfer-Encoding: quoted-printable Date: Tue, 22 Sep 2020 13:09:37 -0500 X-Rspamd-Queue-Id: 4Bwq8R4dVSz4PtF X-Spamd-Bar: - Authentication-Results: mx1.freebsd.org; dkim=none; dmarc=none; spf=pass (mx1.freebsd.org: domain of mike@karels.net designates 216.160.39.52 as permitted sender) smtp.mailfrom=mike@karels.net X-Spamd-Result: default: False [-2.00 / 15.00]; HAS_REPLYTO(0.00)[mike@karels.net]; ARC_NA(0.00)[]; FREEFALL_USER(0.00)[mike]; FROM_HAS_DN(0.00)[]; SH_EMAIL_DBL_DONT_QUERY_IPS(0.00)[0.0.0.0:email]; TO_MATCH_ENVRCPT_ALL(0.00)[]; R_SPF_ALLOW(-0.20)[+ip4:216.160.39.52]; MIME_GOOD(-0.10)[text/plain]; PREVIOUSLY_DELIVERED(0.00)[freebsd-arm@freebsd.org]; REPLYTO_ADDR_EQ_FROM(0.00)[]; RCPT_COUNT_ONE(0.00)[1]; NEURAL_HAM_LONG(-0.99)[-0.991]; TO_DN_NONE(0.00)[]; DBL_PROHIBIT(0.00)[0.0.0.0:email]; NEURAL_HAM_SHORT(-0.09)[-0.092]; DMARC_NA(0.00)[karels.net]; NEURAL_HAM_MEDIUM(-0.72)[-0.717]; RCVD_NO_TLS_LAST(0.10)[]; FROM_EQ_ENVFROM(0.00)[]; R_DKIM_NA(0.00)[]; MIME_TRACE(0.00)[0:+]; ASN(0.00)[asn:209, ipnet:216.160.36.0/22, country:US]; RCVD_COUNT_TWO(0.00)[2]; MAILMAN_DEST(0.00)[freebsd-arm] X-BeenThere: freebsd-arm@freebsd.org X-Mailman-Version: 2.1.33 Precedence: list List-Id: "Porting FreeBSD to ARM processors." List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 22 Sep 2020 18:09:45 -0000 I just installed 12.2BETA2 on a BeagleBone Black (armv7), and it took at least an hour. I hit ^T periodically, and time seemed screwed up (real time was progressing slowly at best). Eventually the system came up, and I logged in. I ran date several times, a few seconds apart, and the time did not change. I ran a ping of a nearby system, and it sent and received only one ping; aparently timeouts/alarms are broken. It is as if the system clock is not interrupting, or is doing so much more slowl= y than it should. I'll append a transcript in case there are clues that I missed. Any thoughts on what is broken? fwiw, I also installed on a Raspberry Pi 3B+ (aarch64), and that proceeded normally. Mike Loading configured modules... can't find '/boot/entropy' /boot/kernel/umodem.ko text=3D0x1520 text=3D0xf10 data=3D0x234+0x4 syms=3D= [0x4+0xe70+0x4+0xa74] loading required module 'ucom' /boot/kernel/ucom.ko text=3D0x1714 text=3D0x2c6c data=3D0x3c4+0x838 syms=3D= [0x4+0x13e0+0x4+0xbac] can't find '/etc/hostid' Hit [Enter] to boot immediately, or any other key for command prompt. Booting [/boot/kernel/kernel]... = Using DTB provided by EFI at 0x87ee8000. Kernel entry at 0x97000180... Kernel args: (null) ---<>--- Copyright (c) 1992-2020 The FreeBSD Project. Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 The Regents of the University of California. All rights reserved. FreeBSD is a registered trademark of The FreeBSD Foundation. FreeBSD 12.2-BETA2 r365865 GENERIC arm FreeBSD clang version 10.0.1 (git@github.com:llvm/llvm-project.git llvmorg= -10.0.1-0-gef32c611aa2) VT: init without driver. CPU: ARM Cortex-A8 r3p2 (ECO: 0x00000000) CPU Features: = Thumb2, Security, VMSAv7 Optional instructions: = UMULL, SMULL, SIMD(ext) LoUU:2 LoC:3 LoUIS:1 = Cache level 1: 32KB/64B 4-way data cache WT WB Read-Alloc 32KB/64B 4-way instruction cache Read-Alloc Cache level 2: 256KB/64B 8-way unified cache WT WB Read-Alloc Write-Alloc real memory =3D 536010752 (511 MB) avail memory =3D 508633088 (485 MB) No PSCI/SMCCC call function found Texas Instruments AM335x Processor, Revision ES2.1 arc4random: no preloaded entropy cache random: entropy device external interface kbd0 at kbdmux0 ofwbus0: simplebus0: on ofwbus0 simplebus1: mem 0x44c00000-0x44c007ff,0= x44c00800-0x44c00fff,0x44c01000-0x44c013ff,0x44c01400-0x44c017ff on simple= bus0 simplebus2: on simplebus1 simplebus3: on simplebus1 simplebus4: on simplebus1 am335x_prcm0: mem 0-0x1fff on ti_sysc0 simplebus5: mem 0-0x1fff on ti_sysc5 ti_scm0: mem 0-0x7ff on simplebus5 simplebus6: mem 0x48000000-0x480007ff,0= x48000800-0x48000fff,0x48001000-0x480013ff,0x48001400-0x480017ff,0x4800180= 0-0x48001bff,0x48001c00-0x48001fff on simplebus0 simplebus7: on simplebus6 simplebus8: on simplebus6 simplebus9: on simplebus6 simplebus10: mem 0x47c00000-0x47c007ff,= 0x47c00800-0x47c00fff,0x47c01000-0x47c013ff on simplebus0 simplebus11: on simplebus10 simplebus12: mem 0x4a000000-0x4a0007ff,= 0x4a000800-0x4a000fff,0x4a001000-0x4a0013ff on simplebus0 simplebus13: on simplebus12 simplebus14: mem 0x4b144400-0x4b1444ff,= 0x4b144800-0x4b144bff on simplebus0 simplebus15: on simplebus14 regfix0: on ofwbus0 clk_fixed0: on ofwbus0 ti_aintc0: mem 0x48200000-0x48200fff on si= mplebus0 ti_aintc0: Revision 5.0 am335x_prcm0: Clocks: System 24.0 MHz, CPU 1000 MHz cpulist0: on ofwbus0 cpu0: on cpulist0 cpufreq_dt0: on cpu0 cpufreq_dt0: no regulator for cpu@0 device_attach: cpufreq_dt0 attach returned 6 pmu0: mem 0x4b000000-0x4bffffff irq 0 on ofw= bus0 gpio0: mem 0-0xfff irq 9 on ti_sysc= 1 gpio0: failed to get device id based on ti,hwmods gpio0: ti_gpio_pic_detach: not implemented yet device_attach: gpio0 attach returned 22 uart0: failed to get clock based on hwmods uart0: failed to get clock based on hwmods uart0: console (115384,n,8,1)ible)> mem 0-0xfff irq 10 on ti_sysc2 iichb0: mem 0-0xfff irq 11 on ti_sysc3 iichb0: failed to get device id using ti,hwmod device_attach: iichb0 attach returned 6 ti_adc0: mem 0-0xfff irq 12 disabled on ti_sysc4 ti_adc0: scheme: 0x1 func: 0x730 rtl: 0 rev: 0.1 custom rev: 0 ti_pinmux0: mem 0x800-0xa37 on simplebus5 am335x_scm0: on ti_scm0 ti_wdt0: mem 0-0xfff irq 15 on ti_sysc7 am335x_rtc0: mem 0-0xfff irq 16,17 on= ti_sysc8 am335x_rtc0: AM335X RTC v1.0.6 am335x_dmtimer0: mem 0-0x3ff irq 26 on ti_sysc15 Event timer "DMTimer2" frequency 24000000 Hz quality 500 gpio0: mem 0-0xfff irq 32 on ti_sys= c21 gpio0: failed to get device id based on ti,hwmods gpio0: ti_gpio_pic_detach: not implemented yet device_attach: gpio0 attach returned 22 sdhci_ti0: mem 0-0xfff irq 33 on ti_sysc22 sdhci_ti0: failed to get clock based on hwmods property ti_prcm: Failed to find clock device (0) sdhci_ti0: Error: failed to enable MMC clock sdhci_ti0-slot0: Hardware doesn't specify base clock frequency, using 50MH= z as default. mmc0: on sdhci_ti0 ti_mbox0: mem 0-0x1ff irq 35 on ti_sysc24 ti_mbox0: revision 4.0 iichb0: mem 0-0xfff irq 36 on ti_sysc26 iichb0: failed to get device id using ti,hwmod device_attach: iichb0 attach returned 6 gpio0: mem 0-0xfff irq 41 on ti_sys= c31 gpio0: failed to get device id based on ti,hwmods gpio0: ti_gpio_pic_detach: not implemented yet device_attach: gpio0 attach returned 22 gpio0: mem 0-0xfff irq 42 on ti_sys= c32 gpio0: failed to get device id based on ti,hwmods gpio0: ti_gpio_pic_detach: not implemented yet device_attach: gpio0 attach returned 22 sdhci_ti1: mem 0-0xfff irq 45 on ti_sysc35 sdhci_ti1: failed to get clock based on hwmods property ti_prcm: Failed to find clock device (0) sdhci_ti1: Error: failed to enable MMC clock sdhci_ti1-slot0: Hardware doesn't specify base clock frequency, using 50MH= z as default. mmc1: on sdhci_ti1 fb0: mem 0-0xfff irq 46 on ti_sysc39 cpswss0: <3-port Switch Ethernet Subsystem> mem 0-0x7ff,0x1200-0x12ff irq = 48,49,50,51 on ti_sysc41 cpswss0: CPSW SS Version 1.12 (0) cpswss0: Initial queue size TX=3D128 RX=3D384 cpsw0: on cpswss0 miibus0: on cpsw0 smscphy0: PHY 0 on miibus0 smscphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto cpsw0: Ethernet address: 1c:ba:8c:d5:7d:6b gpioled0: on ofwbus0 gpioled0: failed to map pin gpioled0: failed to map pin gpioled0: failed to map pin gpioled0: failed to map pin cryptosoft0: Timecounters tick every 1.000 msec mmcsd0: 16GB at mmc0 50= .0MHz/4bit/65535-block mmcsd1: 4GB at mm= c1 50.0MHz/8bit/65535-block mmcsd1boot0: 1MB partition 1 at mmcsd1 mmcsd1boot1: 1MB partition 2 at mmcsd1 mmcsd1rpmb: 131kB partition 3 at mmcsd1 Trying to mount root from ufs:/dev/ufs/rootfs [rw]... Warning: no time-of-day clock registered, system time will not be set accu= rately Growing root partition to fill device random: read_random_uio unblock wait load: 1.28 cmd: awk 39 [piperd] 0.11r 0.00u 0.00s 0% 2060k load: 1.28 cmd: awk 39 [piperd] 0.13r 0.00u 0.00s 0% 2060k load: 1.28 cmd: awk 39 [piperd] 1.09r 0.00u 0.00s 0% 2060k ^Grandom: unblocking device. load: 1.28 cmd: awk 39 [piperd] 1.11r 0.00u 0.00s 0% 2060k Script /etc/rc.d/growfs running GEOM_PART: mmcsd0s2 was automatically resized. Use `gpart commit mmcsd0s2` to save changes or `gpart undo mmcsd0s2` to = revert them. mmcsd0s2 resized mmcsd0s2a resized gpart: arg0 'ufs/rootfs': Invalid argument super-block backups (for fsck_ffs -b #) at: 6411392, 7693632, 8975872, 10258112, 11540352, 12822592, 14104832, 153870= 72, 16669312, 17951552, 19233792, 20516032, 21798272, 23080512, 24362752, 25644992, 26927232, 28209472, 29491712, 30773952 /etc/rc: WARNING: hostid: unable to figure out a UUID from DMI data, gener= ating a new one sleep: about 1 second(s) left out of the original 2 load: 1.28 cmd: sleep 52 [nanslp] 0.26r 0.00u 0.00s 0% 1692k sleep: about 1 second(s) left out of the original 2 load: 1.28 cmd: sleep 52 [nanslp] 0.26r 0.00u 0.00s 0% 1692k sleep: about 1 second(s) left out of the original 2 load: 1.28 cmd: sleep 52 [nanslp] 0.26r 0.00u 0.00s 0% 1692k sleep: about 1 second(s) left out of the original 2 load: 1.28 cmd: sleep 52 [nanslp] 0.26r 0.00u 0.00s 0% 1692k Script /etc/rc.d/hostid running Setting hostuuid: f72f25fb-f967-11ea-aa7a-1cba8cd57d6b. Setting hostid: 0xf3986255. Starting file system checks: /dev/ufs/rootfs: FILE SYSTEM CLEAN; SKIPPING CHECKS /dev/ufs/rootfs: clean, 3219813 free (229 frags, 402448 blocks, 0.0% fragm= entation) Mounting local filesystems:. ELF ldconfig path: /lib /usr/lib /usr/lib/compat Soft Float compatibility ldconfig path: Building /boot/kernel/linker.hints Setting hostname: generic. Setting up harvesting: [UMA],[FS_ATIME],SWI,INTERRUPT,NET_NG,NET_ETHER,NET= _TUN,MOUSE,KEYBOARD,ATTACH,CACHED Feeding entropy: . lo0: link state changed to UP cpsw0: link state changed to DOWN load: 1.18 cmd: sleep 285 [nanslp] 0.48r 0.00u 0.00s 0% 1848k sleep: about 0 second(s) left out of the original 1 load: 1.18 cmd: sleep 285 [nanslp] 0.49r 0.00u 0.00s 0% 1852k sleep: about 0 second(s) left out of the original 1 load: 1.18 cmd: sleep 285 [nanslp] 0.49r 0.00u 0.00s 0% 1852k sleep: about 0 second(s) left out of the original 1 cpsw0: link state changed to UP Script /etc/rc.d/netif running load: 1.18 cmd: sleep 286 [nanslp] 0.66r 0.00u 0.00s 0% 1848k sleep: about 0 second(s) left out of the original 1 load: 1.18 cmd: sleep 286 [nanslp] 0.66r 0.00u 0.00s 0% 1852k sleep: about 0 second(s) left out of the original 1 Script /etc/rc.d/netif running Starting Network: lo0 cpsw0. lo0: flags=3D8049 metric 0 mtu 16384 options=3D680003 inet6 ::1 prefixlen 128 inet6 fe80::1%lo0 prefixlen 64 scopeid 0x2 inet 127.0.0.1 netmask 0xff000000 groups: lo nd6 options=3D21 cpsw0: flags=3D8843 metric 0 mtu 1= 500 options=3D8000b ether 1c:ba:8c:d5:7d:6b media: Ethernet autoselect (100baseTX ) status: active nd6 options=3D29 Starting devd. Starting dhclient. DHCPDISCOVER on cpsw0 to 255.255.255.255 port 67 interval 7 DHCPOFFER from 10.0.2.2 DHCPREQUEST on cpsw0 to 255.255.255.255 port 67 DHCPACK from 10.0.2.2 bound to 10.0.2.16 -- renewal in 21600 seconds. add host 127.0.0.1: gateway lo0 fib 0: route already in table add host ::1: gateway lo0 fib 0: route already in table add net fe80::: gateway ::1 add net ff02::: gateway ::1 add net ::ffff:0.0.0.0: gateway ::1 add net ::0.0.0.0: gateway ::1 Generating host.conf. Creating and/or trimming log files. Starting syslogd. Clearing /tmp (X related). Updating motd:. Mounting late filesystems:. Updating /var/run/os-release done. Generating RSA host key. 2048 SHA256:bBJMh1UXYLNfwLfLpOm2VpdywAyV922V6GqyA/yrkHY root@generic (RSA) Generating ECDSA host key. 256 SHA256:GHRibfR4nmvo2FjLOQpzkzTSI90EbCpCbn4yU9sK9X8 root@generic (ECDSA= ) Generating ED25519 host key. 256 SHA256:6tEiyuIG9ySGBSRwLKqxJ1+rXav/rpy7yv8Jw+SHIQQ root@generic (ED255= 19) Performing sanity check on sshd configuration. Starting sshd. Starting cron. Starting background file system checks in 60 seconds. Fri Sep 18 04:32:35 UTC 2020 FreeBSD/arm (generic) (ttyu0) login: = ... root@generic:~ # date Fri Sep 18 04:32:38 UTC 2020 root@generic:~ # date Fri Sep 18 04:32:38 UTC 2020 root@generic:~ # date Fri Sep 18 04:32:38 UTC 2020 root@generic:~ # date Fri Sep 18 04:32:38 UTC 2020