site_archiver@lists.apple.com Delivered-To: darwin-dev@lists.apple.com RetryCount = 60 LinkInactiveWaitTimeSeconds = 60 MaximumRetryTimeSeconds = 60 relevant boot-time syslog output: _______________________________________________ Do not post admin requests to the list. They will be ignored. Darwin-dev mailing list (Darwin-dev@lists.apple.com) Help/Unsubscribe/Update your Subscription: http://lists.apple.com/mailman/options/darwin-dev/site_archiver%40lists.appl... 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: 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. 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 4.1.1@1.786 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 4.1.1@1.786 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$ This email sent to site_archiver@lists.apple.com