Jump to content

DNS issues with OS X 10.6.6


Brian Barr
 Share

Recommended Posts

I just installed SnomOne version 2011-4.2.0.3981 on a new mac mini running 10.6.6

 

I am having major issues with setting up a sip trunk to voip.ms.

 

It appears that the issue is related to DNS resolving..

 

Here's a system log:

 

7] 2011/03/20 23:39:57: syslog is disabled on the PBX

[1] 2011/03/20 23:39:57: Starting up version 2011-4.2.0.3981

[7] 2011/03/20 23:39:57: 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

[8] 2011/03/20 23:39:57: Scheduler precision is 0 us

[1] 2011/03/20 23:39:57: Working Directory is /Applications/snomONE/conf

[7] 2011/03/20 23:39:57: UDP: Opening socket on 0.0.0.0

[7] 2011/03/20 23:39:57: UDP: Opening socket on [::]

[5] 2011/03/20 23:39:57: Starting threads

[7] 2011/03/20 23:39:57: UDP: Opening socket on 0.0.0.0

[8] 2011/03/20 23:39:57: SMTP: Connect to 192.168.4.75:25

[9] 2011/03/20 23:39:57: Resolve 1: url sip:chicago.voip.ms:5060

[5] 2011/03/20 23:39:57: Set scheduling priority to 15

[7] 2011/03/20 23:39:57: TCP: Opening socket on 0.0.0.0:80

[7] 2011/03/20 23:39:57: UDP: Opening socket on [::]

[3] 2011/03/20 23:39:57: Could not connect to 192.168.4.75:25

[9] 2011/03/20 23:39:57: Resolve 1: a udp chicago.voip.ms 5060

[8] 2011/03/20 23:39:57: DNS: Use DNS server fec0::ffff:0:0:0:1

[9] 2011/03/20 23:39:57: DNS: Request chicago.voip.ms from server fec0::ffff:0:0:0:1

[7] 2011/03/20 23:39:57: TCP: Opening socket on [::]:80

[7] 2011/03/20 23:39:57: TCP: Opening socket on 0.0.0.0:443

[7] 2011/03/20 23:39:57: TCP: Opening socket on [::]:443

[7] 2011/03/20 23:39:57: TCP: Opening socket on 0.0.0.0:389

[7] 2011/03/20 23:39:57: TCP: Opening socket on [::]:389

[7] 2011/03/20 23:39:57: UDP: Opening socket on 0.0.0.0:161

[7] 2011/03/20 23:39:57: UDP: Opening socket on [::]:161

[7] 2011/03/20 23:39:57: UDP: Opening socket on 0.0.0.0:5060

[7] 2011/03/20 23:39:57: UDP: Opening socket on 0.0.0.0:69

[8] 2011/03/20 23:39:57: Joined multicast group 224.0.1.75

[7] 2011/03/20 23:39:57: UDP: Opening socket on [::]:5060

[1] 2011/03/20 23:39:57: UDP: TOS could not be set

[7] 2011/03/20 23:39:57: TCP: Opening socket on 0.0.0.0:5060

[7] 2011/03/20 23:39:57: TCP: Opening socket on [::]:5060

[7] 2011/03/20 23:39:57: TCP: Opening socket on 0.0.0.0:5061

[7] 2011/03/20 23:39:57: TCP: Opening socket on [::]:5061

[7] 2011/03/20 23:39:57: UDP: Opening socket on [::]:69

[8] 2011/03/20 23:40:18: DNS: Add A chicago.voip.ms (ttl=60)

[9] 2011/03/20 23:40:18: Resolve 1: a udp chicago.voip.ms 5060

[9] 2011/03/20 23:40:18: Resolve 1: aaaa udp chicago.voip.ms 5060

[9] 2011/03/20 23:40:18: DNS: Request chicago.voip.ms from server fec0::ffff:0:0:0:1

[8] 2011/03/20 23:40:37: DNS: Add AAAA chicago.voip.ms (ttl=60)

[4] 2011/03/20 23:40:37: Could not find packet with number 1

[9] 2011/03/20 23:41:18: Remote site 192.168.4.140 closed the connection

[8] 2011/03/20 23:41:18: DNS: A chicago.voip.ms expired

 

It appears the pbx software is trying to use a dns server at fec0::ffff:0:0:0:1 (an ipv6 address) that does not exist.

 

If I put the actual ip address of chicago.voip.ms into the trunk sip configuration in lieu of the dns address (chicago.voip.ms) all is well.

 

So how do you completely disable ipv6 in this software? Or how do you force it to use (only) an ipv4 address for a dns server?

 

I also tried editing pbx.xml and changing the entry "<dns_server1>192.168.4.1</dns_server1>" to 8.8.8.8 (google's public dns server) -- does not work either.

 

This seems to be a serious bug in the code. As it stands, the software cannot in any way use ipv4 dns while running on 10.6.6 -- it may work on an earlier version of OS X.

 

I don't know.

 

 

I also tried disabling ipv6 in the mini's network settings -- same result.

 

Anyone else have any ideas on how to fix this?

Link to comment
Share on other sites

First of all, what is in your /etc/resolv.conf? That is where the PBX is reading the DNS configuration from. Looks like it has problems reading this file. If it cannot read the DNS servers, it starts to use some well-known addresses, including the one that you see above.

 

If you want to disable IPv6, you can use the option "--ipv6 false" in the script that starts up the PBX.

 

 

Link to comment
Share on other sites

Still not working..

 

Here's the /etc/resolv.conf file:

 

#

# Mac OS X Notice

#

# This file is not used by the host name and address resolution

# or the DNS query routing mechanisms used by most processes on

# this Mac OS X system.

#

# This file is automatically generated.

#

nameserver 192.168.4.1

 

 

I modified the /library/launchdaemons/com.snom.sanomone.plist file per your suggestions (for disabling ipv6) to :

 

<?xml version="1.0" encoding="UTF-8"?>

<!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">

<plist version="1.0">

<dict>

<key>Disabled</key>

<false/>

<key>GroupName</key>

<string>admin</string>

<key>KeepAlive</key>

<true/>

<key>Label</key>

<string>com.snom.snomone</string>

<key>OnDemand</key>

<false/>

<key>ProgramArguments</key>

<array>

<string>/Applications/snomONE/bin/pbxctrl-darwin9.0</string>

<string>--no-daemon</string>

<string>--ipv6</string>

<string>false</string>

<string>--dir</string>

<string>/Applications/snomONE/conf/</string>

</array>

<key>RunAtLoad</key>

<true/>

<key>StartOnMount</key>

<false/>

<key>UserName</key>

<string>root</string>

</dict>

</plist>

 

Ater a reboot it still does not work, except now the pbx software is trying to find a dns server at 127.0.0.1....(I suppose if I installed dnsmasqd on my local machine it might work..)

 

 

7] 2011/03/21 10:48:40: syslog is disabled on the PBX

[1] 2011/03/21 10:48:40: Starting up version 2011-4.2.0.3981

[7] 2011/03/21 10:48:40: 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

[8] 2011/03/21 10:48:40: Scheduler precision is 0 us

[1] 2011/03/21 10:48:40: Working Directory is /Applications/snomONE/conf

[7] 2011/03/21 10:48:40: UDP: Opening socket on 0.0.0.0

[5] 2011/03/21 10:48:40: Starting threads

[7] 2011/03/21 10:48:40: UDP: Opening socket on 0.0.0.0

[7] 2011/03/21 10:48:40: UDP: Opening socket on 0.0.0.0:5060

[8] 2011/03/21 10:48:40: Joined multicast group 224.0.1.75

[7] 2011/03/21 10:48:40: TCP: Opening socket on 0.0.0.0:5060

[7] 2011/03/21 10:48:40: TCP: Opening socket on 0.0.0.0:5061

[5] 2011/03/21 10:48:40: Set scheduling priority to 15

[8] 2011/03/21 10:48:40: SMTP: Connect to 192.168.4.75:25

[3] 2011/03/21 10:48:40: Could not connect to 192.168.4.75:25

[9] 2011/03/21 10:48:40: Resolve 1: url sip:chicago.voip.ms:5060

[9] 2011/03/21 10:48:40: Resolve 1: a udp chicago.voip.ms 5060

[8] 2011/03/21 10:48:40: DNS: Use DNS server 127.0.0.1

[9] 2011/03/21 10:48:40: DNS: Request chicago.voip.ms from server 127.0.0.1

[7] 2011/03/21 10:48:40: TCP: Opening socket on 0.0.0.0:80

[7] 2011/03/21 10:48:40: TCP: Opening socket on 0.0.0.0:443

[7] 2011/03/21 10:48:40: TCP: Opening socket on 0.0.0.0:389

[7] 2011/03/21 10:48:40: UDP: Opening socket on 0.0.0.0:161

[7] 2011/03/21 10:48:40: UDP: Opening socket on 0.0.0.0:69

[8] 2011/03/21 10:49:00: DNS: Add A chicago.voip.ms (ttl=60)

[9] 2011/03/21 10:49:00: Resolve 1: a udp chicago.voip.ms 5060

[9] 2011/03/21 10:49:00: Resolve 2: url sip:chicago.voip.ms:5060

[9] 2011/03/21 10:49:00: Resolve 2: a udp chicago.voip.ms 5060

[6] 2011/03/21 10:49:00: Could not determine destination address on 2

[5] 2011/03/21 10:49:32: Registration on trunk 2 (VOIP.MS) failed. Retry in 60 seconds

[2] 2011/03/21 10:49:32: Trunk status VOIP.MS (2) changed to "408 Request Timeout" (Registration failed, retry after 60 seconds)

 

 

I would say this software is not usable on OS X at present. This seems to be a major bug.

Link to comment
Share on other sites

If I put the IP address of chicago.voip.ms in the trunk config, all seems well (certain info redacted with XXXXXX), although the some stuff in the sip headers still points to 127.0.0.1

 

 

7] 2011/03/21 10:56:27: syslog is disabled on the PBX

[1] 2011/03/21 10:56:27: Starting up version 2011-4.2.0.3981

[7] 2011/03/21 10:56:27: 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

[8] 2011/03/21 10:56:27: Scheduler precision is 0 us

[1] 2011/03/21 10:56:27: Working Directory is /Applications/snomONE/conf

[7] 2011/03/21 10:56:28: UDP: Opening socket on 0.0.0.0

[5] 2011/03/21 10:56:28: Starting threads

[5] 2011/03/21 10:56:28: Set scheduling priority to 15

[7] 2011/03/21 10:56:28: UDP: Opening socket on 0.0.0.0

[7] 2011/03/21 10:56:28: UDP: Opening socket on 0.0.0.0:5060

[8] 2011/03/21 10:56:28: Joined multicast group 224.0.1.75

[7] 2011/03/21 10:56:28: TCP: Opening socket on 0.0.0.0:5060

[7] 2011/03/21 10:56:28: TCP: Opening socket on 0.0.0.0:5061

[8] 2011/03/21 10:56:28: SMTP: Connect to 192.168.4.75:25

[3] 2011/03/21 10:56:28: Could not connect to 192.168.4.75:25

[9] 2011/03/21 10:56:28: Resolve 1: url sip:64.120.22.242:5060

[9] 2011/03/21 10:56:28: Resolve 1: udp 64.120.22.242 5060

[8] 2011/03/21 10:56:28: Trunk 2 (VOIP.MS) is associated with the following addresses: udp:64.120.22.242:5060

[9] 2011/03/21 10:56:28: Resolve 2: url sip:64.120.22.242:5060

[9] 2011/03/21 10:56:28: Resolve 2: udp 64.120.22.242 5060

[5] 2011/03/21 10:56:28: SIP Tx udp:64.120.22.242:5060:

REGISTER sip:voip.ms SIP/2.0

Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-f1614440e6a6b8045bef3d6d92e32d27;rport

From: "XXXXXX" <sip:XXXXXX@voip.ms>;tag=735581657

To: "XXXXXX" <sip:XXXXXX@voip.ms>

Call-ID: aerpcyrb@pbx

CSeq: 28683 REGISTER

Max-Forwards: 70

Contact: <sip:XXXXXX@127.0.0.1:5060;transport=udp;line=c81e728d>;+sip.instance="<urn:uuid:36ea4f19-125f-4c6a-8918-86fa9bfe5fe4>"

User-Agent: snom-PBX/2011-4.2.0.3981

Supported: outbound

Expires: 60

Content-Length: 0

 

[7] 2011/03/21 10:56:28: TCP: Opening socket on 0.0.0.0:80

[7] 2011/03/21 10:56:28: TCP: Opening socket on 0.0.0.0:443

[7] 2011/03/21 10:56:28: TCP: Opening socket on 0.0.0.0:389

[7] 2011/03/21 10:56:28: UDP: Opening socket on 0.0.0.0:161

[7] 2011/03/21 10:56:28: UDP: Opening socket on 0.0.0.0:69

[5] 2011/03/21 10:56:28: SIP Tr udp:64.120.22.242:5060:

REGISTER sip:voip.ms SIP/2.0

Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-f1614440e6a6b8045bef3d6d92e32d27;rport

From: "XXXXXX" <sip:XXXXXX@voip.ms>;tag=735581657

To: "XXXXXX" <sip:XXXXXX@voip.ms>

Call-ID: aerpcyrb@pbx

CSeq: 28683 REGISTER

Max-Forwards: 70

Contact: <sip:XXXXXX@127.0.0.1:5060;transport=udp;line=c81e728d>;+sip.instance="<urn:uuid:36ea4f19-125f-4c6a-8918-86fa9bfe5fe4>"

User-Agent: snom-PBX/2011-4.2.0.3981

Supported: outbound

Expires: 60

Content-Length: 0

Link to comment
Share on other sites

Hmm... The PBX reads the resolv.conf during the start up. Maybe it is not there when that happens? Can you check anyhow if the DHCP client is slower than the PBX startup? Maybe give it a quick shot with a static IP configuration where the /etc/resolv.con is written 100 % before the PBX starts up?

Link to comment
Share on other sites

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

Link to comment
Share on other sites

I installed "macports" and then dnsmasq to see if that might work as a proxy --

 

The porting folks were nice enough to include a working launchd xml plist file that with the proper syntax that starts dnsmasq AFTER the network gets going.

 

-- the operative stuff is to include this:

 

<dict>

<key>NetworkState</key>

<true/>

</dict>

 

 

 

From what I have found out, launchd will fire up a task that has this included if there are any network interfaces that are up and assigned a valid IP address (v4 or v6).

I am unsure of exactly how this works but it seems to.

 

 

While this is not in my opinion a final or robust fix, it is a workable one.

 

Here's a /library/launchdaemons/com.snom.sanomone.plist file that works -

 

(Note I have disabled ipv6 here -- others may not wish to do so)

 

<?xml version="1.0" encoding="UTF-8"?>

<!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">

<plist version="1.0">

<dict>

<key>Disabled</key>

<false/>

<key>GroupName</key>

<string>admin</string>

<key>KeepAlive</key>

<dict>

<key>NetworkState</key>

<true/>

</dict>

<key>Label</key>

<string>com.snom.snomone</string>

<key>ProgramArguments</key>

<array>

<string>/Applications/snomONE/bin/pbxctrl-darwin9.0</string>

<string>--no-daemon</string>

<string>--ipv6</string>

<string>false</string>

<string>--dir</string>

<string>/Applications/snomONE/conf/</string>

</array>

<key>StartOnMount</key>

<false/>

<key>UserName</key>

<string>root</string>

</dict>

</plist>

 

 

The software really needs to retry grabbing a DNS server every so often is it does not have valid one from the OS list. That would stop all of this nonsense and not

require a fragile timing hack to make it work.

Link to comment
Share on other sites

Just another note on all of this ..

 

I discovered another scenario whereby the pbx software is 86'd "forever" by the lack of a dns server at startup -- even using the "delay until network ready" launchd technique previously outlined:

 

If the pbx system is connected to a switch and starting up and the dns server (router or whatever) is somehow not talking to said switch (maybe powered down or restarting) while the pbx software is loading the pbx software will fail to find a dns server and loop in this error condition "forever" -- even after the dns server later becomes available.

 

 

This is not a robust behavior for a mission-critical piece of software. IMO - It should recover gracefully from error conditions like this..

Link to comment
Share on other sites

Join the conversation

You can post now and register later. If you have an account, sign in now to post with your account.
Note: Your post will require moderator approval before it will be visible.

Guest
Reply to this topic...

×   Pasted as rich text.   Paste as plain text instead

  Only 75 emoji are allowed.

×   Your link has been automatically embedded.   Display as a link instead

×   Your previous content has been restored.   Clear editor

×   You cannot paste images directly. Upload or insert images from URL.

 Share

×
×
  • Create New...