Ok -- A little more fiddling yields the following:
Changing to a static IP with a designated DNS server does not fix the problem.
Deleting all other network interfaces from the mac (i.e. Airport) does not solve the problem.
The problem seems to be that the PBX comes up too rapidly before the Mac's network configuration has stabilized so we seem to have a launchd configuration issue.
I "bounced" the pbx by killing the task using the activity monitor utility. Launchd then fired it right back up, automatically. When the pbx task came back up,
everything worked normally with no issues regarding dns resolution.
So it appears we have a timing and network "stability" dependency issue here with how the pbx is first started up. The pbx software should be patched to periodically retry grabbing the dns server
if it cannot find one out of the gate at startup. As it stands, it tries once and if not found, it spins forever in that state. Not good.
I found this out there:
http://lists.apple.com/archives/unix-porting/2010/Feb/msg00001.html
that deals with this issue, but it is beyond the scope of what I really want to dig into.
Bottom line, the software as it sits will not work reliably on OS X.
3/21/11 3:00:04 PM kernel npvhash=4095
3/21/11 2:59:49 PM WindowServer[72] bootstrap_look_ip failed: Unknown service name
3/21/11 3:00:04 PM kernel PAE enabled
3/21/11 3:00:04 PM kernel 64 bit mode enabled
3/21/11 3:00:04 PM kernel Darwin Kernel Version 10.6.0: Wed Nov 10 18:13:17 PST 2010; root:xnu-1504.9.26~3/RELEASE_I386
3/21/11 3:00:04 PM kernel vm_page_bootstrap: 441336 free pages and 17416 wired pages
3/21/11 3:00:04 PM kernel standard timeslicing quantum is 10000 us
3/21/11 3:00:04 PM kernel mig_table_max_displ = 73
3/21/11 3:00:04 PM kernel AppleACPICPU: ProcessorId=0 LocalApicId=0 Enabled
3/21/11 3:00:04 PM kernel AppleACPICPU: ProcessorId=1 LocalApicId=1 Enabled
3/21/11 3:00:04 PM kernel calling mpo_policy_init for TMSafetyNet
3/21/11 3:00:04 PM kernel Security policy loaded: Safety net for Time Machine (TMSafetyNet)
3/21/11 3:00:04 PM kernel calling mpo_policy_init for Quarantine
3/21/11 3:00:04 PM kernel Security policy loaded: Quarantine policy (Quarantine)
3/21/11 3:00:04 PM kernel calling mpo_policy_init for Sandbox
3/21/11 3:00:04 PM kernel Security policy loaded: Seatbelt sandbox policy (Sandbox)
3/21/11 3:00:04 PM kernel Copyright © 1982, 1986, 1989, 1991, 1993
3/21/11 3:00:04 PM kernel The Regents of the University of California. All rights reserved.
3/21/11 3:00:04 PM kernel MAC Framework successfully initialized
3/21/11 3:00:04 PM kernel using 9175 buffer headers and 4096 cluster IO buffer headers
3/21/11 3:00:04 PM kernel IOAPIC: Version 0x11 Vectors 64:87
3/21/11 3:00:04 PM kernel ACPI: System State [s0 S3 S4 S5] (S3)
3/21/11 3:00:04 PM kernel AppleIntelCPUPowerManagement: initialization complete
3/21/11 3:00:04 PM kernel mbinit: done (64 MB memory set for mbuf pool)
3/21/11 3:00:04 PM kernel rooting via boot-uuid from /chosen: 94DDF50F-C2C9-33D9-B330-9EBC5F4520C1
3/21/11 3:00:04 PM kernel Waiting on <dict ID="0"><key>IOProviderClass</key><string ID="1">IOResources</string><key>IOResourceMatch</key><string ID="2">boot-uuid-media</string></dict>
3/21/11 3:00:04 PM kernel com.apple.AppleFSCompressionTypeZlib kmod start
3/21/11 3:00:04 PM kernel com.apple.AppleFSCompressionTypeZlib load succeeded
3/21/11 3:00:04 PM kernel BTCOEXIST off
3/21/11 3:00:04 PM kernel wl0: Broadcom BCM4353 802.11 Wireless Controller
3/21/11 3:00:04 PM kernel 5.10.131.36
3/21/11 3:00:04 PM kernel AppleIntelCPUPowerManagementClient: ready
3/21/11 3:00:04 PM kernel Got boot device = IOService:/AppleACPIPlatformExpert/PCI0@0/AppleACPIPCI/SATA@A/AppleMCP89AHCI/PRT0@0/IOAHCIDevice@0/AppleAHCIDiskDriver/IOAHCIBlockStorageDevice/IOBlockStorageDriver/TOSHIBA MK3255GSXF Media/IOGUIDPartitionScheme/Customer@2
3/21/11 3:00:04 PM kernel BSD root: disk0s2, major 14, minor 2
3/21/11 3:00:04 PM kernel FireWire (OHCI) TI ID 823f built-in now active, GUID e80688fffec5c274; max speed s800.
3/21/11 2:59:58 PM com.apple.launchd[1] *** launchd[1] has started up. ***
3/21/11 3:00:05 PM kernel BCM5701Enet: Ethernet address c4:2c:03:04:be:b6
3/21/11 3:00:05 PM kernel AirPort_Brcm43224: Ethernet address 60:33:4b:0d:39:d8
3/21/11 3:00:05 PM kernel IO80211Controller::dataLinkLayerAttachComplete(): adding AppleEFINVRAM notification
3/21/11 3:00:05 PM kernel IO80211Interface::efiNVRAMPublished():
3/21/11 3:00:05 PM kernel systemShutdown false
3/21/11 3:00:05 PM mDNSResponder[19] mDNSResponder mDNSResponder-258.14 (Nov 18 2010 14:18:32) starting
3/21/11 3:00:05 PM blued[16] Apple Bluetooth daemon started
3/21/11 3:00:06 PM com.apple.SecurityServer[23] Session 0x5fbff962 created
3/21/11 3:00:06 PM com.apple.SecurityServer[23] Entering service
3/21/11 3:00:07 PM kernel Previous Shutdown Cause: 0
3/21/11 3:00:07 PM kernel NVDANV50HAL loaded and registered.
3/21/11 3:00:07 PM kernel DSMOS has arrived
3/21/11 3:00:16 PM bootlog[47] BOOT_TIME: 1300733998 0
3/21/11 3:00:18 PM kernel AirPort: Link Down on en1. Reason 8 (Disassociated because station leaving).
3/21/11 3:00:19 PM com.apple.usbmuxd[29] usbmuxd-211 built on Jan 13 2011 at 04:20:21 on Jan 13 2011 at 04:20:21, running 64 bit
3/21/11 3:00:19 PM com.snom.snomone[51] Starting up
3/21/11 3:00:19 PM PBX[51] PBX: Starting up
3/21/11 3:00:19 PM /System/Library/CoreServices/loginwindow.app/Contents/MacOS/loginwindow[36] Login Window Application Started
3/21/11 3:00:20 PM com.apple.SecurityServer[23] Session 0x400f81 created
3/21/11 3:00:20 PM com.apple.SecurityServer[23] Session 0x400f81 attributes 0x30
3/21/11 3:00:21 PM loginwindow[36] Login Window Started Security Agent
3/21/11 3:00:21 PM SecurityAgent[88] User info context values set for tangalos
3/21/11 3:00:21 PM configd[14] bootp_session_transmit: bpf_write(en1) failed: Network is down (50)
3/21/11 3:00:21 PM configd[14] DHCP en1: INIT transmit failed
3/21/11 3:00:21 PM configd[14] network configuration changed.
3/21/11 3:00:21 PM configd[14] setting hostname to "pstphone.local"
3/21/11 3:00:21 PM SecurityAgent[88] Login Window login proceeding
3/21/11 3:00:21 PM SecurityAgent[88] Login Window done
3/21/11 3:00:21 PM loginwindow[36] Login Window - Returned from Security Agent
3/21/11 3:00:21 PM loginwindow[36] USER_PROCESS: 36 console
3/21/11 3:00:21 PM com.apple.UserEventAgent-LoginWindow[79] ALF error: cannot find useragent 1102
3/21/11 3:00:21 PM com.apple.UserEventAgent-LoginWindow[79] plugin.UserEventAgentFactory: called with typeID=FC86416D-6164-2070-726F-70735C216EC0
3/21/11 3:00:21 PM com.apple.UserEventAgent-LoginWindow[79] plugin.UserEventAgentFactory: called with typeID=FC86416D-6164-2070-726F-70735C216EC0
3/21/11 3:00:21 PM com.apple.launchd.peruser.501[90] (com.apple.ReportCrash) Falling back to default Mach exception handler. Could not find: com.apple.ReportCrash.Self
3/21/11 3:00:22 PM com.snom.snomone[51] [7] 20110321150021: syslog is disabled on the PBX
3/21/11 3:00:22 PM com.snom.snomone[51] [1] 20110321150021: Starting up version 2011-4.2.0.3981
3/21/11 3:00:22 PM com.snom.snomone[51] [7] 20110321150021: Found time zones HST AKDT AKST PDT PST MDT MST CDT CST2 EDT EST ADT AST NDT NST BST CET GMT+2 TUR GMT+3 GMT+4 GMT+5 GMT+6 GMT+7 GMT+8 GMT+9 CST CAT IST AUS1 AUS2 AUS3 AUS4 AUS5 AUS6 NZST CHAST GMT
3/21/11 3:00:22 PM com.snom.snomone[51] [8] 20110321150021: Scheduler precision is 0 us
3/21/11 3:00:22 PM com.snom.snomone[51] [1] 20110321150021: Working Directory is /Applications/snomONE/conf
3/21/11 3:00:22 PM com.snom.snomone[51] [7] 20110321150022: UDP: Opening socket on 0.0.0.0
3/21/11 3:00:22 PM com.snom.snomone[51] [5] 20110321150022: Starting threads
3/21/11 3:00:22 PM com.snom.snomone[51] [7] 20110321150022: UDP: Opening socket on 0.0.0.0
3/21/11 3:00:22 PM com.snom.snomone[51] [7] 20110321150022: UDP: Opening socket on 0.0.0.0:5060
3/21/11 3:00:22 PM com.snom.snomone[51] [8] 20110321150022: Joined multicast group 224.0.1.75
3/21/11 3:00:22 PM com.snom.snomone[51] [7] 20110321150022: TCP: Opening socket on 0.0.0.0:5060
3/21/11 3:00:22 PM com.snom.snomone[51] [7] 20110321150022: TCP: Opening socket on 0.0.0.0:5061
3/21/11 3:00:22 PM com.snom.snomone[51] [5] 20110321150022: Set scheduling priority to 15
3/21/11 3:00:22 PM com.snom.snomone[51] [3] 20110321150022: Could not connect to 192.168.4.75:25
3/21/11 3:00:22 PM com.snom.snomone[51] [7] 20110321150022: TCP: Opening socket on 0.0.0.0:80
3/21/11 3:00:22 PM com.snom.snomone[51] [7] 20110321150022: TCP: Opening socket on 0.0.0.0:443
3/21/11 3:00:22 PM com.snom.snomone[51] [7] 20110321150022: TCP: Opening socket on 0.0.0.0:389
3/21/11 3:00:22 PM com.snom.snomone[51] [7] 20110321150022: UDP: Opening socket on 0.0.0.0:161
3/21/11 3:00:22 PM com.snom.snomone[51] [7] 20110321150022: UDP: Opening socket on 0.0.0.0:69
3/21/11 3:00:22 PM com.snom.snomone[51] [8] 20110321150022: DNS: Use DNS server 127.0.0.1
3/21/11 3:00:23 PM com.apple.launchd.peruser.501[90] (com.apple.Kerberos.renew.plist[113]) Exited with exit code: 1
3/21/11 3:00:27 PM kernel AppleBCM5701Ethernet: 0 0 setFixedSpeed - logic error, speed any?
3/21/11 3:00:32 PM com.snom.snomone[51] [5] 20110321150032: Table cdrt: Finished reading 1 rows
3/21/11 3:00:32 PM com.snom.snomone[51] [5] 20110321150032: Table cdri: Finished reading 1 rows
3/21/11 3:00:40 PM kernel Ethernet [AppleBCM5701Ethernet]: Link up on en0, 100-Megabit, Full-duplex, Symmetric flow-control, Debug [79ed,0300,0de1,0300,4de1,0000]
3/21/11 3:00:41 PM configd[14] network configuration changed.
3/21/11 3:00:41 PM configd[14] setting hostname to "pstpbx"
3/21/11 3:00:42 PM com.snom.snomone[51] [8] 20110321150042: DNS: Add A chicago.voip.ms (ttl=60)
3/21/11 3:00:42 PM com.snom.snomone[51] [6] 20110321150042: Could not determine destination address on 2
3/21/11 3:01:14 PM com.snom.snomone[51] [5] 20110321150114: Registration on trunk 2 (VOIP.MS) failed. Retry in 60 seconds
3/21/11 3:01:14 PM com.snom.snomone[51] [2] 20110321150114: Trunk status VOIP.MS (2) changed to "408 Request Timeout" (Registration failed, retry after 60 seconds)
3/21/11 3:01:42 PM com.snom.snomone[51] [8] 20110321150142: DNS: A chicago.voip.ms expired
3/21/11 3:02:33 PM com.snom.snomone[51] [8] 20110321150233: DNS: Add A chicago.voip.ms (ttl=60)
3/21/11 3:02:33 PM com.snom.snomone[51] [6] 20110321150233: Could not determine destination address on 4
3/21/11 3:03:05 PM com.snom.snomone[51] [5] 20110321150305: Registration on trunk 2 (VOIP.MS) failed. Retry in 60 seconds
3/21/11 3:03:33 PM com.snom.snomone[51] [8] 20110321150333: DNS: A chicago.voip.ms expired
3/21/11 3:04:25 PM com.snom.snomone[51] [8] 20110321150425: DNS: Add A chicago.voip.ms (ttl=60)
3/21/11 3:04:25 PM com.snom.snomone[51] [6] 20110321150425: Could not determine destination address on 6
3/21/11 3:04:57 PM com.snom.snomone[51] [5] 20110321150457: Registration on trunk 2 (VOIP.MS) failed. Retry in 60 seconds
3/21/11 3:05:16 PM /System/Library/CoreServices/RemoteManagement/AppleVNCServer.bundle/Contents/MacOS/AppleVNCServer[119] CGSKeyTranslateInitialize: KLGetCurrentKeyboardLayout or KLGetKeyboardLayoutProperty is not available, fall back to USA keymap
3/21/11 3:05:20 PM activitymonitord[149] sending signal: 15 to pid: 51
3/21/11 3:05:20 PM com.apple.launchd[1] (com.snom.snomone[51]) Exited: Terminated
3/21/11 3:05:20 PM com.snom.snomone[152] Starting up
3/21/11 3:05:20 PM PBX[152] PBX: Starting up
3/21/11 3:05:20 PM com.snom.snomone[152] [7] 20110321150520: syslog is disabled on the PBX
3/21/11 3:05:20 PM com.snom.snomone[152] [1] 20110321150520: Starting up version 2011-4.2.0.3981
3/21/11 3:05:20 PM com.snom.snomone[152] [7] 20110321150520: Found time zones HST AKDT AKST PDT PST MDT MST CDT CST2 EDT EST ADT AST NDT NST BST CET GMT+2 TUR GMT+3 GMT+4 GMT+5 GMT+6 GMT+7 GMT+8 GMT+9 CST CAT IST AUS1 AUS2 AUS3 AUS4 AUS5 AUS6 NZST CHAST GMT
3/21/11 3:05:20 PM com.snom.snomone[152] [8] 20110321150520: Scheduler precision is 0 us
3/21/11 3:05:20 PM com.snom.snomone[152] [1] 20110321150520: Working Directory is /Applications/snomONE/conf
3/21/11 3:05:20 PM com.snom.snomone[152] [7] 20110321150520: UDP: Opening socket on 0.0.0.0
3/21/11 3:05:20 PM com.snom.snomone[152] [5] 20110321150520: Starting threads
3/21/11 3:05:20 PM com.snom.snomone[152] [7] 20110321150520: UDP: Opening socket on 0.0.0.0
3/21/11 3:05:20 PM com.snom.snomone[152] [7] 20110321150520: UDP: Opening socket on 0.0.0.0:5060
3/21/11 3:05:20 PM com.snom.snomone[152] [5] 20110321150520: Set scheduling priority to 15
3/21/11 3:05:20 PM com.snom.snomone[152] [8] 20110321150520: Joined multicast group 224.0.1.75
3/21/11 3:05:20 PM com.snom.snomone[152] [7] 20110321150520: TCP: Opening socket on 0.0.0.0:5060
3/21/11 3:05:20 PM com.snom.snomone[152] [7] 20110321150520: TCP: Opening socket on 0.0.0.0:5061
3/21/11 3:05:20 PM com.snom.snomone[152] [8] 20110321150520: DNS: Use DNS server 192.168.4.1
3/21/11 3:05:20 PM com.snom.snomone[152] [7] 20110321150520: TCP: Opening socket on 0.0.0.0:80
3/21/11 3:05:20 PM com.snom.snomone[152] [7] 20110321150520: TCP: Opening socket on 0.0.0.0:443
3/21/11 3:05:20 PM com.snom.snomone[152] [7] 20110321150520: TCP: Opening socket on 0.0.0.0:389
3/21/11 3:05:20 PM com.snom.snomone[152] [7] 20110321150520: UDP: Opening socket on 0.0.0.0:161
3/21/11 3:05:20 PM com.snom.snomone[152] [7] 20110321150520: UDP: Opening socket on 0.0.0.0:69
3/21/11 3:05:21 PM com.snom.snomone[152] [8] 20110321150521: DNS: Add A chicago.voip.ms 64.120.22.242 (ttl=188)
3/21/11 3:05:21 PM com.snom.snomone[152] [8] 20110321150521: Trunk 2 (VOIP.MS) is associated with the following addresses: udp:64.120.22.242:5060
3/21/11 3:05:21 PM com.snom.snomone[152] [8] 20110321150521: Answer challenge with username 107765_PSTLAW
3/21/11 3:05:21 PM com.snom.snomone[152] [2] 20110321150521: Trunk status VOIP.MS (2) changed to "200 OK" (Refresh interval 60 seconds)
3/21/11 3:05:30 PM com.snom.snomone[152] [5] 20110321150530: Table cdrt: Finished reading 1 rows
3/21/11 3:05:30 PM com.snom.snomone[152] [5] 20110321150530: Table cdri: Finished reading 1 rows