Slow boot: "executing fsck"

Question

Level 1

0 points

Slow boot: "executing fsck"

Hi everyone,

I hope some of you can help with this.

Recently, I've noticed that my MacBook Pro (13'', 2009, 2.26 core 2 duo, 8gb ram, Lion), has been taking longer than it should to boot.

I booted into verbose mode, and it spends about 40 seconds on this line:

Executing fsck_hfs (version diskdev_cmds-540.1~34)

Any help with this would be greatly appreciated! I hope to have my MBP booting fast once again soon.

Daniel

Note:
I have already run fsck, repaired and verified permissions, repaired and verified the disk, reset PRAM, and reset SMC

MacBook Pro, Mac OS X (10.7.3)

Posted on Feb 14, 2012 6:20 PM

Reply

Answer 1

Linc Davis

Level 10

209,541 points

Feb 14, 2012 6:53 PM in response to DanielG.

...taking longer than it should to boot.

How much longer?

Launch the Console application by entering the first few letters of its name into a Spotlight search, and select "system.log" from the file list. Enter "BOOT_TIME" (without the quotes) in the search box. Note the times of the log messages referring to boot times. Now clear the search box and scroll back in the log to the time of the most recent boot when you had the problem. Look for error messages in the minutes afterthat time, and post them here -- the text, please, not a screenshot. Edit out excessive repeats, if any. Do the same with kernel.log.

If the logs don't go back far enough in time, scroll down in the Console file list to /private/var/log/system.log.0.bz2 and /private/var/log/kernel.log.0.bz2. Search those archived logs, and if necessary the older ones below them, for the same information.

Also in Console, look under System Diagnostic Reports for crash or panic logs, and post the most recent one, if any. For privacy’s sake, I suggest you edit out the “Anonymous UUID,” a long string of letters, numbers, and dashes in the header of the report, if present (it may not be.) Please do not post shutdownStall or hang logs -- they're very long and not helpful.

Reply

Answer 2

DanielG. Author

Level 1

0 points

Feb 15, 2012 12:58 PM in response to Linc Davis

Sorry for the slow response!

Booting takes about a minute and a half now, used to be 30-45 seconds.

Here are the errors in system.log:

Feb 15 15:39:01 localhost bootlog[0]: BOOT_TIME 1329338341 0

Feb 15 15:39:03 localhost com.apple.launchd[1]: *** launchd[1] has started up. ***

Feb 15 15:39:39 localhost com.apple.launchd[1] (com.apple.sandboxd): Unknown value for key POSIXSpawnType: Interactive

Feb 15 15:39:44 localhost com.apple.pfctl[31]: No ALTQ support in kernel

Feb 15 15:39:44 localhost com.apple.pfctl[31]: ALTQ related functions disabled

Feb 15 15:39:44 localhost com.apple.ucupdate.plist[23]: ucupdate: Checked 1 update, no match found.

Feb 15 15:39:47 localhost UserEventAgent[11]: starting CaptiveNetworkSupport as SystemEventAgent built Jun 13 2011 17:29:21

Feb 15 15:39:47 localhost UserEventAgent[11]: WirelessAirPortDeviceNameCopy(): no BSD interface name found for object 12295

Feb 15 15:39:47 localhost UserEventAgent[11]: CaptiveNetworkSupport:CaptiveSCCopyWiFiDevices:388 WiFi Device Name == NULL

Feb 15 15:39:48 localhost UserEventAgent[11]: CertsKeychainMonitor: configuring

Feb 15 15:39:50 localhost com.apple.usbmuxd[22]: usbmuxd-263 on Nov 14 2011 at 18:58:10, running 64 bit

Feb 15 15:39:50 localhost mDNSResponder[37]: mDNSResponder mDNSResponder-320.14.0 (Nov 16 2011 01:16:56) starting OSXVers 11

Feb 15 15:39:50 localhost airportd[65]: _processDLILEvent: en1 attached (down)

Feb 15 15:39:51 localhost UserEventAgent[11]: CaptiveNetworkSupport:CreateInterfaceWatchList:2788 WiFi Devices Found. 🙂

Feb 15 15:39:51 localhost UserEventAgent[11]: CaptiveNetworkSupport:CaptivePublishState:1211 en1 - PreProbe

Feb 15 15:39:57: --- last message repeated 2 times ---

Feb 15 15:39:51 localhost mds[36]: (Normal) FMW: FMW 0 0

Feb 15 15:39:52 localhost configd[14]: bootp_session_transmit: bpf_write(en1) failed: Network is down (50)

Feb 15 15:39:52 localhost configd[14]: DHCP en1: INIT-REBOOT transmit failed

Feb 15 15:39:52 Daniel-Garys-MacBook-Pro configd[14]: setting hostname to "Daniel-Garys-MacBook-Pro.local"

Feb 15 15:39:52 Daniel-Garys-MacBook-Pro configd[14]: network configuration changed.

Feb 15 15:39:52 Daniel-Garys-MacBook-Pro mds[36]: (Warning) Server: No stores registered for metascope "kMDQueryScopeComputer"

Feb 15 15:40:00: --- last message repeated 2 times ---

Feb 15 15:39:56 Daniel-Garys-MacBook-Pro mtmd[35]: Set snapshot time:1329338398 (current time:1329338396)

Feb 15 15:40:00 Daniel-Garys-MacBook-Pro mds[36]: (/.Spotlight-V100/Store-V2/CFFD2322-EAC9-4E15-8165-E9F726D1A5D2)(Normal) IndexGeneral in openReverseStore:Shadowing reverse store on open

Feb 15 15:40:02 Daniel-Garys-MacBook-Pro UserEventAgent[11]: ServermgrdRegistration cannot load config data

Feb 15 15:40:02 Daniel-Garys-MacBook-Pro UserEventAgent[11]: get_backup_share_points no AFP

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro configd[14]: network configuration changed.

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro mDNSResponder[37]: D2D_IPC: Loaded

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro mDNSResponder[37]: D2DInitialize succeeded

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro configd[14]: network configuration changed.

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro loginwindow[39]: Login Window Application Started

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro systemkeychain[69]: done file: /var/run/systemkeychaincheck.done

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro rpcsvchost[127]: sandbox_init: com.apple.msrpc.netlogon.sb succeeded

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro mds[36]: (Normal) Permissions: perm store dirty count exceeded rebuilding

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro com.apple.launchd[1] (com.apple.netbiosd[75]): Exited abnormally: Hangup: 1

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro netbiosd[134]: Unable to start NetBIOS name service:

Feb 15 15:40:04 Daniel-Garys-MacBook-Pro configd[14]: network configuration changed.

Feb 15 15:40:04 Daniel-Garys-MacBook-Pro UserEventAgent[11]: CaptiveNetworkSupport:CaptivePublishState:1211 en1 - Probe

Feb 15 15:40:04 Daniel-Garys-MacBook-Pro UserEventAgent[11]: CaptiveNetworkSupport:CNSPreferences:60 Creating new preferences

Feb 15 15:40:04 Daniel-Garys-MacBook-Pro UserEventAgent[11]: CaptiveNetworkSupport:CaptiveStartDetect:2343 Bypassing probe on ***** because it is protected and not on the exception list

Feb 15 15:40:04 Daniel-Garys-MacBook-Pro UserEventAgent[11]: CaptiveNetworkSupport:CaptivePublishState:1211 en1 - Unknown

Feb 15 15:40:04 Daniel-Garys-MacBook-Pro configd[14]: network configuration changed.

Feb 15 15:40:05 Daniel-Garys-MacBook-Pro airportd[65]: _doAutoJoin: Already associated to “****”. Bailing on auto-join.

Feb 15 15:40:05 Daniel-Garys-MacBook-Pro loginwindow[39]: **DMPROXY** Found `/System/Library/CoreServices/DMProxy'.

Feb 15 15:40:04 Daniel-Garys-MacBook-Pro com.apple.launchctl.LoginWindow[143]: com.apple.findmymacmessenger: Already loaded

Feb 15 15:40:04 Daniel-Garys-MacBook-Pro loginwindow[39]: Login Window Started Security Agent

Feb 15 15:40:05 Daniel-Garys-MacBook-Pro ntpd[18]: proto: precision = 1.000 usec

Feb 15 15:40:05 Daniel-Garys-MacBook-Pro airportd[65]: _doAutoJoin: Already associated to “****”. Bailing on auto-join.

Feb 15 15:40:05 Daniel-Garys-MacBook-Pro SecurityAgent[149]: Echo enabled

Feb 15 15:40:05 Daniel-Garys-MacBook-Pro SecurityAgent[149]: User info context values set for Daniel

Feb 15 15:40:05 Daniel-Garys-MacBook-Pro WindowServer[128]: kCGErrorFailure: Set a breakpoint @ CGErrorBreakpoint() to catch errors as they are logged.

Feb 15 15:40:05 Daniel-Garys-MacBook-Pro loginwindow[39]: Login Window - Returned from Security Agent

Feb 15 15:40:05 Daniel-Garys-MacBook-Pro airportd[65]: _doAutoJoin: Already associated to “****”. Bailing on auto-join.

Feb 15 15:40:05 Daniel-Garys-MacBook-Pro loginwindow[39]: USER_PROCESS: 39 console

Feb 15 15:40:06 Daniel-Garys-MacBook-Pro com.apple.launchd.peruser.501[156] (com.apple.ReportCrash): Falling back to default Mach exception handler. Could not find: com.apple.ReportCrash.Self

Feb 15 15:40:06 Daniel-Garys-MacBook-Pro com.apple.launchctl.Aqua[158]: load: option requires an argument -- D

Feb 15 15:40:06 Daniel-Garys-MacBook-Pro com.apple.launchctl.Aqua[158]: usage: launchctl load [-wF] [-D <user|local|network|system|all>] paths...

Feb 15 15:40:06 Daniel-Garys-MacBook-Pro com.apple.launchd.peruser.501[156] (com.apple.launchctl.Aqua[158]): Exited with code: 1

Feb 15 15:40:08 Daniel-Garys-MacBook-Pro com.apple.mtmfs[34]: /var/folders/zz/zyxvpxvq6csfxvn_n0000000000000/T//mtmfsMap34

Feb 15 15:40:08 Daniel-Garys-MacBook-Pro com.apple.dock.extra[184]: 2012-02-15 15:40:08.440 com.apple.dock.extra[184:1707] Could not connect the action buttonPressed: to target of class NSApplication

Feb 15 15:40:08 Daniel-Garys-MacBook-Pro com.apple.dock.extra[184]: 2012-02-15 15:40:08.441 com.apple.dock.extra[184:1707] Could not connect the action buttonPressed: to target of class NSApplication

Feb 15 15:40:08 Daniel-Garys-MacBook-Pro com.apple.dock.extra[184]: 2012-02-15 15:40:08.442 com.apple.dock.extra[184:1707] Could not connect the action buttonPressed: to target of class NSApplication

Feb 15 15:40:08 Daniel-Garys-MacBook-Pro com.apple.dock.extra[184]: Could not connect the action buttonPressed: to target of class NSApplication

Feb 15 15:40:08: --- last message repeated 3 times ---

Feb 15 15:40:08 Daniel-Garys-MacBook-Pro com.apple.dock.extra[184]: 2012-02-15 15:40:08.443 com.apple.dock.extra[184:1707] Could not connect the action buttonPressed: to target of class NSApplication

Feb 15 15:40:08 Daniel-Garys-MacBook-Pro coreservicesd[53]: *** process 53 exceeded 500 log message per second limit - remaining messages this second discarded ***

Feb 15 15:40:09 Daniel-Garys-MacBook-Pro UserEventAgent[11]: CaptiveNetworkSupport:CNSServerRegisterUserAgent:187 new user agent port: 18951

Feb 15 15:40:10 Daniel-Garys-MacBook-Pro VirusBarrier Plus Agent[189]: Running at path /Applications/VirusBarrier Plus.app/Contents/Library/LoginItems/VirusBarrier Plus Agent.app

And the kernel. log messages:

Feb 15 15:39:40 localhost kernel[0]: Darwin Kernel Version 11.3.0: Thu Jan 12 18:47:41 PST 2012; root:xnu-1699.24.23~1/RELEASE_X86_64

Feb 15 15:39:40 localhost kernel[0]: vm_page_bootstrap: 1959197 free pages and 56035 wired pages

Feb 15 15:39:40 localhost kernel[0]: kext submap [0xffffff7f8072f000 - 0xffffff8000000000], kernel text [0xffffff8000200000 - 0xffffff800072f000]

Feb 15 15:39:40 localhost kernel[0]: zone leak detection enabled

Feb 15 15:39:40 localhost kernel[0]: standard timeslicing quantum is 10000 us

Feb 15 15:39:40 localhost kernel[0]: mig_table_max_displ = 73

Feb 15 15:39:40 localhost kernel[0]: AppleACPICPU: ProcessorId=0 LocalApicId=0 Enabled

Feb 15 15:39:40 localhost kernel[0]: AppleACPICPU: ProcessorId=1 LocalApicId=1 Enabled

Feb 15 15:39:40 localhost kernel[0]: calling mpo_policy_init for TMSafetyNet

Feb 15 15:39:40 localhost kernel[0]: Security policy loaded: Safety net for Time Machine (TMSafetyNet)

Feb 15 15:39:40 localhost kernel[0]: calling mpo_policy_init for Sandbox

Feb 15 15:39:40 localhost kernel[0]: Security policy loaded: Seatbelt sandbox policy (Sandbox)

Feb 15 15:39:40 localhost kernel[0]: calling mpo_policy_init for Quarantine

Feb 15 15:39:40 localhost kernel[0]: Security policy loaded: Quarantine policy (Quarantine)

Feb 15 15:39:40 localhost kernel[0]: MAC Framework successfully initialized

Feb 15 15:39:40 localhost kernel[0]: using 16384 buffer headers and 10240 cluster IO buffer headers

Feb 15 15:39:40 localhost kernel[0]: IOAPIC: Version 0x11 Vectors 64:87

Feb 15 15:39:40 localhost kernel[0]: ACPI: System State [S0 S3 S4 S5] (S3)

Feb 15 15:39:40 localhost kernel[0]: PFM64 (36 cpu) 0xf10000000, 0xf0000000

Feb 15 15:39:40 localhost kernel[0]: [ PCI configuration begin ]

Feb 15 15:39:40 localhost kernel[0]: console relocated to 0xf10010000

Feb 15 15:39:40 localhost kernel[0]: PCI configuration changed (bridge=2 device=1 cardbus=0)

Feb 15 15:39:40 localhost kernel[0]: [ PCI configuration end, bridges 5 devices 18 ]

Feb 15 15:39:40 localhost kernel[0]: AppleIntelCPUPowerManagement: (built 18:56:37 Jan 12 2012) initialization complete

Feb 15 15:39:40 localhost kernel[0]: FireWire (OHCI) Lucent ID 5901 built-in now active, GUID 0025bcfffedf2688; max speed s800.

Feb 15 15:39:40 localhost kernel[0]: USBMSC Identifier (non-unique): 000000009833 0x5ac 0x8403 0x9833

Feb 15 15:39:40 localhost kernel[0]: mbinit: done [64 MB total pool size, (42/21) split]

Feb 15 15:39:40 localhost kernel[0]: Pthread support ABORTS when sync kernel primitives misused

Feb 15 15:39:40 localhost kernel[0]: com.apple.AppleFSCompressionTypeZlib kmod start

Feb 15 15:39:40 localhost kernel[0]: com.apple.AppleFSCompressionTypeDataless kmod start

Feb 15 15:39:40 localhost kernel[0]: com.apple.AppleFSCompressionTypeZlib load succeeded

Feb 15 15:39:40 localhost kernel[0]: com.apple.AppleFSCompressionTypeDataless load succeeded

Feb 15 15:39:40 localhost kernel[0]: AppleIntelCPUPowerManagementClient: ready

Feb 15 15:39:40 localhost kernel[0]: BTCOEXIST off

Feb 15 15:39:40 localhost kernel[0]: wl0: Broadcom BCM432b 802.11 Wireless Controller

Feb 15 15:39:40 localhost kernel[0]: 5.100.98.75

Feb 15 15:39:40 localhost kernel[0]: [IOBluetoothHCIController::setConfigState] calling registerService

Feb 15 15:39:40 localhost kernel[0]: rooting via boot-uuid from /chosen: blah blah blah***

Feb 15 15:39:40 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>

Feb 15 15:39:40 localhost kernel[0]: Got boot device = IOService:/AppleACPIPlatformExpert/PCI0@0/AppleACPIPCI/SATA@B/AppleMCP79AHCI/PR T0@0/IOAHCIDevice@0/AppleAHCIDiskDriver/IOAHCIBlockStorageDevice/IOBlockStorageD river/Hitachi HTS545016B9SA02 Media/IOGUIDPartitionScheme/Macintosh HD@2

Feb 15 15:39:40 localhost kernel[0]: BSD root: disk0s2, major 14, minor 2

Feb 15 15:39:40 localhost kernel[0]: Kernel is LP64

Feb 15 15:39:49 localhost kernel[0]: Waiting for DSMOS...

Feb 15 15:39:49 localhost kernel[0]: macx_swapon SUCCESS

Feb 15 15:39:50 localhost kernel[0]: NVDANV50HAL loaded and registered.

Feb 15 15:39:50 localhost kernel[0]: NVEthernet: Ethernet address 00:25:bc:df:26:88

Feb 15 15:39:50 localhost kernel[0]: AirPort_Brcm4331: Ethernet address 00:25:00:4e:3e:c8

Feb 15 15:39:50 localhost kernel[0]: IO80211Controller::dataLinkLayerAttachComplete(): adding AppleEFINVRAM notification

Feb 15 15:39:50 localhost kernel[0]: IO80211Interface::efiNVRAMPublished():

Feb 15 15:39:50 localhost kernel[0]: Previous Shutdown Cause: 5

Feb 15 15:39:50 localhost kernel[0]: DSMOS has arrived

Feb 15 15:39:51 localhost kernel[0]: Created virtif 0xffffff801440c000 p2p0

Feb 15 15:39:51 localhost kernel[0]: AirPort: Link Down on en1. Reason 8 (Disassociated because station leaving).

Feb 15 15:39:52 localhost kernel[0]: 00000000 00000020 NVEthernet::setLinkStatus - not Active

Feb 15 15:39:54 Daniel-Garys-MacBook-Pro kernel[0]: en1: 802.11d country code set to 'US'.

Feb 15 15:39:54 Daniel-Garys-MacBook-Pro kernel[0]: en1: Supported channels 1 2 3 4 5 6 7 8 9 10 11 36 40 44 48 52 56 60 64 100 104 108 112 116 120 124 128 132 136 140 149 153 157 161 165

Feb 15 15:39:57 Daniel-Garys-MacBook-Pro kernel[0]: nspace-handler-set-snapshot-time: 1329338398

Feb 15 15:40:02 Daniel-Garys-MacBook-Pro kernel[0]: [ffffff8014966c00][BNBMouseDevice::init][66.6] init is complete

Feb 15 15:40:02 Daniel-Garys-MacBook-Pro kernel[0]: [ffffff8014966c00][BNBMouseDevice::handleStart][66.6] Done

Feb 15 15:40:02 Daniel-Garys-MacBook-Pro kernel[0]: [ffffff8015105a00][AppleMultitouchHIDEventDriver::start] entered

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro kernel[0]: [ffffff80148f5800][AppleMultitouchDevice::start] entered

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro kernel[0]: MacAuthEvent en1 Auth result for: 00:13:f7:fa:f1:0c MAC AUTH succeeded

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro kernel[0]: wlEvent: en1 en1 Link UP

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro kernel[0]: AirPort: Link Up on en1

Feb 15 15:40:03 Daniel-Garys-MacBook-Pro kernel[0]: en1: BSSID changed to 00:13:f7:fa:f1:0c

Feb 15 15:40:04 Daniel-Garys-MacBook-Pro kernel[0]: AirPort: RSN handshake complete on en1

Sorry for the long post!!!

Thanks for your help,

Daniel

Reply

Answer 3

Linc Davis

Level 10

209,541 points

Feb 15, 2012 1:26 PM in response to DanielG.

Uninstall "Virus Barrier" by following the developer's instructions, reboot, and test.

Reply

Answer 4

DanielG. Author

Level 1

0 points

Feb 15, 2012 1:46 PM in response to Linc Davis

Sorry- that didn't help.

Virus Barrier no longer appears in the console now.

I'm still thinking that it has something to do with the Executing fsck_hfs (version diskdev_cmds-540.1~34)

thing that takes so long in verbose boot. Any way to stop this from occuring?

Looking at any video like this, you can see that it's not really normal to happen on every boot.

Thanks for your help,

Daniel

Reply

Answer 5

DanielG. Author

Level 1

0 points

Feb 15, 2012 2:48 PM in response to Linc Davis

I've been looking at other forums about this, and just tried to "mount my root disk" using the terminal command

mount -uw /

The response was:

GetMasterBlock: Error 13 opening /dev/rdisk0s2

mount_hfs: Operation not permitted

I then tried

mount_hfs /dev0s2 /Volumes

And it responded

GetMasterBlock: Error 2 opening /dev0s2

mount_hfs: Operation not permitted

Does this have anything to do with it? What does it mean?

Thanks again,

Daniel

Reply

Answer 6

Linc Davis

Level 10

209,541 points

Feb 15, 2012 4:08 PM in response to DanielG.

Back up all data, then boot from your recovery partition (command-R at startup), launch Disk Utility, select your startup volume, and run "Repair Disk" (not "Repair Permissions.") If any problems are found, repeat.

Reply