configd, booting fast and spanning tree
configd, booting fast and spanning tree
- Subject: configd, booting fast and spanning tree
- From: Michael Bartosh <email@hidden>
- Date: Sat, 6 Aug 2005 22:18:57 -0400
I'm exploring a race condition where the system sets the hostname
incorrectly because spanning tree has not let go of the port. I
realize we could probably enable portfast, but that is a solution
that is intrusive to existing IT infrastructures, so I'd rather find
something that doesn't require a config change on the switch. I also
realize I can set the hostname statically. I'd like to not do that,
in order to keep the box as close to an out-of-box config as feasible.
I've set the verbose flag to yes in /S/L/SystemConfiguration/
IPConfiguration.bundle/Resources/IPConfiguration.xml and made syslog
a little more verbose. That output is below.
I've also tried modifying the following keys in the above file (if
anyone knows where I can get a clear definition of what these keys
do, please let me know). They sounded hopeful:
RetryCount = 60
LinkInactiveWaitTimeSeconds = 60
MaximumRetryTimeSeconds = 60
I'm interested in finding whether anyone has found a solution to this
problem. It did not occur in Panther. There needs to be a key we can
use to turn off this fascination with booting fast. It's fine for mom
and pop but it has continually hurt IT-centric markets.
relevant boot-time syslog output:
Aug 6 21:17:00 localhost kernel[0]: standard timeslicing quantum is
10000 us
Aug 6 21:17:00 localhost mDNSResponder-107 (Mar 20 2005 20: 31:47)
[44]: starting
Aug 6 21:17:00 localhost kernel[0]: vm_page_bootstrap: 253441 free
pages
Aug 6 21:17:00 localhost DirectoryService[41]: Launched version
2.0.1 (v350)
Aug 6 21:17:00 localhost kernel[0]: mig_table_max_displ = 70
Aug 6 21:17:00 localhost configd[34]: adding
com.apple.SystemConfiguration.InterfaceNamer
Aug 6 21:17:00 localhost kernel[0]: 90 prelinked modules
Aug 6 21:17:00 localhost configd[34]: loading
com.apple.SystemConfiguration.DynamicPowerStep
Aug 6 21:17:00 localhost kernel[0]: Copyright (c) 1982, 1986, 1989,
1991, 1993
Aug 6 21:17:00 localhost lookupd[51]: lookupd (version 365) starting
- Sat Aug 6 21:17:00 2005
Aug 6 21:17:00 localhost kernel[0]: The Regents of the University of
California. All rights reserved.
Aug 6 21:17:00 localhost configd[34]: loading
com.apple.SystemConfiguration.Bluetooth
Aug 6 21:17:00 localhost configd[34]: loading
com.apple.SystemConfiguration.BatteryUpdater
Aug 6 21:17:00 localhost kernel[0]: using 2621 buffer headers and
2621 cluster IO buffer headers
Aug 6 21:17:00 localhost configd[34]: loading
com.apple.configd.Apple80211
Aug 6 21:17:00 localhost kernel[0]: DART enabled
Aug 6 21:17:00 localhost configd[34]: adding
com.apple.SystemConfiguration.KernelEventMonitor
Aug 6 21:17:00 localhost kernel[0]: MacRISC4CPU: publishing BootCPU
Aug 6 21:17:00 localhost configd[34]: adding
com.apple.SystemConfiguration.LinkConfiguration
Aug 6 21:17:00 localhost kernel[0]: Enabling ECC Error Notifications
Aug 6 21:17:00 localhost configd[34]: loading
com.apple.SystemConfiguration.ATconfig
Aug 6 21:17:00 localhost kernel[0]: FireWire (OHCI) Apple ID 42
built-in now active, GUID 000d93ff feb39130; max speed s800.
Aug 6 21:17:00 localhost configd[34]: loading
com.apple.SystemConfiguration.EAPOLController
Aug 6 21:17:00 localhost kernel[0]: Security auditing service present
Aug 6 21:17:00 localhost configd[34]: loading
com.apple.SystemConfiguration.IP6Configuration
Aug 6 21:17:00 localhost kernel[0]: BSM auditing present
Aug 6 21:17:00 localhost configd[34]: loading
com.apple.SystemConfiguration.IPConfiguration
Aug 6 21:17:00 localhost kernel[0]: disabled
Aug 6 21:17:00 localhost configd[34]: adding
com.apple.SystemConfiguration.IPMonitor
Aug 6 21:17:00 localhost kernel[0]: rooting via boot-uuid from /
chosen: F1C5506B-50C9-3088-ABC7-59F999664421
Aug 6 21:17:00 localhost configd[34]: adding
com.apple.SystemConfiguration.Kicker
Aug 6 21:17:00 localhost kernel[0]: Waiting on <dict
ID="0"><key>IOProviderClass</key><string ID="1">IOResources</
string><key>IOResourceMatch</key><string ID="2">boot-uuid-media</
string></dict>
Aug 6 21:17:00 localhost configd[34]: loading
com.apple.SystemConfiguration.PPPController
Aug 6 21:17:00 localhost kernel[0]: Got boot device = IOService:/
MacRISC4PE/ht@0,f2000000/AppleMacRiscHT/pci@7/IOPCI2PCIBridge/k2-sata-
root@C/AppleK2SATARoot/k2-sata@0/AppleK2SATA/ATADeviceNub@0/
IOATABlockStorageDriver/IOATABlockStorageDevice/IOBlockStorageDriver/
Hitachi HDS722580VLSA80 Media/IOApplePartitionScheme/
Apple_RAID_OfflineV2_Untitled_2@4/AppleRAIDMember/AppleRAIDMirrorSet/
Untitled RAID Set 1@0
Aug 6 21:17:00 localhost watchdogtimerd: Automatic reboot timer
enabled.
Aug 6 21:17:00 localhost kernel[0]: BCM5701Enet: Ethernet address
00:0d:93:9d:2a:d0
Aug 6 21:17:00 localhost configd[34]: Starting kicker for enable-
network
Aug 6 21:17:00 localhost kernel[0]: BCM5701Enet: Ethernet address
00:0d:93:9d:2a:d1
Aug 6 21:17:00 localhost kernel[0]: BCM5701Enet: Ethernet address
00:11:24:0f:72:23
Aug 6 21:17:00 localhost configd[34]: Starting kicker for
network_change
Aug 6 21:17:00 localhost configd[34]: Starting kicker for
AppleFileServer
Aug 6 21:17:00 localhost configd[34]: MustBroadcast = false
Aug 6 21:17:00 localhost configd[34]: RetryCount = 60
Aug 6 21:17:00 localhost configd[34]: GatherTimeSeconds = 30
Aug 6 21:17:00 localhost configd[34]: LinkInactiveWaitTimeSeconds = 60
Aug 6 21:17:00 localhost configd[34]: InitialRetryTimeSeconds = 30
Aug 6 21:17:00 localhost configd[34]: MaximumRetryTimeSeconds = 60
Aug 6 21:17:00 localhost configd[34]: ARPProbeCount = 4
Aug 6 21:17:00 localhost configd[34]: ARPGratuitousCount = 1
Aug 6 21:17:00 localhost configd[34]: ARPRetryTimeSeconds = 0.300000
Aug 6 21:17:00 localhost configd[34]: DHCPAcceptsBOOTP = false
Aug 6 21:17:00 localhost configd[34]: DHCPFailureConfiguresLinkLocal
= true
Aug 6 21:17:00 localhost configd[34]:
DHCPSuccessDeconfiguresLinkLocal = true
Aug 6 21:17:00 localhost configd[34]: DHCPInitRebootRetryCount = 2
Aug 6 21:17:00 localhost configd[34]: DHCPSelectRetryCount = 3
Aug 6 21:17:00 localhost configd[34]:
DHCPAllocateLinkLocalAtRetryCount = 2
Aug 6 21:17:00 localhost configd[34]: DHCPRouterARPAtRetryCount = 6
Aug 6 21:17:00 localhost configd[34]: RouterARPEnabled = true
Aug 6 21:17:00 localhost configd[34]: DHCPLocalHostNameLengthMax = 15
Aug 6 21:17:00 localhost configd[34]: IPConfiguration logfile '/var/
log/com.apple.IPConfiguration.bootp' opened
Aug 6 21:17:00 localhost configd[34]: PM configd:_copyPMSettings():
using profile #-1 for power source AC Power
Aug 6 21:17:00 localhost com.apple.SecurityServer: Entering service
Aug 6 21:17:00 localhost configd[34]: en0 link is unknown
Aug 6 21:17:00 localhost configd[34]: en1 link is unknown
Aug 6 21:17:01 localhost lookupd[69]: lookupd (version 365) starting
- Sat Aug 6 21:17:01 2005
Aug 6 21:17:01 localhost diskarbitrationd[36]: disk2 hfs
F1C5506B-50C9-3088-ABC7-59F999664421 Server HD /
Aug 6 21:17:01 localhost servermgrd: servermgr_dns ready for
notifications
Aug 6 21:17:01 localhost servermgrd: cupsd's bootstrap server port
not found
Aug 6 21:17:01 localhost servermgrd: cupsd's bootstrap server port
not found
Aug 6 21:17:01 localhost /System/Library/CoreServices/
loginwindow.app/Contents/MacOS/loginwindow: Login Window Application
Started
Aug 6 21:17:01 localhost servermgrd: cupsd's bootstrap server port
not found
Aug 6 21:17:01 localhost servermgrd: cupsd's bootstrap server port
not found
Aug 6 21:17:02 localhost kernel[0]: AppleBCM5701Ethernet - en0 link
active, 100-Mbit, full duplex, no flow control
Aug 6 21:17:02 localhost configd[34]: MANUAL en0: starting
Aug 6 21:17:02 localhost configd[34]: arp_client_open_fd (en0):
refcount 1
Aug 6 21:17:02 localhost configd[34]: arp_client_open_fd (en0):
opened bpf fd 10
Aug 6 21:17:02 localhost configd[34]: Aug 6 21:17:02 localhost
configd[34]: timer: wakeup time is (0.300000) 145070222
Aug 6 21:17:02 localhost configd[34]: timer: adding timer source
Aug 6 21:17:02 localhost loginwindow[87]: Login Window Started
Security Agent
Aug 6 21:17:02 localhost SecurityAgent[97]: Showing Login Window
Aug 6 21:17:03 localhost kernel[0]: AppleBCM5701Ethernet - en1 link
active, 100-Mbit, full duplex, no flow control
Aug 6 21:17:03 localhost configd[34]: MANUAL en1: starting
Aug 6 21:17:03 localhost configd[34]: arp_client_open_fd (en1):
refcount 1
Aug 6 21:17:03 localhost configd[34]: arp_client_open_fd (en1):
opened bpf fd 11
Aug 6 21:17:03 localhost configd[34]: Aug 6 21:17:03 localhost
configd[34]: timer: wakeup time is (0.300000) 145070223
Aug 6 21:17:03 localhost configd[34]: timer: adding timer source
Aug 6 21:17:03 apache1 configd[34]: hostname (multicast DNS) = apache1
Aug 6 21:17:03 apache1 configd[34]: setting hostname to "apache1.local"
Aug 6 21:17:03 apache1 configd[34]: Changes: <array> {
Aug 6 21:17:03 apache1 configd[34]: 0 : State:/Network/Interface/
en0/Link
Aug 6 21:17:03 apache1 configd[34]: 1 : State:/Network/Interface/
en1/Link
Aug 6 21:17:03 apache1 configd[34]: } (2)
Aug 6 21:17:03 apache1 configd[34]: en0 link is down
Aug 6 21:17:03 apache1 configd[34]: arp_client_close_fd(en0): bpf
open fd count is 0
Aug 6 21:17:03 apache1 configd[34]: arp_client_close_fd(en0):
closing bpf fd 10
Aug 6 21:17:03 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:03 apache1 configd[34]: timer: wakeup time is
(60.000000) 145070283
Aug 6 21:17:03 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:03 apache1 configd[34]: en1 link is down
Aug 6 21:17:03 apache1 configd[34]: arp_client_close_fd(en1): bpf
open fd count is 0
Aug 6 21:17:03 apache1 configd[34]: arp_client_close_fd(en1):
closing bpf fd 11
Aug 6 21:17:03 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:03 apache1 configd[34]: timer: wakeup time is
(60.000000) 145070283
Aug 6 21:17:03 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:03 apache1 mDNSResponder: Adding browse domain local.
Aug 6 21:17:04 apache1 kernel[0]: AppleBCM5701Ethernet - en0 link
active, 100-Mbit, full duplex, no flow control
Aug 6 21:17:04 apache1 configd[34]: Changes: <array> {
Aug 6 21:17:04 apache1 configd[34]: 0 : State:/Network/Interface/
en0/Link
Aug 6 21:17:04 apache1 configd[34]: } (1)
Aug 6 21:17:04 apache1 configd[34]: en0 link is up
Aug 6 21:17:04 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:04 apache1 configd[34]: arp_client_open_fd (en0):
refcount 1
Aug 6 21:17:04 apache1 configd[34]: arp_client_open_fd (en0): opened
bpf fd 10
Aug 6 21:17:04 apache1 configd[34]: Aug 6 21:17:04 apache1 configd
[34]: timer: wakeup time is (0.300000) 145070225
Aug 6 21:17:04 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:05 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:05 apache1 configd[34]: timer: wakeup time is (0.300000)
145070225
Aug 6 21:17:05 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:05 apache1 kernel[0]: AppleBCM5701Ethernet - en1 link
active, 100-Mbit, full duplex, flow control enabled
Aug 6 21:17:05 apache1 configd[34]: Changes: <array> {
Aug 6 21:17:05 apache1 configd[34]: 0 : State:/Network/Interface/
en1/Link
Aug 6 21:17:05 apache1 configd[34]: } (1)
Aug 6 21:17:05 apache1 configd[34]: en1 link is up
Aug 6 21:17:05 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:05 apache1 configd[34]: arp_client_open_fd (en1):
refcount 1
Aug 6 21:17:05 apache1 configd[34]: arp_client_open_fd (en1): opened
bpf fd 11
Aug 6 21:17:05 apache1 configd[34]: Aug 6 21:17:05 apache1 configd
[34]: timer: wakeup time is (0.300000) 145070226
Aug 6 21:17:05 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:05 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:05 apache1 configd[34]: timer: wakeup time is (0.300000)
145070226
Aug 6 21:17:05 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:05 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:05 apache1 configd[34]: timer: wakeup time is (0.300000)
145070226
Aug 6 21:17:05 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:05 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:05 apache1 configd[34]: timer: wakeup time is (0.300000)
145070226
Aug 6 21:17:05 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:05 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:05 apache1 configd[34]: timer: wakeup time is (0.300000)
145070226
Aug 6 21:17:05 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:06 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:06 apache1 configd[34]: timer: wakeup time is (0.300000)
145070226
Aug 6 21:17:06 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:06 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:06 apache1 configd[34]: timer: wakeup time is (0.300000)
145070226
Aug 6 21:17:06 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:06 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:06 apache1 configd[34]: timer: wakeup time is (0.000001)
145070226
Aug 6 21:17:06 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:06 apache1 configd[34]: arp_client_close_fd(en0): bpf
open fd count is 0
Aug 6 21:17:06 apache1 configd[34]: arp_client_close_fd(en0):
closing bpf fd 10
Aug 6 21:17:06 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:06 apache1 configd[34]: service_set_address(en0):
10.185.11.20 netmask 255.255.255.0 broadcast 10.185.11.255
Aug 6 21:17:06 apache1 configd[34]: host_route: write routing socket
failed, No such process
Aug 6 21:17:06 apache1 configd[34]: State:/Network/Service/0/IPv4 =
<dictionary> {
Aug 6 21:17:06 apache1 configd[34]: InterfaceName : en0
Aug 6 21:17:06 apache1 configd[34]: Addresses : <array> {
Aug 6 21:17:06 apache1 configd[34]: 0 : 10.185.11.20
Aug 6 21:17:06 apache1 configd[34]: }
Aug 6 21:17:06 apache1 configd[34]: SubnetMasks : <array> {
Aug 6 21:17:06 apache1 configd[34]: 0 : 255.255.255.0
Aug 6 21:17:06 apache1 configd[34]: }
Aug 6 21:17:06 apache1 configd[34]: }
Aug 6 21:17:06 apache1 servermgrd: servermgr_dns: network interface
change noted
Aug 6 21:17:06 apache1 configd[34]: executing /System/Library/
SystemConfiguration/Kicker.bundle/Contents/Resources/enable-network
Aug 6 21:17:06 apache1 configd[34]: posting notification
com.apple.system.config.network_change
Aug 6 21:17:06 apache1 lookupd[101]: lookupd (version 365) starting
- Sat Aug 6 21:17:06 2005
Aug 6 21:17:06 apache1 configd[34]: before_blocking: calling
S_linklocal_elect
Aug 6 21:17:06 apache1 configd[34]: siocprotoattach(en0) failed,
File exists (17)
Aug 6 21:17:06 apache1 configd[34]: LINKLOCAL en0: start
Aug 6 21:17:06 apache1 configd[34]: subnet_route: write routing
socket failed, No such process
Aug 6 21:17:06 apache1 configd[34]: LINKLOCAL en0: status =
'operation succeded'
Aug 6 21:17:06 apache1 enable-network[102]: process network
configuration change
Aug 6 21:17:06 apache1 ntpdate[113]: ntpdate email@hidden Sun Mar 20
15:41:03 PST 2005 (1)
Aug 6 21:17:06 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:06 apache1 configd[34]: timer: wakeup time is (0.300000)
145070227
Aug 6 21:17:06 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:06 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:06 apache1 configd[34]: timer: wakeup time is (0.000001)
145070227
Aug 6 21:17:06 apache1 configd[34]: timer: adding timer source
Aug 6 21:17:06 apache1 configd[34]: arp_client_close_fd(en1): bpf
open fd count is 0
Aug 6 21:17:06 apache1 configd[34]: arp_client_close_fd(en1):
closing bpf fd 11
Aug 6 21:17:06 apache1 configd[34]: timer: freeing timer source
Aug 6 21:17:06 apache1 configd[34]: service_set_address(en1):
192.168.0.20 netmask 255.255.255.0 broadcast 192.168.0.255
Aug 6 21:17:06 apache1 configd[34]: host_route: write routing socket
failed, No such process
Aug 6 21:17:06 apache1 configd[34]: State:/Network/Service/
15E2064E-62FD-41AF-91A6-1263653726AB/IPv4 = <dictionary> {
Aug 6 21:17:06 apache1 configd[34]: InterfaceName : en1
Aug 6 21:17:06 apache1 configd[34]: Addresses : <array> {
Aug 6 21:17:06 apache1 configd[34]: 0 : 192.168.0.20
Aug 6 21:17:06 apache1 configd[34]: }
Aug 6 21:17:06 apache1 configd[34]: SubnetMasks : <array> {
Aug 6 21:17:06 apache1 configd[34]: 0 : 255.255.255.0
Aug 6 21:17:06 apache1 configd[34]: }
Aug 6 21:17:06 apache1 configd[34]: }
Aug 6 21:17:06 apache1 servermgrd: servermgr_dns: network interface
change noted
Aug 6 21:17:06 apache1 configd[34]: before_blocking: calling
S_linklocal_elect
<snip a bunch of automount stuff>
Aug 6 21:17:09 apache1 configd[34]: Changes: <array> {
Aug 6 21:17:09 apache1 configd[34]: 0 : State:/Network/Interface/
en2/Link
Aug 6 21:17:09 apache1 configd[34]: } (1)
Aug 6 21:17:36 apache1 configd[34]: hostname (multicast DNS) = apache1
Aug 6 21:17:36 apache1 ntpdate[113]: getnetnum: "time.apple.com"
invalid host number, line ignored
Aug 6 21:17:36 apache1 ntpdate[113]: no servers can be used, exiting
Aug 6 21:17:36 apache1 ntpd[227]: ntpd email@hidden Sun Mar 20
15:40:56 PST 2005 (1)
Aug 6 21:17:36 apache1 ntpd[227]: precision = 6 usec
Aug 6 21:17:36 apache1 configd[34]: target=enable-network: exit
status = 1
Aug 6 21:17:36 apache1 configd[34]: target=enable-network: disabled
Aug 6 21:17:38 apache1 ntpd_initres[234]: couldn't resolve
`time.apple.com', giving up on it
Aug 6 21:17:54 apache1 /System/Library/CoreServices/RemoteManagement/
ARDAgent.app/Contents/MacOS/ARDAgent: 'mb20' (10.118.2.133) started
ARD administration
Aug 6 21:19:23 apache1 launchproxy[245]: /usr/libexec/sshd-keygen-
wrapper: Connection from: 10.118.2.133 on port: 60879
Aug 6 21:19:28 apache1 com.apple.SecurityServer: authinternal
authenticated user mb20 (uid 501).
Aug 6 21:19:28 apache1 com.apple.SecurityServer: Succeeded
authorizing right system.login.tty by process /usr/sbin/sshd for
authorization created by /usr/sbin/sshd.
Aug 6 21:19:28 apache1 sshd[246]: Accepted keyboard-interactive/pam
for mb20 from 10.118.2.133 port 60879 ssh2
apache1:~ mb20$
_______________________________________________
Do not post admin requests to the list. They will be ignored.
Darwin-dev mailing list (email@hidden)
Help/Unsubscribe/Update your Subscription:
This email sent to email@hidden