[Dnssec-trigger] Resolution on resume from hibernate (MacOS 10.8)

Phil Pennock dnssec-trigger+phil at spodhuis.org
Sat Mar 23 23:13:27 UTC 2013


Seen this twice, don't know enough about Mac internals to track it down,
but now that it's recurred, I can perhaps prod further in future.

MacOS 10.8.3 on a laptop.

When I resume from sleep/hibernate, sometimes I am now missing
functioning DNS resolution.  At least this time, Chrome was running
still, so the resume resulted in a Gmail tab failing to talk to the
server.  This *might* affect open communication paths to the system
resolver?

I have functioning network, I can ping something outside (8.8.8.8),
"sudo unbound-control forward" shows the forwarding is there, pointing
to the local router gateway (which in turn runs unbound).  Turning off
WiFi and turning it back on, does not fix it.  "sudo unbound-control
reload" (and then restoring the forward) does not fix it.  "Fix" is
defined as "ping www.google.com" in a Terminal is able to resolve the
hostname.

Only fix is:

  sudo killall -v mDNSResponder

At which point, everything gets back DNS resolution ability.

This problem started with dnssec-trigger being installed.

Anyone have ideas about what might be going on?  Failing that,
suggestions for things to look at, the next time, to better diagnose?

DNSSec-Trigger installed:
% pkgutil --pkgs | fgrep -i dnssec
nl.nlnetlabsdnssectrigger011ForMacosX10.7.Package_Root.pkg

An extract from system.log is attached, including some dnssec-trigger
failures.  I suspect that this is a clue:

----------------------------8< cut here >8------------------------------
Mar 23 18:39:18 ilmenite com.apple.launchd[1] (nl.nlnetlabs.dnssec-trigger-hook): Throttling respawn: Will start in 1 seconds
----------------------------8< cut here >8------------------------------

Thanks,
-Phil
-------------- next part --------------
Mar 22 23:29:00 ilmenite kernel[0]: hibernate image major 1, minor 0, blocksize 512, pollers 5
Mar 23 18:39:03 ilmenite kernel[0]: hibernate_page_list_setall(preflight 0) start 0xffffff8101815000, 0xffffff8101855000
Mar 23 18:39:03 ilmenite kernel[0]: hibernate_page_list_setall time: 465 ms
Mar 23 18:39:03 ilmenite kernel[0]: pages 1420868, wire 350507, act 519901, inact 545, cleaned 0 spec 662, zf 53520, throt 0, could discard act 135141 inact 165746 purgeable 13752 spec 181094 cleaned 0
Mar 23 18:39:03 ilmenite kernel[0]: hibernate_page_list_setall found pageCount 925135
Mar 23 18:39:03 ilmenite kernel[0]: IOHibernatePollerOpen, ml_get_interrupts_enabled 0
Mar 23 18:39:03 ilmenite kernel[0]: IOHibernatePollerOpen(0)
Mar 23 18:39:03 ilmenite kernel[0]: encryptStart 13270
Mar 23 18:39:03 ilmenite kernel[0]: bitmap_size 0x3f4d8, previewSize 0x271388, writing 924163 pages @ 0x2c3ad0
Mar 23 18:39:03 ilmenite kernel[0]: hibernate_machine_init: state 2, image pages 350303, sum was e492bb6b, image1Size 1a265400, conflictCount 3407, nextFree f592
Mar 23 18:39:03 ilmenite kernel[0]: hibernate_page_list_discard time: 278 ms, discarded act 135141 inact 165746 purgeable 13752 spec 181094 cleaned 0
Mar 23 18:39:03 ilmenite kernel[0]: IOHibernatePollerOpen(), ml_get_interrupts_enabled 0
Mar 23 18:39:03 ilmenite kernel[0]: IOHibernatePollerOpen(0)
Mar 23 18:39:03 ilmenite kernel[0]: hibernate_machine_init reading
Mar 23 18:39:03 ilmenite kernel[0]: PMStats: Hibernate read took 4657 ms
Mar 23 18:39:03 ilmenite kernel[0]: hibernate_machine_init pagesDone 924931 sum2 29e0f6a3, time: 4657 ms, comp bytes: 2192662528 time: 2070 ms 1010 Mb/s, crypt bytes: 1157109248 time: 444 ms 2482 Mb/s
Mar 23 18:39:03 ilmenite kernel[0]: vtd[0] fault: device 2:0:0 reason 0x5 W:0x8837b000
Mar 23 18:39:03 ilmenite kernel[0]: Wake reason: EC.LidOpen (User)
Mar 23 18:39:03 ilmenite kernel[0]: AirPort_Brcm43xx::powerChange: System Wake - Full Wake/ Dark Wake / Maintenance wake
Mar 23 18:39:03 ilmenite kernel[0]: AppleKeyStore::parseBootData: entry at offset 0
Mar 23 18:39:03 ilmenite kernel[0]: AppleKeyStore::parseBootData: setPassphrase() returned 0xe00002c2, skipping entry
Mar 23 18:39:03 ilmenite kernel[0]: AppleKeyStore::parseBootData: entry at offset 64
Mar 23 18:39:03 ilmenite kernel[0]: AppleKeyStore::parseBootData: setKey() returned 0xe00002c2, skipping entryPrevious Sleep Cause: 5
Mar 23 18:39:03 ilmenite kernel[0]: Previous Shutdown Cause: 5
Mar 23 18:39:03 ilmenite kernel[0]: wlEvent: en1 en1 Link DOWN virtIf = 0
Mar 23 18:39:03 ilmenite kernel[0]: AirPort: Link Down on en1. Reason 8 (Disassociated because station leaving).
Mar 23 18:39:03 ilmenite kernel[0]: en1::IO80211Interface::postMessage bssid changed
Mar 23 18:39:03 ilmenite kernel[0]: TBT W (1): 0 [x]
Mar 23 18:39:03 ilmenite.local WindowServer[103]: handle_will_sleep_auth_and_shield_windows: releasing authw 0x7fe1ccb068a0(2000), shield 0x7fe1ca218040(2001), lock state 4
Mar 23 18:39:03 ilmenite.local WindowServer[103]: handle_will_sleep_auth_and_shield_windows: err 0x0
Mar 23 18:39:03 ilmenite.local WindowServer[103]: Created shield window 0xb3a4 for display 0x003f003d
Mar 23 18:39:03 ilmenite.local WindowServer[103]: handle_will_sleep_auth_and_shield_windows: releasing authw 0x7fe1ccb068a0(2002), shield 0x7fe1ca218040(2001), lock state 4
Mar 23 18:39:03 ilmenite.local WindowServer[103]: handle_will_sleep_auth_and_shield_windows: err 0x0
Mar 23 18:39:03 ilmenite.local WindowServer[103]: Created shield window 0xb3a5 for display 0x003f003e
Mar 23 18:39:03 ilmenite.local WindowServer[103]: handle_will_sleep_auth_and_shield_windows: releasing authw 0x7fe1ccb068a0(2002), shield 0x7fe1ca218040(2001), lock state 4
Mar 23 18:39:03 ilmenite.local WindowServer[103]: handle_will_sleep_auth_and_shield_windows: err 0x0
Mar 23 18:39:03 ilmenite.local WindowServer[103]: Created shield window 0xb3a6 for display 0x003f003f
Mar 23 18:39:03 ilmenite.local WindowServer[103]: handle_will_sleep_auth_and_shield_windows: releasing authw 0x7fe1ccb068a0(2002), shield 0x7fe1ca218040(2001), lock state 4
Mar 23 18:39:03 ilmenite.local WindowServer[103]: handle_will_sleep_auth_and_shield_windows: err 0x0
Mar 23 18:39:03 ilmenite com.apple.launchd[1] (com.apple.emond.aslmanager[31999]): Exited with code: 255
Mar 23 18:39:04 ilmenite.local configd[17]: network changed: v4(en1-:192.168.120.165) v6(en1-:fe80::28e:f2ff:fe5c:14c1) DNS- Proxy- SMB
Mar 23 18:39:04 ilmenite.local loginwindow[69]: resume called when there was already a timer
Mar 23 18:39:04 ilmenite.local UserEventAgent[325]: Could not get event name for stream/token: com.apple.time/30: 0x3: No such process
Mar 23 18:39:05 ilmenite.local _softwareupdate[32066]: dnssec-trigger(osx) detected  DNS 
Mar 23 18:39:05 ilmenite.local dnssec-triggerd[87]: [87] error: could not UDP send to ip 192.5.5.241
Mar 23 18:39:05 --- last message repeated 1 time ---
Mar 23 18:39:05 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:05 ilmenite.local dnssec-triggerd[87]: [87] error: could not UDP send to ip 2001:503:ba3e::2:30
Mar 23 18:39:05 --- last message repeated 1 time ---
Mar 23 18:39:05 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:05 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: No route to host
Mar 23 18:39:05 --- last message repeated 1 time ---
Mar 23 18:39:05 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:05 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: No route to host
Mar 23 18:39:05 --- last message repeated 1 time ---
Mar 23 18:39:05 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:05 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: No route to host
Mar 23 18:39:05 --- last message repeated 1 time ---
Mar 23 18:39:05 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:05 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: No route to host
Mar 23 18:39:05 --- last message repeated 1 time ---
Mar 23 18:39:05 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:05 ilmenite.local _eppc[32073]: dnssec-trigger-setdns to field.spodhuis.org and 127.0.0.1
Mar 23 18:39:05 ilmenite.local SubmitDiagInfo[32001]: Cleaning up expired diagnostic messages database at path: /var/log/DiagnosticMessages/2013.02.21.asl
Mar 23 18:39:05 ilmenite kernel[0]: IOBluetoothUSBDFU::probe
Mar 23 18:39:05 ilmenite kernel[0]: IOBluetoothUSBDFU::probe ProductID - 0x821D FirmwareVersion - 0x0100
Mar 23 18:39:05 ilmenite kernel[0]: [BroadcomBluetoothHCIControllerUSBTransport][start] -- completed
Mar 23 18:39:05 ilmenite kernel[0]: [IOBluetoothHCIController][staticBluetoothHCIControllerTransportShowsUp] -- Received Bluetooth Controller register service notification 
Mar 23 18:39:05 ilmenite kernel[0]: [IOBluetoothHCIController::setConfigState] calling registerService
Mar 23 18:39:05 ilmenite kernel[0]: AppleUSBMultitouchDriver::checkStatus - received Status Packet, Payload 2: device was reinitialized
Mar 23 18:39:06 ilmenite.local dnssec-triggerd[87]: [87] error: chmod(/etc/resolv.conf) failed: No such file or directory
Mar 23 18:39:06 ilmenite kernel[0]: MacAuthEvent en1   Auth result for: 00:8e:f2:5c:14:c3  MAC AUTH succeeded
Mar 23 18:39:06 ilmenite kernel[0]: wlEvent: en1 en1 Link UP virtIf = 0
Mar 23 18:39:06 ilmenite kernel[0]: AirPort: Link Up on en1
Mar 23 18:39:06 ilmenite kernel[0]: en1: BSSID changed to 00:8e:f2:5c:14:c3
Mar 23 18:39:06 ilmenite kernel[0]: en1::IO80211Interface::postMessage bssid changed
Mar 23 18:39:06 ilmenite kernel[0]: AirPort: RSN handshake complete on en1
Mar 23 18:39:06 ilmenite.local _softwareupdate[32130]: dnssec-trigger(osx) detected en1 DNS 
Mar 23 18:39:06 ilmenite.local dnssec-triggerd[87]: [87] error: could not UDP send to ip 192.203.230.10
Mar 23 18:39:06 --- last message repeated 1 time ---
Mar 23 18:39:06 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:06 ilmenite.local dnssec-triggerd[87]: [87] error: could not UDP send to ip 2001:7fd::1
Mar 23 18:39:06 --- last message repeated 1 time ---
Mar 23 18:39:06 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:06 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: No route to host
Mar 23 18:39:06 --- last message repeated 1 time ---
Mar 23 18:39:06 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:06 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: No route to host
Mar 23 18:39:06 --- last message repeated 1 time ---
Mar 23 18:39:06 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:06 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: No route to host
Mar 23 18:39:06 --- last message repeated 1 time ---
Mar 23 18:39:06 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:06 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: No route to host
Mar 23 18:39:06 --- last message repeated 1 time ---
Mar 23 18:39:06 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:06 ilmenite.local _eppc[32136]: dnssec-trigger-setdns to field.spodhuis.org and 127.0.0.1
Mar 23 18:39:06 ilmenite.local airportd[31951]: _doAutoJoin: Already associated to ?Pennock?. Bailing on auto-join.
Mar 23 18:39:06 --- last message repeated 2 times ---
Mar 23 18:39:06 ilmenite.local loginwindow[69]: in pam_sm_authenticate(): Got user: pdp
Mar 23 18:39:06 ilmenite.local loginwindow[69]: in pam_sm_authenticate(): Got ruser: pdp
Mar 23 18:39:06 ilmenite.local loginwindow[69]: in pam_sm_authenticate(): Got service: screensaver
Mar 23 18:39:06 ilmenite.local loginwindow[69]: in od_principal_for_user(): No authentication authority returned
Mar 23 18:39:06 ilmenite.local loginwindow[69]: in od_principal_for_user(): failed: 7
Mar 23 18:39:06 ilmenite.local loginwindow[69]: in pam_sm_authenticate(): Failed to determine Kerberos principal name.
Mar 23 18:39:06 ilmenite.local loginwindow[69]: in pam_sm_authenticate(): Done cleanup3
Mar 23 18:39:06 ilmenite.local loginwindow[69]: in pam_sm_authenticate(): Kerberos 5 refuses you
Mar 23 18:39:06 ilmenite.local su[32030]: in pam_sm_authenticate(): authentication succeeded
Mar 23 18:39:06 ilmenite.local su[32030]: in pam_sm_acct_mgmt(): The root_only option means root only.
Mar 23 18:39:06 ilmenite.local su[32030]: in pam_sm_acct_mgmt(): OpenDirectory - Membership cache TTL set to 1800.
Mar 23 18:39:06 ilmenite.local su[32030]: in od_record_check_pwpolicy(): retval: 0
Mar 23 18:39:07 ilmenite.local loginwindow[69]: in pam_sm_acct_mgmt(): OpenDirectory - Membership cache TTL set to 1800.
Mar 23 18:39:07 ilmenite.local loginwindow[69]: in od_record_check_pwpolicy(): retval: 0
Mar 23 18:39:07 ilmenite.local loginwindow[69]: in od_record_attribute_create_cfstring(): returned 2 attributes for dsAttrTypeStandard:AuthenticationAuthority
Mar 23 18:39:07 ilmenite.local loginwindow[69]: in pam_sm_setcred(): Establishing credentials
Mar 23 18:39:07 ilmenite.local loginwindow[69]: in pam_sm_setcred(): Got user: pdp
Mar 23 18:39:07 ilmenite.local loginwindow[69]: in pam_sm_setcred(): Context initialised
Mar 23 18:39:07 ilmenite.local loginwindow[69]: in pam_sm_setcred(): Got euid, egid: 501 20
Mar 23 18:39:07 ilmenite.local loginwindow[69]: in pam_sm_setcred(): Done getpwnam()
Mar 23 18:39:07 ilmenite.local loginwindow[69]: in pam_sm_setcred(): Done setegid() & seteuid()
Mar 23 18:39:07 ilmenite.local loginwindow[69]: in pam_sm_setcred(): pam_sm_setcred: krb5 user pdp doesn't have a principal
Mar 23 18:39:07 ilmenite.local loginwindow[69]: in pam_sm_setcred(): Done cleanup3
Mar 23 18:39:07 ilmenite.local loginwindow[69]: in pam_sm_setcred(): Done seteuid() & setegid()
Mar 23 18:39:07 ilmenite.local loginwindow[69]: in pam_sm_setcred(): Done cleanup4
Mar 23 18:39:10 ilmenite.local configd[17]: network changed: v6(en1+:fe80::28e:f2ff:fe5c:14c1) DNS+ Proxy+ SMB
Mar 23 18:39:10 ilmenite.local UserEventAgent[11]: Captive: en1: Not probing 'Pennock' (protected network)
Mar 23 18:39:10 ilmenite.local configd[17]: network changed: v6(en1!:fe80::28e:f2ff:fe5c:14c1) DNS Proxy SMB
Mar 23 18:39:12 ilmenite kernel[0]: CODE SIGNING: cs_invalid_page(0x1000): p=32255[ksadmin] clearing CS_VALID
Mar 23 18:39:12 ilmenite.local configd[17]: network changed: v4(en1+:192.168.120.165) v6(en1:fe80::28e:f2ff:fe5c:14c1) DNS! Proxy SMB
Mar 23 18:39:13 ilmenite.local imagent[363]: [Warning] Bag loading failed! Error (NSURLErrorDomain:-1003): A server with the specified hostname could not be found. http://init-p01md.apple.com/bag
Mar 23 18:39:14 ilmenite kernel[0]: CODE SIGNING: cs_invalid_page(0x1000): p=32260[ksadmin] clearing CS_VALID
Mar 23 18:39:15 ilmenite.local coreservicesd[31]: SendFlattenedData, got error #268435460 (ipc/send) timed out from ::mach_msg(), sending notification kLSNotifyChildApplicationReady to notificationID=6819
Mar 23 18:39:17 ilmenite.local _softwareupdate[32294]: dnssec-trigger(osx) detected en1 DNS 192.168.120.1
Mar 23 18:39:18 ilmenite.local _eppc[32298]: dnssec-trigger-setdns to field.spodhuis.org and 127.0.0.1
Mar 23 18:39:18 ilmenite com.apple.launchd[1] (nl.nlnetlabs.dnssec-trigger-hook): Throttling respawn: Will start in 1 seconds
Mar 23 18:39:26 --- last message repeated 6 times ---
Mar 23 18:39:26 ilmenite.local login[32370]: in pam_sm_acct_mgmt(): OpenDirectory - Membership cache TTL set to 1800.
Mar 23 18:39:26 ilmenite.local login[32370]: in od_record_check_pwpolicy(): retval: 0
Mar 23 18:39:26 ilmenite.local login[32370]: in od_record_attribute_create_cfstring(): returned 2 attributes for dsAttrTypeStandard:AuthenticationAuthority
Mar 23 18:39:26 ilmenite.local login[32370]: USER_PROCESS: 32370 ttys001
Mar 23 18:39:35 ilmenite.local com.apple.backupd[32459]: Starting automatic backup
Mar 23 18:39:35 ilmenite.local com.apple.SecurityServer[15]: Succeeded authorizing right 'com.apple.ServiceManagement.daemons.modify' by client '/usr/libexec/UserEventAgent' [11] for authorization created by '/usr/libexec/UserEventAgent' [11] (100012,0)
Mar 23 18:39:35 ilmenite.local com.apple.backupd[32459]: Attempting to mount network destination URL: afp://tm@howlite.local/tmbackups
Mar 23 18:39:35 ilmenite kernel[0]: ASP_TCP CheckReqQueueSize: increasing req queue from 32 to 128 entries. so 0xffffff8042c596e0 
Mar 23 18:39:36 ilmenite.local com.apple.backupd[32459]: Mounted network destination at mount point: /Volumes/tmbackups using URL: afp://tm@howlite.local/tmbackups
Mar 23 18:39:36 ilmenite kernel[0]: ASP_TCP asp_tcp_usr_control: invalid kernelUseCount 0
Mar 23 18:39:36 ilmenite kernel[0]: AFP_VFS afpfs_mount: /Volumes/tmbackups, pid 32461
Mar 23 18:39:36 ilmenite kernel[0]: AFP_VFS afpfs_mount : succeeded on volume 0xffffff8100952008 /Volumes/tmbackups (error = 0, retval = 0)
Mar 23 18:39:37 ilmenite.local sudo[32460]:      pdp : TTY=ttys001 ; PWD=/Users/pdp ; USER=root ; COMMAND=/usr/sbin/unbound-control
Mar 23 18:39:40 ilmenite.local sudo[32482]:      pdp : TTY=ttys001 ; PWD=/Users/pdp ; USER=root ; COMMAND=/usr/sbin/unbound-control reload
Mar 23 18:39:40 ilmenite.local unbound[86]: [86:0] notice: Restart of unbound 1.4.17.
Mar 23 18:39:40 ilmenite.local unbound[86]: [86:0] notice: init module 0: validator
Mar 23 18:39:40 ilmenite.local unbound[86]: [86:0] notice: init module 1: iterator
Mar 23 18:39:47 ilmenite.local sudo[32487]:      pdp : TTY=ttys001 ; PWD=/Users/pdp ; USER=root ; COMMAND=/usr/sbin/unbound-control forward
Mar 23 18:39:51 ilmenite.local com.apple.SecurityServer[15]: Succeeded authorizing right 'com.apple.ServiceManagement.daemons.modify' by client '/usr/libexec/UserEventAgent' [11] for authorization created by '/usr/libexec/UserEventAgent' [11] (100012,0)
Mar 23 18:39:53 ilmenite.local configd[17]: _handleLinkEvent: Unable to process link event, op mode request returned -3903 (Operation not supported)
Mar 23 18:39:53 ilmenite kernel[0]: wlEvent: en1 en1 Link DOWN virtIf = 0
Mar 23 18:39:53 ilmenite kernel[0]: AirPort: Link Down on en1. Reason 8 (Disassociated because station leaving).
Mar 23 18:39:53 ilmenite kernel[0]: en1::IO80211Interface::postMessage bssid changed
Mar 23 18:39:53 ilmenite.local _softwareupdate[32509]: dnssec-trigger(osx) detected  DNS 
Mar 23 18:39:53 ilmenite.local dnssec-triggerd[87]: [87] error: could not UDP send to ip 192.5.5.241
Mar 23 18:39:53 --- last message repeated 1 time ---
Mar 23 18:39:53 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:53 ilmenite.local dnssec-triggerd[87]: [87] error: could not UDP send to ip 2001:500:2d::d
Mar 23 18:39:53 --- last message repeated 1 time ---
Mar 23 18:39:53 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:53 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: Network is down
Mar 23 18:39:53 --- last message repeated 1 time ---
Mar 23 18:39:53 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:53 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: Network is down
Mar 23 18:39:53 --- last message repeated 1 time ---
Mar 23 18:39:53 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:53 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: Network is down
Mar 23 18:39:53 --- last message repeated 1 time ---
Mar 23 18:39:53 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:53 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: Network is down
Mar 23 18:39:53 --- last message repeated 1 time ---
Mar 23 18:39:53 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:53 ilmenite.local _eppc[32515]: dnssec-trigger-setdns to field.spodhuis.org and 127.0.0.1
Mar 23 18:39:54 ilmenite com.apple.launchd[1] (nl.nlnetlabs.dnssec-trigger-hook): Throttling respawn: Will start in 1 seconds
Mar 23 18:39:54 ilmenite.local configd[17]: network changed: v4(en1-:192.168.120.165) v6(en1-:fe80::28e:f2ff:fe5c:14c1) DNS- Proxy- SMB
Mar 23 18:39:54 ilmenite.local mDNSResponder[34]: DeregisterInterface: Frequent transitions for interface en1 (2001:4830:1100:80C6:1240:F3FF:FEEB:1BBE)
Mar 23 18:39:54 ilmenite kernel[0]: ASP_TCP Disconnect: triggering reconnect by bumping reconnTrigger from curr value 0 on so 0xffffff8042c596e0
Mar 23 18:39:54 ilmenite kernel[0]: ASP_TCP asp_tcp_usr_control: invalid kernelUseCount 0
Mar 23 18:39:54 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect started /Volumes/tmbackups prevTrigger 0 currTrigger 1
Mar 23 18:39:54 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  doing reconnect on /Volumes/tmbackups
Mar 23 18:39:54 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  soft mounted and hidden volume so do not notify KEA for /Volumes/tmbackups
Mar 23 18:39:54 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  Max reconnect time: 30 secs, Connect timeout: 15 secs for /Volumes/tmbackups
Mar 23 18:39:54 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  connect to the server /Volumes/tmbackups
Mar 23 18:39:54 ilmenite kernel[0]: ASP_TCP asp_SetTCPQoS:  sock_settclassopt got error 57 
Mar 23 18:39:54 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  connect on /Volumes/tmbackups failed 65.
Mar 23 18:39:54 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  sleep for 1 seconds and then try again
Mar 23 18:39:55 ilmenite com.apple.launchd[1] (nl.nlnetlabs.dnssec-trigger-hook): Throttling respawn: Will start in 1 seconds
Mar 23 18:39:55 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  connect to the server /Volumes/tmbackups
Mar 23 18:39:55 ilmenite kernel[0]: ASP_TCP asp_SetTCPQoS:  sock_settclassopt got error 57 
Mar 23 18:39:55 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  connect on /Volumes/tmbackups failed 65.
Mar 23 18:39:55 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  sleep for 2 seconds and then try again
Mar 23 18:39:56 ilmenite.local _softwareupdate[32578]: dnssec-trigger(osx) detected  DNS 
Mar 23 18:39:56 ilmenite.local dnssec-triggerd[87]: [87] error: could not UDP send to ip 192.228.79.201
Mar 23 18:39:56 --- last message repeated 1 time ---
Mar 23 18:39:56 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:56 ilmenite.local dnssec-triggerd[87]: [87] error: could not UDP send to ip 2001:503:ba3e::2:30
Mar 23 18:39:56 --- last message repeated 1 time ---
Mar 23 18:39:56 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:56 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: No route to host
Mar 23 18:39:56 --- last message repeated 1 time ---
Mar 23 18:39:56 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:56 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: No route to host
Mar 23 18:39:56 --- last message repeated 1 time ---
Mar 23 18:39:56 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:56 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: No route to host
Mar 23 18:39:56 --- last message repeated 1 time ---
Mar 23 18:39:56 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:56 ilmenite.local dnssec-triggerd[87]: [87] error: outgoing tcp: connect: No route to host
Mar 23 18:39:56 --- last message repeated 1 time ---
Mar 23 18:39:56 ilmenite.local dnssec-triggerd[87]: [87] error: could not send queries for probe
Mar 23 18:39:56 ilmenite.local _eppc[32584]: dnssec-trigger-setdns to field.spodhuis.org and 127.0.0.1
Mar 23 18:39:57 ilmenite.local dnssec-triggerd[87]: [87] error: chmod(/etc/resolv.conf) failed: No such file or directory
Mar 23 18:39:57 ilmenite com.apple.launchd[1] (nl.nlnetlabs.dnssec-trigger-hook): Throttling respawn: Will start in 1 seconds
Mar 23 18:39:57 --- last message repeated 1 time ---
Mar 23 18:39:57 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  connect to the server /Volumes/tmbackups
Mar 23 18:39:57 ilmenite kernel[0]: ASP_TCP asp_SetTCPQoS:  sock_settclassopt got error 57 
Mar 23 18:39:57 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  connect on /Volumes/tmbackups failed 65.
Mar 23 18:39:57 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  sleep for 4 seconds and then try again
Mar 23 18:39:58 ilmenite kernel[0]: MacAuthEvent en1   Auth result for: 00:8e:f2:5c:14:c3  MAC AUTH succeeded
Mar 23 18:39:58 ilmenite kernel[0]: wlEvent: en1 en1 Link UP virtIf = 0
Mar 23 18:39:58 ilmenite kernel[0]: AirPort: Link Up on en1
Mar 23 18:39:58 ilmenite kernel[0]: en1: BSSID changed to 00:8e:f2:5c:14:c3
Mar 23 18:39:58 ilmenite kernel[0]: en1::IO80211Interface::postMessage bssid changed
Mar 23 18:39:58 ilmenite kernel[0]: AirPort: RSN handshake complete on en1
Mar 23 18:39:58 ilmenite.local configd[17]: network changed: v4(en1+:192.168.120.165) DNS+ Proxy+ SMB
Mar 23 18:39:58 ilmenite.local mDNSResponder[34]: mDNS_RegisterInterface: Frequent transitions for interface en1 (FE80:0000:0000:0000:1240:F3FF:FEEB:1BBE)
Mar 23 18:39:58 ilmenite.local mDNSResponder[34]: mDNS_RegisterInterface: Frequent transitions for interface en1 (192.168.120.165)
Mar 23 18:39:58 ilmenite.local UserEventAgent[11]: Captive: en1: Not probing 'Pennock' (protected network)
Mar 23 18:39:58 ilmenite.local configd[17]: network changed: v4(en1!:192.168.120.165) DNS Proxy SMB
Mar 23 18:39:58 ilmenite.local _softwareupdate[32636]: dnssec-trigger(osx) detected en1 DNS 192.168.120.1
Mar 23 18:39:58 ilmenite.local dnssec-triggerd[87]: [87] error: http_probe_create_get: No route to host
Mar 23 18:39:58 --- last message repeated 2 times ---
Mar 23 18:39:58 ilmenite.local configd[17]: network changed: v4(en1:192.168.120.165) v6(en1+:fe80::28e:f2ff:fe5c:14c1) DNS* Proxy SMB
Mar 23 18:39:58 ilmenite com.apple.launchd[1] (nl.nlnetlabs.dnssec-trigger-hook): Throttling respawn: Will start in 1 seconds
Mar 23 18:39:58 --- last message repeated 1 time ---
Mar 23 18:39:58 ilmenite.local airportd[32494]: _doAutoJoin: Already associated to ?Pennock?. Bailing on auto-join.
Mar 23 18:39:58 --- last message repeated 1 time ---
Mar 23 18:39:58 ilmenite.local _eppc[32640]: dnssec-trigger-setdns to field.spodhuis.org and 127.0.0.1
Mar 23 18:39:58 ilmenite com.apple.launchd[1] (nl.nlnetlabs.dnssec-trigger-hook): Throttling respawn: Will start in 1 seconds
Mar 23 18:39:59 --- last message repeated 6 times ---
Mar 23 18:39:59 ilmenite.local imagent[363]: [Warning] Bag loading failed! Error (NSURLErrorDomain:-1003): A server with the specified hostname could not be found. http://init-p01md.apple.com/bag
Mar 23 18:39:59 ilmenite com.apple.launchd[1] (nl.nlnetlabs.dnssec-trigger-hook): Throttling respawn: Will start in 1 seconds
Mar 23 18:40:01 ilmenite.local sudo[32709]:      pdp : TTY=ttys001 ; PWD=/Users/pdp ; USER=root ; COMMAND=/usr/sbin/unbound-control forward
Mar 23 18:40:01 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  connect to the server /Volumes/tmbackups
Mar 23 18:40:01 ilmenite kernel[0]: ASP_TCP asp_SetTCPQoS:  sock_settclassopt got error 57 
Mar 23 18:40:01 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  Logging in with uam 8 /Volumes/tmbackups
Mar 23 18:40:01 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  Restoring session /Volumes/tmbackups
Mar 23 18:40:01 ilmenite kernel[0]: ASP_TCP ReplayPendingReqs: replaying slot 48 with reqID 50 afpCmd 0x7 on so 0xffffff8042c596e0
Mar 23 18:40:01 ilmenite kernel[0]: AFP_VFS afpfs_DoReconnect:  get the reconnect token
Mar 23 18:40:08 ilmenite.local sudo[32733]:      pdp : TTY=ttys001 ; PWD=/Users/pdp ; USER=root ; COMMAND=/usr/sbin/unbound-control reload
Mar 23 18:40:08 ilmenite.local unbound[86]: [86:0] notice: Restart of unbound 1.4.17.
Mar 23 18:40:08 ilmenite.local unbound[86]: [86:0] notice: init module 0: validator
Mar 23 18:40:08 ilmenite.local unbound[86]: [86:0] notice: init module 1: iterator
Mar 23 18:40:11 ilmenite.local sudo[32735]:      pdp : TTY=ttys001 ; PWD=/Users/pdp ; USER=root ; COMMAND=/usr/sbin/unbound-control forward
Mar 23 18:40:28 ilmenite.local sudo[32745]:      pdp : TTY=ttys001 ; PWD=/Users/pdp ; USER=root ; COMMAND=/usr/sbin/unbound-control forward 192.168.120.1
Mar 23 18:40:31 ilmenite.local mds[66]: (Error) Volume: Root store set to FSOnly with matching create! (loaded:1)
Mar 23 18:40:32 ilmenite.local com.apple.backupd[32459]: Disk image /Volumes/tmbackups/ilmenite.sparsebundle mounted at: /Volumes/Time Machine Backups
Mar 23 18:40:32 ilmenite.local com.apple.backupd[32459]: Backing up to: /Volumes/Time Machine Backups/Backups.backupdb
Mar 23 18:40:39 ilmenite.local com.apple.backupd[32459]: Using file event preflight for Ilmenite SSD
Mar 23 18:40:46 ilmenite.local sudo[32767]:      pdp : TTY=ttys001 ; PWD=/Users/pdp ; USER=root ; COMMAND=/usr/bin/killall -v mDNSResponder
Mar 23 18:40:46 ilmenite.local mDNSResponder[34]: mDNSResponder mDNSResponder-379.37 (Dec 16 2012 19:43:09) stopping
Mar 23 18:40:46 ilmenite.local UserEventAgent[11]: dnssd_clientstub DNSServiceProcessResult called with DNSServiceRef with no ProcessReply function
Mar 23 18:40:46 --- last message repeated 1 time ---
Mar 23 18:40:46 ilmenite.local configd[17]: dnssd_clientstub read_all(19) failed 0/28 0 
Mar 23 18:40:46 ilmenite.local UserEventAgent[11]: dnssd_clientstub DNSServiceProcessResult called with DNSServiceRef with no ProcessReply function
Mar 23 18:40:46 --- last message repeated 1 time ---
Mar 23 18:40:46 ilmenite.local configd[17]: [0x7fdf814242a0] SCNetworkReachability _llq_callback w/error=-65563
Mar 23 18:40:46 ilmenite.local UserEventAgent[11]: dnssd_clientstub DNSServiceProcessResult called with DNSServiceRef with no ProcessReply function
Mar 23 18:40:46 ilmenite.local configd[17]: [0x7fdf81428c10] SCNetworkReachability _llq_callback w/error=-65563
Mar 23 18:40:46 ilmenite.local UserEventAgent[11]: dnssd_clientstub DNSServiceProcessResult called with DNSServiceRef with no ProcessReply function
Mar 23 18:40:46 ilmenite.local configd[17]: [0x7fdf8142deb0] SCNetworkReachability _llq_callback w/error=-65563
Mar 23 18:40:46 ilmenite.local UserEventAgent[11]: dnssd_clientstub DNSServiceProcessResult called with DNSServiceRef with no ProcessReply function
Mar 23 18:40:46 ilmenite.local configd[17]: [0x7fdf8231a8f0] SCNetworkReachability _llq_callback w/error=-65563
Mar 23 18:40:46 ilmenite.local UserEventAgent[11]: dnssd_clientstub DNSServiceProcessResult called with DNSServiceRef with no ProcessReply function
Mar 23 18:40:46 --- last message repeated 1 time ---
Mar 23 18:40:46 ilmenite.local configd[17]: [0x7fdf8210da60] SCNetworkReachability _llq_callback w/error=-65563
Mar 23 18:40:46 ilmenite.local UserEventAgent[11]: dnssd_clientstub DNSServiceProcessResult called with DNSServiceRef with no ProcessReply function
Mar 23 18:40:46 ilmenite.local usbmuxd[54]: dnssd_clientstub read_all(11) failed 0/28 0 
Mar 23 18:40:46 ilmenite.local UserEventAgent[325]: dnssd_clientstub read_all(6) failed 0/28 0 
Mar 23 18:40:46 ilmenite.local NetworkBrowserAgent[405]: dnssd_clientstub read_all(4) failed 0/28 0 
Mar 23 18:40:46 ilmenite.local coreaudiod[345]: dnssd_clientstub read_all(4) failed 0/28 0 
Mar 23 18:40:46 ilmenite.local NetworkBrowserAgent[405]: StatusMonitor::handleNameMonitorCallBack returned -65563
Mar 23 18:40:46 ilmenite.local NetworkBrowserAgent[405]: dnssdclientstub:sdRef: CallbackwithError morebytes zero sdr 0x7ffca3002770
Mar 23 18:40:46 ilmenite.local coreaudiod[345]: dnssd_clientstub read_all(6) failed 0/28 0 
Mar 23 18:40:46 ilmenite.local NetworkBrowserAgent[405]: dnssd_clientstub read_all(10) failed 0/28 0 
Mar 23 18:40:46 ilmenite.local NetworkBrowserAgent[405]: BonjourBrowser::handleBrowseCallBack returned -65563
Mar 23 18:40:46 --- last message repeated 3 times ---
Mar 23 18:40:46 ilmenite.local NetworkBrowserAgent[405]: dnssd_clientstub read_all(11) failed 0/28 0 
Mar 23 18:40:46 ilmenite.local NetworkBrowserAgent[405]: BonjourBrowser::handleBrowseCallBack returned -65563
Mar 23 18:40:46 ilmenite.local NetworkBrowserAgent[405]: dnssd_clientstub read_all(9) failed 0/28 0 
Mar 23 18:40:46 ilmenite.local mDNSResponder[34]: D2D_IPC: Terminated
Mar 23 18:40:46 ilmenite.local mDNSResponder[34]: D2DTerminate succeeded
Mar 23 18:40:46 ilmenite.local mDNSResponder[34]: DeregisterInterface: Frequent transitions for interface en1 (2001:4830:1100:80C6:1240:F3FF:FEEB:1BBE)
Mar 23 18:40:46 ilmenite.local configd[17]: _d2dCallback: D2D connection to mDNSResponder lost
Mar 23 18:40:46 ilmenite.local mDNSResponder[32769]: mDNSResponder mDNSResponder-379.37 (Dec 16 2012 19:43:09) starting OSXVers 12
Mar 23 18:40:46 ilmenite.local mDNSResponder[32769]: D2D_IPC: Loaded
Mar 23 18:40:46 ilmenite.local mDNSResponder[32769]: D2DInitialize succeeded
Mar 23 18:40:47 ilmenite.local configd[17]: _resetD2DConnection: Connection re-established to mDNSResponder D2D server
Mar 23 18:40:48 ilmenite.local com.apple.backupd[32459]: Will copy (59.7 MB) from Ilmenite SSD
Mar 23 18:40:48 ilmenite.local com.apple.backupd[32459]: Found 232 files (59.7 MB) needing backup
Mar 23 18:40:48 ilmenite.local com.apple.backupd[32459]: 3.45 GB required (including padding), 844.88 GB available
Mar 23 18:40:50 ilmenite.local Google Chrome[29177]: dnssd_clientstub write_all(45) failed -1/57 32 Broken pipe
Mar 23 18:40:50 ilmenite.local Google Chrome[29177]: dnssd_clientstub write_all(45) failed -1/28 32 Broken pipe
Mar 23 18:40:58 ilmenite.local Google Chrome[32239]: dnssd_clientstub write_all(45) failed -1/56 32 Broken pipe
Mar 23 18:40:58 ilmenite.local Google Chrome[32239]: dnssd_clientstub write_all(45) failed -1/28 32 Broken pipe
Mar 23 18:41:30 ilmenite.local Google Drive[447]: dnssd_clientstub write_all(15) failed -1/56 32 Broken pipe
Mar 23 18:41:30 ilmenite.local Google Drive[447]: dnssd_clientstub write_all(15) failed -1/28 32 Broken pipe


More information about the dnssec-trigger mailing list