9 Replies Latest reply: Feb 15, 2012 4:47 PM by DanielG.
DanielG. Level 1 Level 1 (0 points)

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)
  • 1. Re: Slow boot: "executing fsck"
    Linc Davis Level 10 Level 10 (117,725 points)

    ...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 after that 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.

  • 2. Re: Slow boot: "executing fsck"
    DanielG. Level 1 Level 1 (0 points)

    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]: Copyright (c) 1982, 1986, 1989, 1991, 1993

    Feb 15 15:39:40 localhost kernel[0]: The Regents of the University of California. All rights reserved.

    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

  • 3. Re: Slow boot: "executing fsck"
    Linc Davis Level 10 Level 10 (117,725 points)

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

  • 4. Re: Slow boot: "executing fsck"
    DanielG. Level 1 Level 1 (0 points)

    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

  • 5. Re: Slow boot: "executing fsck"
    DanielG. Level 1 Level 1 (0 points)

    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

    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

  • 6. Re: Slow boot: "executing fsck"
    Linc Davis Level 10 Level 10 (117,725 points)

    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.

  • 7. Re: Slow boot: "executing fsck"
    DanielG. Level 1 Level 1 (0 points)

    Nothing happened after I did that.

    Anything else that I can try to stop it from booting with fsck?
    Thanks,
    Daniel

  • 8. Re: Slow boot: "executing fsck"
    Linc Davis Level 10 Level 10 (117,725 points)

    I think if I were in your place, I'd erase and restore the boot volume. If that didn't work, I'd replace the drive.

  • 9. Re: Slow boot: "executing fsck"
    DanielG. Level 1 Level 1 (0 points)

    I'll try to do that soon. Hopefully it will work!