VMware Communities
adrianm
Contributor
Contributor

Internal Error When starting a VM on Fusion 6 - Mavericks Host

Hi,

I am trying to get Fusion 6 running on my test Mavericks Partition.  I am running preview 7 OS X 10.9 (13A569) the install seemed to go fine but I keep getting "Internal Error" messages when trying to start a VM.  I followed the uninstall instructions in the KB and installed again but I am still getting the error:

Syslog doesn't seem to have anything interesting.

VMFusion Log is attached there are some error messages in there that might be relevant.

I have Server Installed and I am set up for Open Directory.

Any suggestions pls?

Adrian

Reply
0 Kudos
53 Replies
VladimirL
Contributor
Contributor

Yes, I've tried vmx.log.temporaryFileName option. No luck.

And latest update doesn't help either (Professional Version 6.0.2 (1398658)).

Collected diagnostic information attached. Thanks!

Reply
0 Kudos
wongv
VMware Employee
VMware Employee

Thanks for the support file, VladimirL.  I noticed errors about loading the 6.0.2 kexts in the system log.  Please unload the kexts from the previous version by running: /Applications/VMware Fusion.app/Contents/Library/services.sh --stop.  Then retry running vmx from the command line with the following step (it seems like there was a missing "\" before one of the spaces in your previous attempt to do this).

  • Quit Fusion if it is running and set your core file size limit to unlimited: ulimit -c unlimited
  • In the file "/Users/Vladimir/LIbrary/Preferences/VMware Fusion/config", add the following line
    vmx.log.destination = "syslog"
  • Start the services: sudo /Applications/VMware Fusion.app/Contents/Library/services.sh --start
  • Try to run the VM (the quotes are necessary): "/Applications/VMware Fusion.app/Contents/Library/vmware-vmx-debug" -x "/Users/Vladimir/Documents/Virtual Machines.localized/Windows XP Professional.vmwarevm/Windows XP Professional.vmx"
  • If the previous command fails, please post the output and attach /var/log/system.log
  • Stop the services: sudo /Applications/VMware Fusion.app/Contents/Library/services.sh --stop
Reply
0 Kudos
VladimirL
Contributor
Contributor

Hi!

I've changed number of processor cores from 4 to 2 in settings and it's working again!

Thanks for the support!

Reply
0 Kudos
febygeorge
Contributor
Contributor

I upgraded from OSX Mountain Lion to Mavericks recently, and was on Fusion 5.0. The virtual machines failed to startup after the OS upgrade.

Now I upgraded to Fusion 6.0.2, the problem persists. Tried removing and reinstalling Fusion 6.0 from scratch, that didn't help.

Errors displayed are

"Only 2048 open files are allowed. Please raise the open file limit to at least 8192, using the command "limit descriptors ..." or "ulimit -n ...".

"Internal error"

Attaching the support information file is available at https://dl.dropboxusercontent.com/u/14950594/vm-12-02-13.1436.zip.

Reply
0 Kudos
febygeorge
Contributor
Contributor

tried following some of the steps in the earlier messages.

sudo "/Applications/VMware Fusion.app/Contents/Library/vmware-vmx-debug" -x "/Users/fgeorge/Documents/Virtual Machines/Windows 8 x64.vmwarevm/Windows 8 x64.vmx"

2013-12-02T14:00:18.044| ServiceImpl_Opener: PID 2977

ServiceUser_Open: /Applications/VMware Fusion.app/Contents/Library/Open VMware Fusion Services exit status 256.

Services_Init: Failed to open the services.

PowerOn

HINT(no connection): msg.loader.debug.wsAndFusion

NUMA: automatic VM sizing request ignored

USBGL: Connected to arbitrator socket: 104

Could not initialize emulated USB smart card subsystem.

Question without a remote UI: msg.noAutodetectBackingQuestion

VIDE: (0x170) OUTB Cmd 0x2f, Aborting Unknown Atapi Command

VIDE: Truncating 0x5a from 65520 bytes to 32768

HDAudio: HDAudioControllerReset: RIRB run bit is set.

/var/log/system.log has the following


Dec  2 13:58:01 fgeorge.local sudo[2900]:  fgeorge : TTY=ttys001 ; PWD=/Users/fgeorge ; USER=root ; COMMAND=/Applications/VMware Fusion.app/Contents/Library/services.sh --start

Dec  2 13:58:01 fgeorge kernel[0]: vmci: Loaded @ 0xffffff7fb02782ad: Info 0xffffff7fb02830f8 Name com.vmware.kext.vmci Version 90.5.7 build-1398658 at Oct 29 2013 18:34:30

Dec  2 13:58:01 fgeorge kernel[0]: vmci: Initializing module.

Dec  2 13:58:01 fgeorge kernel[0]: vmci: VMCI: shared components initialized.

Dec  2 13:58:01 fgeorge kernel[0]: vmci: Begin helper queue thread.

Dec  2 13:58:01 fgeorge kernel[0]: vmci: VMCI: host components initialized.

Dec  2 13:58:01 fgeorge kernel[0]: vmci: Module initialized.

Dec  2 13:58:01 fgeorge kernel[0]: vsock: Loaded @ 0xffffff7fb0288aa4: Info 0xffffff7fb0293030 Name com.vmware.kext.vsockets Version 90.5.7 build-1398658 at Oct 29 2013 18:34:32

Dec  2 13:58:01 fgeorge kernel[0]: vsock: Initializing module...

Dec  2 13:58:01 fgeorge kernel[0]: vsock: Begin workloop.

Dec  2 13:58:01 fgeorge kernel[0]: vsock: Module initialized.

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: Loaded @ 0xffffff7fb0298ab6: Info 0xffffff7fb02a0020 Name com.vmware.kext.vmnet Version 0139.86.58 build-1398658 at Oct 29 2013 18:34:33

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: Initializing module.

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: VMNet_Start allocated gOSMallocTag.

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: VMNet_Start allocated vnetBigLock.

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: Module initialized.

Dec  2 13:58:01 fgeorge kernel[0]: vmmon: Loaded @ 0xffffff7fb02a1eca: Info 0xffffff7fb02ac040 Name com.vmware.kext.vmx86 Version 0139.86.58 build-1398658 at Oct 29 2013 18:34:27

Dec  2 13:58:01 fgeorge kernel[0]: vmmon: Instrumenting bug 151304...

Dec  2 13:58:01 fgeorge kernel[0]: vmmon: Cycles 42

Dec  2 13:58:01 fgeorge kernel[0]: vmmon: Timer thread started.

Dec  2 13:58:01 fgeorge kernel[0]: vmmon: Module initialized.

Dec  2 13:58:01 fgeorge.local vmnet-bridge[2941]: Dynamic store changed

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: VNetUserIf_Create: created userIf at 0xffffff8048cf3c00.

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: VMNetConnect: returning port 0xffffff8048cf3c00

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: Hub 0 does not exist, allocating memory.

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: Allocated hub 0xffffff804c806000 for hubNum 0.

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: VMNET_SO_BINDTOHUB: port: paddr 00:50:56:ea:b1:c9

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: Hub 0

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: Port 0

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: bridge-en3: media 20 dev 0xffffff8043305ab0 family 2

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: bridge-en3: up

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: bridge-en3: attached

Dec  2 13:58:01 fgeorge kernel[0]: vmnet: VNetUserIfFree: freeing userIf at 0xffffff8048cf3c00.

Dec  2 13:58:01 fgeorge.local vmnet-bridge[2941]: Started bridge for 0, en3

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VNetUserIf_Create: created userIf at 0xffffff804621c600.

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VMNetConnect: returning port 0xffffff804621c600

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Hub 1 does not exist, allocating memory.

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Allocated hub 0xffffff8044884000 for hubNum 1.

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VMNET_SO_BINDTOHUB: port: paddr 00:50:56:fd:74:e0

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Hub 1

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Port 0

Dec  2 13:58:02 fgeorge kernel[0]: vmnet1: attached with 1 suspended link-layer multicast membership(s)

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VNetUserIfFree: freeing userIf at 0xffffff804621c600.

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: netif-vmnet1: Adding protocol 2.

Dec  2 13:58:02 fgeorge kernel[0]: vmnet1: failed to restore 1 suspended link-layer multicast membership(s) (err=102)

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: netif-vmnet1: SIOCSIFFLAGS: 0x8863

Dec  2 13:58:02 --- last message repeated 1 time ---

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VNetUserIf_Create: created userIf at 0xffffff8045ae9c00.

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VMNetConnect: returning port 0xffffff8045ae9c00

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VMNET_SO_BINDTOHUB: port: paddr 00:50:56:f8:b9:be

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Hub 1

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Port 0

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Port 1

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VNetUserIf_Create: created userIf at 0xffffff804ab77a00.

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VMNetConnect: returning port 0xffffff804ab77a00

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Hub 8 does not exist, allocating memory.

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Allocated hub 0xffffff804d4b9000 for hubNum 8.

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VMNET_SO_BINDTOHUB: port: paddr 00:50:56:eb:73:08

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Hub 8

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Port 0

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VMNetSetopt: Set link state UP

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VNetUserIf_Create: created userIf at 0xffffff80453a9600.

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VMNetConnect: returning port 0xffffff80453a9600

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VMNET_SO_BINDTOHUB: port: paddr 00:50:56:f0:4d:1e

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Hub 8

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Port 0

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Port 1

Dec  2 13:58:02 fgeorge kernel[0]: vmnet8: attached with 1 suspended link-layer multicast membership(s)

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VNetUserIfFree: freeing userIf at 0xffffff80453a9600.

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: netif-vmnet8: Adding protocol 2.

Dec  2 13:58:02 fgeorge kernel[0]: vmnet8: failed to restore 1 suspended link-layer multicast membership(s) (err=102)

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: netif-vmnet8: SIOCSIFFLAGS: 0x8863

Dec  2 13:58:02 --- last message repeated 1 time ---

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VNetUserIf_Create: created userIf at 0xffffff80453ce000.

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VMNetConnect: returning port 0xffffff80453ce000

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: VMNET_SO_BINDTOHUB: port: paddr 00:50:56:f7:93:94

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Hub 8

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Port 0

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Port 1

Dec  2 13:58:02 fgeorge kernel[0]: vmnet: Port 2

Dec  2 13:58:02 fgeorge kernel[0]: vmioplug: Loaded @ 0xffffff7fb02afd00: Info 0xffffff7fb02b20a8 Name com.vmware.kext.vmioplug.12.1.13 Version 12.1.13 build-1398658 at Oct 29 2013 18:34:34

Dec  2 13:58:04 fgeorge.local mDNSResponder[46]: mDNS_RegisterInterface: Frequent transitions for interface vmnet1 (192.168.21.1)

Dec  2 13:58:04 fgeorge.local mDNSResponder[46]: mDNS_RegisterInterface: Frequent transitions for interface vmnet8 (172.16.53.1)

Dec  2 14:00:17 fgeorge.local sudo[2975]:  fgeorge : TTY=ttys001 ; PWD=/Users/fgeorge ; USER=root ; COMMAND=/Applications/VMware Fusion.app/Contents/Library/vmware-vmx-debug -x /Users/fgeorge/Documents/Virtual Machines/Windows 8 x64.vmwarevm/Windows 8 x64.vmx

Dec  2 14:00:18 fgeorge.local VMware Fusion 1398658[3019]: Error: Unable to start the bridge services. Error: /Applications/VMware Fusion.app/Contents/Library/vmnet-bridge is already running, process ID: 2941 [1]

Dec  2 14:00:18 fgeorge kernel[0]: vmmon: offset 0: 80

Dec  2 14:00:18 fgeorge kernel[0]: vmmon: offset 1: 16

Dec  2 14:00:18 fgeorge kernel[0]: vmmon: offset 2: 56

Dec  2 14:00:18 fgeorge kernel[0]: vmmon: offset 3: 64

Dec  2 14:00:18 fgeorge kernel[0]: vmmon: offset 4: 76

Dec  2 14:00:18 fgeorge kernel[0]: vmmon: PTSC: initialized at 2394296000 Hz using reference clock, TSCs are synchronized.

Dec  2 14:00:18 fgeorge kernel[0]: vmmon: Cycles 44

Dec  2 14:00:19 fgeorge.local WindowServer[139]: Received display connect changed for display 0x4280380

Dec  2 14:00:19 fgeorge.local WindowServer[139]: Found 62 modes for display 0x04280380 [36, 26]

Dec  2 14:00:19 fgeorge.local WindowServer[139]: Received display connect changed for display 0x3f003d

Dec  2 14:00:19 fgeorge.local WindowServer[139]: Found 1 modes for display 0x003f003d [1, 0]

Dec  2 14:00:19 fgeorge.local WindowServer[139]: Received display connect changed for display 0x3f003e

Dec  2 14:00:19 fgeorge.local WindowServer[139]: Found 1 modes for display 0x003f003e [1, 0]

Dec  2 14:00:19 fgeorge.local WindowServer[139]: Received display connect changed for display 0x3f003f

Dec  2 14:00:19 fgeorge.local WindowServer[139]: Found 1 modes for display 0x003f003f [1, 0]

Dec  2 14:00:19 fgeorge.local WindowServer[139]: MPAccessSurfaceForDisplayDevice: Set up page flip mode on display 0x04280380 device: 0x7fb423e089f0  isBackBuffered: 1 numComp: 3 numDisp: 3

Dec  2 14:00:19 fgeorge.local WindowServer[139]: CGXMuxAcknowledge: Posting glitchless acknowledge

Dec  2 14:00:19 fgeorge.local WindowServer[139]: Received display connect changed for display 0x4280380

Dec  2 14:00:19 fgeorge.local WindowServer[139]: Found 1 modes for display 0x04280380 [1, 0]

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: VNetUserIf_Create: created userIf at 0xffffff80423e3000.

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: VMNetConnect: returning port 0xffffff80423e3000

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: VMNET_SO_BINDTOHUB: port: paddr 00:50:56:ff:07:1c

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: Hub 8

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: Port 0

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: Port 1

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: Port 2

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: Port 3

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: VNetUserIf_Create: created userIf at 0xffffff8049140c00.

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: VMNetConnect: returning port 0xffffff8049140c00

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: VMNET_SO_BINDTOHUB: port: paddr 00:50:56:e1:85:84

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: Hub 8

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: Port 0

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: Port 1

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: Port 2

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: Port 3

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: Port 4

Dec  2 14:00:21 fgeorge kernel[0]: vmnet: VNetUserIfFree: freeing userIf at 0xffffff8049140c00.

Reply
0 Kudos
wongv
VMware Employee
VMware Employee

Hi febygeorge, you have hit a different problem.  Please follow the instructions of the error message to raise your open file limit (e.g. "limit descriptors 8192" or "ulimit -n 8192") and try again.

Reply
0 Kudos
febygeorge
Contributor
Contributor

I am unable to do so on my mac 10.9

ulimit -n 8192

-bash: ulimit: open files: cannot modify limit: Invalid argument

Since I haven't made any explicit changes to this setting any time in the past, I was wondering why this issue cropped up in the first place?

Reply
0 Kudos
febygeorge
Contributor
Contributor

ok..ignore my previous update.. I upped the limits by following the directions @ https://coderwall.com/p/lfjoaq

Things are working fine now. Thanks.

Still curious to know why no one else has faced this issue.

Reply
0 Kudos
dariusd
VMware Employee
VMware Employee

Do you have an /etc/launchd.conf?  If so, what does it contain?

Cheers,

--

Darius

Reply
0 Kudos
febygeorge
Contributor
Contributor

yeah.. i modified the limit in /etc/launchd.conf and its working now

[fgeorge@fgeorge ~ 17:54:46 ] more /etc/launchd.conf

limit maxfiles 8192 32768

Reply
0 Kudos
dariusd
VMware Employee
VMware Employee

Thanks for posting back with news of your success and a link to that web page... That's super helpful for if anyone else does end up encountering the same problem.  :smileycool:

Normally /etc/launchd.conf is absent.  If there were problematic ulimits already configured in there, it was not part of the default configuration of OS X or Fusion.  Maybe some other program has configured the limits without telling you...

Cheers,

--

Darius

Reply
0 Kudos
fragpit
Contributor
Contributor

Hi guys,

I faced the same problem on OS X 10.10 and Fusion 7 (7.0 and 7.1.1)

and I found, that fixing permissions in Disk Utility solves the problem.

Reply
0 Kudos
jaschluc
Contributor
Contributor

I also have had an issue ever since upgrading to Mavericks, currently at 10.10.3. I get the same "Internal Error" whenever I try to run my VMs. I even upgraded to Fusion version 7.0.1 with no luck. I went through this entire thread and tried and checked the various suggestions with no luck either. I have attached a crash dump.

Also see the below. It hang for about 15 mins

sudo /Applications/VMware\ Fusion.app/Contents/Library/vmware-vmx-debug -x /Users/me/Documents/Virtual\ Machines.localized/Cisco\ Windows\ 7\ x64.vmwarevm/Cisco\ Windows\ 7\ x64.vmx

2015-06-02T11:20:54.163| ServiceImpl_Opener: PID 32012

PowerOn

HINT(no connection): msg.loader.debug.wsAndFusion

NUMA: automatic VM sizing request ignored

VProbes facility is disabled.

VProbes facility is disabled.

VIDE: Truncating 0x5a from 65520 bytes to 32768

HDAudio: HDAudioControllerReset: RIRB run bit is set.

Bluetooth-MacOS: Error in retrieving host adapter

VIDE: Truncating 0x5a from 65520 bytes to 32768

HDAudio: HDAudioControllerReset: RIRB run bit is set.

Bluetooth-MacOS: Error in retrieving host adapter

Bluetooth-MacOS: Error in retrieving host adapter

Bluetooth-MacOS: Error in retrieving host adapter

Bluetooth-MacOS: Error in retrieving host adapter

Reply
0 Kudos
wongv
VMware Employee
VMware Employee

Hi.  Your system ran out of file descriptor.  The error in /Users/jaschluc/Documents/Virtual Machines.localized/Cisco Windows 7 x64.vmwarevm/vmware.log is:

2015-06-02T09:40:55.722-08:00| vmx| I120: SyncWaitQPanicOnFdLimit: The system limit on the total number of open files has been reached.

If this is a regular occurrence, next time it happens, please run "sudo lsof > fd.txt".  The content of fd.txt would show where all the file descriptors are being used.  This may or may not be caused by Fusion.

Reply
0 Kudos