Parallels 13.0 issue #67

thenoid · 2017-08-29T17:13:37Z

VM never starts.

> docker-machine -D create --driver=parallels prl-dev
Docker Machine Version:  0.12.2, build 9371605
Found binary path at /usr/local/bin/docker-machine-driver-parallels
Launching plugin server for driver parallels
Plugin server listening at address 127.0.0.1:58803
() Calling .GetVersion
Using API Version  1
() Calling .SetConfigRaw
() Calling .GetMachineName
(flag-lookup) Calling .GetMachineName
(flag-lookup) Calling .DriverName
(flag-lookup) Calling .GetCreateFlags
Found binary path at /usr/local/bin/docker-machine-driver-parallels
Launching plugin server for driver parallels
Plugin server listening at address 127.0.0.1:58807
() Calling .GetVersion
Using API Version  1
() Calling .SetConfigRaw
() Calling .GetMachineName
(prl-dev) Calling .GetMachineName
(prl-dev) Calling .DriverName
(prl-dev) Calling .GetCreateFlags
(prl-dev) Calling .SetConfigFromFlags
Running pre-create checks...
(prl-dev) Calling .PreCreateCheck
(prl-dev) DBG | executing: /usr/local/bin/prlctl --version
(prl-dev) DBG | executing: /usr/local/bin/prlsrvctl info --license
(prl-dev) DBG | Found Parallels Desktop version: 13, edition: business
(prl-dev) DBG | executing: /usr/local/bin/prlsrvctl net info Shared
(prl-dev) DBG | local Boot2Docker ISO version:  v17.06.1-ce
(prl-dev) Calling .GetConfigRaw
Creating machine...
(prl-dev) Calling .Create
(prl-dev) DBG | local Boot2Docker ISO version:  v17.06.1-ce
(prl-dev) Copying /Users/rolsen/.docker/machine/cache/boot2docker.iso to /Users/rolsen/.docker/machine/machines/prl-dev/boot2docker.iso...
(prl-dev) Creating SSH key...
(prl-dev) DBG | SSH key: /Users/rolsen/.docker/machine/machines/prl-dev/id_rsa
(prl-dev) Creating Parallels Desktop VM...
(prl-dev) DBG | executing: /usr/local/bin/prlctl --version
(prl-dev) DBG | executing: /usr/local/bin/prlctl create prl-dev --distribution boot2docker --dst /Users/rolsen/.docker/machine/machines/prl-dev --no-hdd
(prl-dev) DBG | executing: /usr/local/bin/prlctl set prl-dev --select-boot-device off --cpus 4 --memsize 4096 --cpu-hotplug off --on-window-close keep-running --longer-battery-life on --3d-accelerate off --device-bootorder cdrom0
(prl-dev) DBG | executing: /usr/local/bin/prlctl set prl-dev --device-set cdrom0 --iface sata --position 0 --image /Users/rolsen/.docker/machine/machines/prl-dev/boot2docker.iso --connect
(prl-dev) DBG | executing: /usr/local/bin/prlctl set prl-dev --device-add hdd --iface sata --position 1 --image /Users/rolsen/.docker/machine/machines/prl-dev/disk.hdd --type plain --size 32
(prl-dev) DBG | Writing magic tar header
(prl-dev) DBG | Writing SSH key tar header
(prl-dev) DBG | HDS image path: /Users/rolsen/.docker/machine/machines/prl-dev/disk.hdd/disk.hdd.0.{5fbaabe3-6958-40ff-92a7-860e329aab41}.hds
(prl-dev) DBG | executing: /usr/local/bin/prl_disk_tool convert --expanding --hdd /Users/rolsen/.docker/machine/machines/prl-dev/disk.hdd
Error creating machine: Error in driver during machine creation: exit status 2
notifying bugsnag: [Error creating machine: Error in driver during machine creation: exit status 2]

> /usr/local/bin/prl_disk_tool convert --expanding --hdd /Users/rolsen/.docker/machine/machines/prl-dev/disk.hdd
Unable to open the disk image.

> ls -la /Users/rolsen/.docker/machine/machines/prl-dev/disk.hdd
total 8
drwxr-xr-x 5 rolsen staff  170 Aug 29 11:12 .
drwx------ 8 rolsen staff  272 Aug 29 11:12 ..
-rw-r--r-- 1 rolsen staff 1600 Aug 29 11:12 DiskDescriptor.xml
-rw-r--r-- 1 rolsen staff 1600 Aug 29 11:12 DiskDescriptor.xml.Backup
-rw-rw-rw- 1 rolsen staff    0 Aug 29 11:12 disk.hdd

The text was updated successfully, but these errors were encountered:

rickard-von-essen · 2017-08-29T17:25:24Z

What happens if you execute the same commands manually?

thenoid · 2017-08-29T17:51:04Z

mkdir -p /Users/rolsen/.docker/machine/machines/prl-dev/
> cp /Users/rolsen/.docker/machine/cache/boot2docker.iso /Users/rolsen/.docker/machine/machines/prl-dev/boot2docker.iso
> /usr/local/bin/prlctl create prl-dev --distribution boot2docker --dst /Users/rolsen/.docker/machine/machines/prl-dev --no-hdd
Creating the virtual machine...
Generate the VM configuration for boot2docker.
Remove the hdd0 device.
The VM has been successfully created.
> /usr/local/bin/prlctl set prl-dev --select-boot-device off --cpus 4 --memsize 4096 --cpu-hotplug off --on-window-close keep-running --longer-battery-life on --3d-accelerate off --device-bootorder cdrom0
set cpus(8): 4
set cpu hotplug: 0
Set the memsize parameter to 4096Mb.
Set 3d acceleration: 0.

The VM has been successfully configured.
> /usr/local/bin/prlctl set prl-dev --device-set cdrom0 --iface sata --position 0 --image /Users/rolsen/.docker/machine/machines/prl-dev/boot2docker.iso --connect
Creating cdrom0 (+) sata:0 image='/Users/rolsen/.docker/machine/machines/prl-dev/boot2docker.iso'

Configured cdrom0 (+) sata:0 image='/Users/rolsen/.docker/machine/machines/prl-dev/boot2docker.iso'


The VM has been successfully configured.
> /usr/local/bin/prlctl set prl-dev --device-add hdd --iface sata --position 1 --image /Users/rolsen/.docker/machine/machines/prl-dev/disk.hdd --type plain --size 32
The folder /Users/rolsen/.docker/machine/machines/prl-dev/disk.hdd does not exist. Specify another folder and try again.
Creating hdd0 (+) sata:1 image='/Users/rolsen/.docker/machine/machines/prl-dev/disk.hdd' type='plain' 32Mb online compact=off

Created hdd0 (+) sata:1 image='/Users/rolsen/.docker/machine/machines/prl-dev/disk.hdd' type='plain' 32Mb online compact=on


The VM has been successfully configured.
> /usr/local/bin/prl_disk_tool convert --expanding --hdd /Users/rolsen/.docker/machine/machines/prl-dev/disk.hdd

An error occurred during the operation: PRL_ERR_DISK_CREATE_IMAGE_ERROR (0x80021016)

but i don't know if that error is caused by my not running whatever magic this does

(prl-dev) DBG | Writing magic tar header
(prl-dev) DBG | Writing SSH key tar header

ryansch · 2017-08-30T18:52:54Z

I just ran into this as well. I can't provision a docker VM at all on parallels desktop 13.

Exact same failure mode with /usr/local/bin/prl_disk_tool convert --expanding --hdd /Users/ryansch/.docker/machine/machines/default/disk.hdd

rickard-von-essen · 2017-08-30T19:44:53Z

I can confirm this.

TomKeur · 2017-08-30T21:13:19Z

Some problem over here:

docker-machine create --driver=parallels --parallels-memory 4096 --parallels-cpu-count 4 --parallels-disk-size 20480 default

(default) DBG | HDS image path: /Users/tomkeur/.docker/machine/machines/default/disk.hdd/disk.hdd.0.{5fbaabe3-6958-40ff-92a7-860e329aab41}.hds
(default) DBG | executing: /usr/local/bin/prl_disk_tool convert --expanding --hdd /Users/tomkeur/.docker/machine/machines/default/disk.hdd
Error creating machine: Error in driver during machine creation: exit status 2
notifying bugsnag: [Error creating machine: Error in driver during machine creation: exit status 2]

When I'm running:
/usr/local/bin/prl_disk_tool convert --expanding --hdd /Users/tomkeur/.docker/machine/machines/default/disk.hdd

I'm getting the following error:

Unable to open the disk image.

rickard-von-essen · 2017-08-31T05:04:59Z

This sequence of commands doesn't seem to work anymore:

prlctl set <vm> --device-add hdd --iface sata --position 1 --image ~/disk.hdd --type plain --size 32
prl_disk_tool convert --expanding --hdd ~/disk.hdd

@legal90 any insight?

bradbirnbaum · 2017-08-31T13:35:40Z

I have the same issue. Had to downgrade to Parallels 12

legal90 · 2017-08-31T14:10:33Z

Hi all,
Yeah, I can confirm that as well. It looks like the conversion of the disk image from plain to expanding is broken in Parallels Desktop 13.
Since I don't work at Parallels any longer, I don't have insights there anymore.
So, I hope that @romankulikov could help us. The details are below.

Reproduction Scenario

Create an empty machine:

prlctl create test-vm --distribution boot2docker --no-hdd

Attach a plain disk to it:

prlctl set test-vm --device-add hdd --iface sata --image ~/disk.hdd --type plain --size 32

Convert the disk image to expanding:

prl_disk_tool convert --expanding --hdd ~/disk.hdd

An error occurred during the operation: PRL_ERR_DISK_CREATE_IMAGE_ERROR (0x80021016)

After some investigation I've found that the latter command actually breaks the disk image. Just compare the disk dir content before running it:

$ ls -la /Users/legal/disk.hdd/*
-rw-r--r--  1 legal  staff      1369 Aug 31 15:44 /Users/legal/disk.hdd/DiskDescriptor.xml
-rw-r--r--  1 legal  staff      1326 Aug 31 15:44 /Users/legal/disk.hdd/DiskDescriptor.xml.Backup
-rw-rw-rw-  1 legal  staff         0 Aug 31 15:44 /Users/legal/disk.hdd/disk.hdd
-rw-r--r--  1 legal  staff  33554432 Aug 31 15:44 /Users/legal/disk.hdd/disk.hdd.0.{5fbaabe3-6958-40ff-92a7-860e329aab41}.hds

...with how it looks after running prl_disk_tool convert --expanding <...>:

$ ls -la /Users/legal/disk.hdd/*
-rw-r--r--  1 legal  staff  1600 Aug 31 15:46 /Users/legal/disk.hdd/DiskDescriptor.xml
-rw-r--r--  1 legal  staff  1565 Aug 31 15:46 /Users/legal/disk.hdd/DiskDescriptor.xml.Backup
-rw-rw-rw-  1 legal  staff     0 Aug 31 15:44 /Users/legal/disk.hdd/disk.hdd

Logs

That's what we have in the /Library/Logs/parallels.log:

08-31 15:46:38.916 I /imagetool:87191:775/ IL000000.00 '/Users/legal/disk.hdd'
08-31 15:46:38.952 I /imagetool:87191:775/ IL000000.01
08-31 15:46:38.952 I /imagetool:87191:775/ IL000022.00
08-31 15:46:38.952 I /imagetool:87191:775/ IL000022.01
08-31 15:46:38.952 I /imagetool:87191:775/ IL000010.00
08-31 15:46:38.952 W /imagetool:87191:775/ Failed to get shutdown state
08-31 15:46:38.953 W /imagetool:87191:775/ Failed to get disk change state
08-31 15:46:38.956 W /imagetool:87191:775/ MBR not found, assume whole disk is empty
08-31 15:46:38.956 W /imagetool:87191:775/ Unable to reopen disk image. Disk can't be compacted. 0x80046001
08-31 15:46:38.956 I /imagetool:87191:775/ Image info: [Image: /Users/legal/disk.hdd] [Type: 1] [Size 32 MB (65536 sectors)] [Sector size 512] [Splitted: NO] [Suspended: NO] [ShutDown state: 0] [Changed state: NO] [Old: NO] [Snaphots: 1] [Compactable: NO] [Content resizable: YES] [Encrypted: NO]
08-31 15:46:38.957 I /imagetool:87191:775/ IL000010.01
08-31 15:46:38.957 I /imagetool:87191:775/ IL000002.00 0 1
08-31 15:46:38.957 I /imagetool:87191:775/ IL000002.01
08-31 15:46:38.957 I /imagetool:87191:775/ IL000005.00 Commit changes...
08-31 15:46:38.957 I /imagetool:87191:775/ IL000004.00
08-31 15:46:38.957 I /imagetool:87191:775/ IL000016.00
08-31 15:46:38.957 I /imagetool:87191:775/ IL000017.00
08-31 15:46:38.957 I /imagetool:87191:775/ IL000017.01
08-31 15:46:38.957 I /imagetool:87191:775/ IL000016.01
08-31 15:46:38.957 I /imagetool:87191:775/ IL000027.00
08-31 15:46:38.957 I /imagetool:87191:775/ IL000027.01
08-31 15:46:38.973 F /AbstractFile:87191:775/ open64() error: 17, flags = 0x2, disp = 0x1000A00
08-31 15:46:38.973 F /AbstractFile:87191:775/ [0x7fca5552d7b0]OpenHandle: Error opening file /Users/legal/disk.hdd/disk.hdd.0.{5fbaabe3-6958-40ff-92a7-860e329aab41}.hds with error 17 in handle pool
08-31 15:46:38.973 F /AbstractFile:87191:775/ [0x7fca5552d7b0]OpenHandle: Access = 0x3, Share = 0x1, Disp = 0xA00, Add = 0x0
08-31 15:46:38.973 F /AbstractFile:87191:775/ HPWrap[0x7fca5552d7b0] --- Entry info ---
08-31 15:46:38.973 F /AbstractFile:87191:775/ HPWrap[0x7fca5552d7b0] handle (0x7fca5552d7c0):
08-31 15:46:38.973 F /AbstractFile:87191:775/ HandleDesc[0x7fca5552d7c0] Iface = 0x7fca5552f9d0
08-31 15:46:38.973 F /AbstractFile:87191:775/ HandleDesc[0x7fca5552d7c0] Name = /Users/legal/disk.hdd/disk.hdd.0.{5fbaabe3-6958-40ff-92a7-860e329aab41}.hds
08-31 15:46:38.973 F /AbstractFile:87191:775/ HandleDesc[0x7fca5552d7c0] Access = 0x3
08-31 15:46:38.973 F /AbstractFile:87191:775/ HandleDesc[0x7fca5552d7c0] Share disp = 0x1
08-31 15:46:38.973 F /AbstractFile:87191:775/ HandleDesc[0x7fca5552d7c0] Disposition = 0xA00
08-31 15:46:38.973 F /AbstractFile:87191:775/ HandleDesc[0x7fca5552d7c0] Block size = 0
08-31 15:46:38.973 F /AbstractFile:87191:775/ HandleDesc[0x7fca5552d7c0] References to ICommonFile = 0
08-31 15:46:38.973 F /AbstractFile:87191:775/ HandleDesc[0x7fca5552d7c0] RefGuard state = locked
08-31 15:46:38.973 F /AbstractFile:87191:775/ HPWrap[0x7fca5552d7b0] disk descriptor = 0x7fca5554ec10
08-31 15:46:38.973 F /AbstractFile:87191:775/ HPWrap[0x7fca5552d7b0] --- Pool info ---
08-31 15:46:38.973 F /AbstractFile:87191:775/ HPWrap[0x7fca5552d7b0] Maximum handles count = 1194
08-31 15:46:38.973 F /AbstractFile:87191:775/ HPWrap[0x7fca5552d7b0] Opened disks count = 2
08-31 15:46:38.973 F /AbstractFile:87191:775/ HPWrap[0x7fca5552d7b0] Currently opened handles = 1
08-31 15:46:38.974 F /AbstractFile:87191:775/ HPWrap[0x7fca5552d7b0] Disk descriptors [2]:
08-31 15:46:38.974 F /AbstractFile:87191:775/ DiskEnt[0x7fca55419370] Disk Id = 0x1
08-31 15:46:38.974 F /AbstractFile:87191:775/ DiskEnt[0x7fca55419370] Opened handles count = 1
08-31 15:46:38.974 F /AbstractFile:87191:775/ DiskEnt[0x7fca55419370] All handles count = 1
08-31 15:46:38.974 F /AbstractFile:87191:775/ DiskEnt[0x7fca55419370] HandleListGuard state = unlocked
08-31 15:46:38.974 F /AbstractFile:87191:775/ DiskEnt[0x7fca55419370] Handle descriptors:
08-31 15:46:38.974 F /AbstractFile:87191:775/ HandleDesc[0x7fca55416ca0] Iface = 0x7fca5541aa40
08-31 15:46:38.974 F /AbstractFile:87191:775/ HandleDesc[0x7fca55416ca0] Name = /Users/legal/disk.hdd/disk.hdd.0.{5fbaabe3-6958-40ff-92a7-860e329aab41}.hds
08-31 15:46:38.974 F /AbstractFile:87191:775/ HandleDesc[0x7fca55416ca0] Access = 0x3
08-31 15:46:38.974 F /AbstractFile:87191:775/ HandleDesc[0x7fca55416ca0] Share disp = 0x1
08-31 15:46:38.974 F /AbstractFile:87191:775/ HandleDesc[0x7fca55416ca0] Disposition = 0x0
08-31 15:46:38.974 F /AbstractFile:87191:775/ HandleDesc[0x7fca55416ca0] Block size = 0
08-31 15:46:38.974 F /AbstractFile:87191:775/ HandleDesc[0x7fca55416ca0] References to ICommonFile = 0
08-31 15:46:38.974 F /AbstractFile:87191:775/ HandleDesc[0x7fca55416ca0] RefGuard state = unlocked
08-31 15:46:38.974 F /AbstractFile:87191:775/ DiskEnt[0x7fca55419370] --- Has 1 handle descriptors
08-31 15:46:38.974 F /AbstractFile:87191:775/ DiskEnt[0x7fca5554ec10] Disk Id = 0x2
08-31 15:46:38.974 F /AbstractFile:87191:775/ DiskEnt[0x7fca5554ec10] Opened handles count = 0
08-31 15:46:38.974 F /AbstractFile:87191:775/ DiskEnt[0x7fca5554ec10] All handles count = 1
08-31 15:46:38.974 F /AbstractFile:87191:775/ DiskEnt[0x7fca5554ec10] HandleListGuard state = unlocked
08-31 15:46:38.974 F /AbstractFile:87191:775/ DiskEnt[0x7fca5554ec10] Handle descriptors:
08-31 15:46:38.974 F /AbstractFile:87191:775/ DiskEnt[0x7fca5554ec10] --- No handle descriptors
08-31 15:46:38.974 F /dimg:87191:775/ CreateFile(/Users/legal/disk.hdd/disk.hdd.0.{5fbaabe3-6958-40ff-92a7-860e329aab41}.hds) failed!: 17
08-31 15:46:38.974 F /dimg:87191:775/ Init: Can't open file 0x80021012
08-31 15:46:38.974 F /dimg:87191:775/ CCompImage:
08-31 15:46:38.974 F /dimg:87191:775/ Compact parameter for callback: 0x0
08-31 15:46:38.974 F /dimg:87191:775/ CStructImage:
08-31 15:46:38.974 F /dimg:87191:775/ Is initialized: no
08-31 15:46:38.974 F /dimg:87191:775/ Maximum file size: 18446744073709551615 bytes
08-31 15:46:38.974 F /dimg:87191:775/ Last size for rollback: 0 bytes
08-31 15:46:38.974 F /dimg:87191:775/ Create: Error initializing for the /Users/legal/disk.hdd/disk.hdd.0.{5fbaabe3-6958-40ff-92a7-860e329aab41}.hds
08-31 15:46:38.974 F /dimg:87191:775/ Error 0x80021012 when creating the disk. Releasing image.
08-31 15:46:38.974 F /vdisk:87191:775/ Couldn't create image: /Users/legal/disk.hdd/disk.hdd.0.{5fbaabe3-6958-40ff-92a7-860e329aab41}.hds error 0x80021012
08-31 15:46:38.975 F /vdisk:87191:775/ Callback caught error 0x80021016
08-31 15:46:38.975 F /vdisk:87191:775/ Error creating disk /Users/legal/disk.hdd.copy [0x80021016]
08-31 15:46:38.975 I /imagetool:87191:775/ IL000005.04 -2147348458
08-31 15:46:38.975 F /prl_disk_tool:87191:775/ Failed to convert image, error 0x80021016
08-31 15:46:38.976 I /imagetool:87191:775/ IL000011.00
08-31 15:46:38.978 F /imagetool:87191:775/ Unable to remove directory '/Users/legal/disk.hdd.copy'
08-31 15:46:38.978 I /imagetool:87191:775/ IL000011.01

legal90 · 2017-08-31T14:13:38Z

Parallels Report ID: 208911121

romankulikov · 2017-08-31T16:18:53Z

No insights from me for now. Parallels Desktop 13 is broken here :-( So suggest not to update to it at the moment.

thenoid · 2017-09-11T17:10:36Z

Still broken with the latest update (Version 13.0.1 (42947)) fwiw.

(default) DBG | executing: /usr/local/bin/prl_disk_tool convert --expanding --hdd /Users/rolsen/.docker/machine/machines/default/disk.hdd
Error creating machine: Error in driver during machine creation: exit status 2
notifying bugsnag: [Error creating machine: Error in driver during machine creation: exit status 2]```

ryansch · 2017-09-11T18:20:54Z

I've opened an enterprise ticket against this issue.

TomKeur · 2017-09-13T18:19:54Z

Are you doing a release? Because the version is still at 1.2.3?

legal90 · 2017-09-13T18:37:08Z

@TomKeur Yes, I'm gonna build & release it today or tommorow.

TomKeur · 2017-09-13T18:38:02Z

Thanks!

legal90 · 2017-09-13T21:49:50Z

Done. v1.3.0 is released:
https://github.com/Parallels/docker-machine-parallels/releases/tag/v1.3.0

legal90 added bug upstream/parallels labels Sep 4, 2017

legal90 mentioned this issue Sep 11, 2017

Create a bigger initial disk on Parallels Desktop 13.0 #68

Merged

romankulikov closed this as completed in #68 Sep 13, 2017

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Parallels 13.0 issue #67

Parallels 13.0 issue #67

thenoid commented Aug 29, 2017 •

edited

Loading

rickard-von-essen commented Aug 29, 2017

thenoid commented Aug 29, 2017

ryansch commented Aug 30, 2017 •

edited

Loading

rickard-von-essen commented Aug 30, 2017

TomKeur commented Aug 30, 2017 •

edited

Loading

rickard-von-essen commented Aug 31, 2017

bradbirnbaum commented Aug 31, 2017

legal90 commented Aug 31, 2017 •

edited

Loading

legal90 commented Aug 31, 2017

romankulikov commented Aug 31, 2017

thenoid commented Sep 11, 2017 •

edited

Loading

ryansch commented Sep 11, 2017

TomKeur commented Sep 13, 2017

legal90 commented Sep 13, 2017

TomKeur commented Sep 13, 2017

legal90 commented Sep 13, 2017

Parallels 13.0 issue #67

Parallels 13.0 issue #67

Comments

thenoid commented Aug 29, 2017 • edited Loading

rickard-von-essen commented Aug 29, 2017

thenoid commented Aug 29, 2017

ryansch commented Aug 30, 2017 • edited Loading

rickard-von-essen commented Aug 30, 2017

TomKeur commented Aug 30, 2017 • edited Loading

rickard-von-essen commented Aug 31, 2017

bradbirnbaum commented Aug 31, 2017

legal90 commented Aug 31, 2017 • edited Loading

Reproduction Scenario

Logs

legal90 commented Aug 31, 2017

romankulikov commented Aug 31, 2017

thenoid commented Sep 11, 2017 • edited Loading

ryansch commented Sep 11, 2017

TomKeur commented Sep 13, 2017

legal90 commented Sep 13, 2017

TomKeur commented Sep 13, 2017

legal90 commented Sep 13, 2017

thenoid commented Aug 29, 2017 •

edited

Loading

ryansch commented Aug 30, 2017 •

edited

Loading

TomKeur commented Aug 30, 2017 •

edited

Loading

legal90 commented Aug 31, 2017 •

edited

Loading

thenoid commented Sep 11, 2017 •

edited

Loading