Task 517376

Name	07003412_129_4
Workunit	274228
Created	3 Jan 2019, 22:15:01 UTC
Sent	3 Jan 2019, 22:16:01 UTC
Report deadline	5 Jan 2019, 22:16:01 UTC
Received	6 Jan 2019, 2:15:58 UTC
Server state	Over
Outcome	Computation error
Client state	Compute error
Exit status	197 (0x000000C5) EXIT_TIME_LIMIT_EXCEEDED
Computer ID	524
Run time	4 hours 56 min 58 sec
CPU time	4 hours 26 min 49 sec
Validate state	Invalid
Credit	0.00
Device peak FLOPS	3.39 GFLOPS
Application version	boinc2docker v1.12 (vbox64_mt) windows_x86_64
Peak working set size	78.90 MB
Peak swap size	89.05 MB
Peak disk usage	246.92 MB
Stderr output

<core_client_version>7.12.1</core_client_version>
<![CDATA[
<message>
exceeded elapsed time limit 17795.46 (86400.00G/4.52G)</message>
<stderr_txt>
2019-01-05 15:16:20 (4500): vboxwrapper (7.9.26200): starting
2019-01-05 15:16:20 (4500): Feature: Checkpoint interval offset (269 seconds)
2019-01-05 15:16:20 (4500): Detected: VirtualBox COM Interface (Version: 5.1.26)
2019-01-05 15:16:20 (4500): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-01-05 15:16:20 (4500): Create VM. (boinc_14a25b68216e5f5a, slot#5)
2019-01-05 15:16:20 (4500): Updating drive controller type and model for desired configuration.
2019-01-05 15:16:20 (4500): Setting Memory Size for VM. (3072MB)
2019-01-05 15:16:20 (4500): Setting CPU Count for VM. (1)
2019-01-05 15:16:20 (4500): Setting Chipset Options for VM.
2019-01-05 15:16:20 (4500): Setting Boot Options for VM.
2019-01-05 15:16:20 (4500): Enabling VM Network Access.
2019-01-05 15:16:20 (4500): Setting Network Configuration for NAT.
2019-01-05 15:16:20 (4500): Disabling USB Support for VM.
2019-01-05 15:16:20 (4500): Disabling COM Port Support for VM.
2019-01-05 15:16:20 (4500): Disabling LPT Port Support for VM.
2019-01-05 15:16:20 (4500): Disabling Audio Support for VM.
2019-01-05 15:16:20 (4500): Disabling Clipboard Support for VM.
2019-01-05 15:16:20 (4500): Disabling Drag and Drop Support for VM.
2019-01-05 15:16:20 (4500): Adding storage controller(s) to VM.
2019-01-05 15:16:20 (4500): Adding virtual ISO 9660 disk drive to VM. (vm_isocontext.iso)
2019-01-05 15:16:20 (4500): Adding VirtualBox Guest Additions to VM.
2019-01-05 15:16:20 (4500): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-01-05 15:16:20 (4500): Enabling shared directory for VM.
2019-01-05 15:16:20 (4500): Starting VM. (boinc_14a25b68216e5f5a, slot#5)
2019-01-05 15:16:30 (4500): Guest Log: BIOS: VirtualBox 5.1.26

2019-01-05 15:16:30 (4500): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-01-05 15:16:30 (4500): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-01-05 15:16:30 (4500): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-01-05 15:16:30 (4500): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-01-05 15:16:30 (4500): Guest Log: BIOS: Booting from CD-ROM...

2019-01-05 15:16:30 (4500): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-01-05 15:16:30 (4500): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-01-05 15:16:30 (4500): Successfully started VM. (PID = '17440')
2019-01-05 15:16:30 (4500): Reporting VM Process ID to BOINC.
2019-01-05 15:16:35 (4500): VM state change detected. (old = 'poweroff', new = 'running')
2019-01-05 15:16:45 (4500): Guest Log: net.ipv4.ip_forward = 1

2019-01-05 15:16:45 (4500): Guest Log: sysctl: cannot stat /proc/sys/net/ipv6/conf/all/forwarding: No such file or directory

2019-01-05 15:16:45 (4500): Guest Log: sysctl: setting key "cannot stat %s": No such file or directory

2019-01-05 15:16:45 (4500): Guest Log: sysctl: "cannot stat %s" is an unknown key

2019-01-05 15:16:45 (4500): Guest Log: sysctl: setting key "cannot stat %s": No such file or directory

2019-01-05 15:16:45 (4500): Guest Log: Segmentation fault

2019-01-05 15:16:45 (4500): Guest Log: automount ...

2019-01-05 15:16:45 (4500): Guest Log: Is the disk unpartitioned?, test for the 'boot2docker format-me' string

2019-01-05 15:16:45 (4500): Guest Log: automount over.

2019-01-05 15:16:45 (4500): Guest Log: [1;32mSetting hostname to [1;35mboot2docker[0;39m [1;32mDone.[0;39m

2019-01-05 15:16:45 (4500): Guest Log: Sat Jan  5 15:16:41 UTC 2019 dhcp -------------------------------

2019-01-05 15:16:45 (4500): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-01-05 15:16:45 (4500): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-01-05 15:16:45 (4500): Preference change detected
2019-01-05 15:16:45 (4500): Setting CPU throttle for VM. (75%)
2019-01-05 15:16:45 (4500): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-01-05 15:16:50 (4500): Guest Log: udevadm settle - timeout of 5 seconds reached, the event queue contains:

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:00/PNP0700:00 (1072)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00 (1292)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXCPU:00 (1293)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXPWRBN:00 (1294)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 (1295)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXPWRBN:00/input/input0/event0 (1296)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSLPBN:00 (1297)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSLPBN:00/input/input1 (1298)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSLPBN:00/input/input1/event1 (1299)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00 (1300)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00 (1301)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/ACPI0003:00 (1302)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/ACPI0003:00/power_supply/AC (1303)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/LNXVIDEO:00 (1304)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/LNXVIDEO:00/device:01 (1305)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/LNXVIDEO:00/input/input6 (1306)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/LNXVIDEO:00/input/input6/event5 (1307)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/PNP0400:00 (1308)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/PNP0400:01 (1309)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/PNP0501:00 (1310)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/PNP0501:01 (1311)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/PNP0501:02 (1312)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/PNP0501:03 (1313)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/PNP0C02:00 (1314)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/PNP0C0A:00 (1315)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/PNP0C0A:00/power_supply/BAT0 (1316)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/PNP8390:00 (1317)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:00 (1318)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:00/APP0001:00 (1319)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:00/PNP0000:00 (1320)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:00/PNP0100:00 (1321)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:00/PNP0103:00 (1322)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:00/PNP0200:00 (1323)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:00/PNP0303:00 (1324)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:00/PNP0700:00 (1325)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:00/PNP0B00:00 (1326)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:00/PNP0F03:00 (1327)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:02 (1328)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0F:00 (1329)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0F:01 (1330)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0F:02 (1331)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0F:03 (1332)

2019-01-05 15:16:50 (4500): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:01 (1333)

2019-01-05 15:16:50 (4500): Guest Log: Sat Jan  5 15:16:47 UTC 2019 dhcp -------------------------------

2019-01-05 15:16:50 (4500): Guest Log: VBoxService 5.0.16 r105871 (verbosity: 0) linux.amd64 (Mar  4 2016 17:38:55) release log

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.000102 main     Log opened 2019-01-05T15:16:48.001473000Z

2019-01-05 15:16:50 (4500): Guest Log: VBoxService 5.0.16 r105871 (verbosity: 0) linux.amd64 (Mar  4 2016 17:38:55) release log

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.000102 main     Log opened 2019-01-05T15:16:48.001473000Z

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.046135 main     OS Product: Linux

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.046201 main     OS Release: 4.1.19-boot2docker

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.046250 main     OS Version: #1 SMP Sun Apr 17 19:40:54 UTC 2016

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.046294 main     Executable: /sbin/VBoxService

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.046296 main     Process ID: 837

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.046297 main     Package type: LINUX_64BITS_GENERIC

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.0461300:00:00.047464 main     5.0.16 r105871 started. Verbose level = 0

2019-01-05 15:16:50 (4500): Guest Log: 5 main     OS Product: Linux

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.046201 main     OS Release: 4.1.19-boot2docker

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.046250 main     OS Version: #1 SMP Sun Apr 17 19:40:54 UTC 2016

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.046294 main     Executable: /sbin/VBoxService

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.046296 main     Process ID: 837

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.061343 vminfo   rtldrNativeLoad: dlopen('libdbus-1.so.3', RTLD_NOW | RTLD_LOCAL) failed: libdbus-1.so.3: cannot open shared object file: No such file or directory

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.061420 vminfo   Error: Unable to connect to system D-Bus (1/3): D-Bus not installed

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.046297 main     Package type: LINUX_64BITS_GENERIC

2019-01-05 15:16:50 (4500): Guest Log: 00:00:00.047464 main     5.0.16 r105871 started. Verbose level = 0

2019-01-05 15:16:50 (4500): Guest Log: Mounting BOINC shared/...

2019-01-05 15:16:50 (4500): Guest Log: -------------------

2019-01-05 15:16:50 (4500): Guest Log: Sat Jan  5 21:16:49 UTC 2019

2019-01-05 15:16:55 (4500): Guest Log: 00:00:05.063305 vminfo   Error: Unable to connect to system D-Bus (2/3): D-Bus not installed

2019-01-05 15:16:55 (4500): Guest Log: Sat Jan  5 21:16:54 UTC 2019

2019-01-05 15:16:55 (4500): Guest Log: 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default 

2019-01-05 15:16:55 (4500): Guest Log:     link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00

2019-01-05 15:16:55 (4500): Guest Log:     inet 127.0.0.1/8 scope host lo

2019-01-05 15:16:55 (4500): Guest Log:        valid_lft forever preferred_lft forever

2019-01-05 15:16:55 (4500): Guest Log: 2: dummy0: <BROADCAST,NOARP> mtu 1500 qdisc noop state DOWN group default 

2019-01-05 15:16:55 (4500): Guest Log:     link/ether be:23:9d:73:fd:ce brd ff:ff:ff:ff:ff:ff

2019-01-05 15:16:55 (4500): Guest Log: 3: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP group default qlen 1000

2019-01-05 15:16:55 (4500): Guest Log:     link/ether 08:00:27:00:a3:c1 brd ff:ff:ff:ff:ff:ff

2019-01-05 15:16:55 (4500): Guest Log:     inet 10.0.2.15/24 brd 10.0.2.255 scope global eth0

2019-01-05 15:16:55 (4500): Guest Log:        valid_lft forever preferred_lft forever

2019-01-05 15:16:55 (4500): Guest Log: -------------------

2019-01-05 15:16:55 (4500): Guest Log: Linking /etc/docker to /var/lib/boot2docker for persistence

2019-01-05 15:16:55 (4500): Guest Log: Waiting for Docker daemon to start...

2019-01-05 15:17:00 (4500): Guest Log: REPOSITORY          TAG                 IMAGE ID            CREATED             SIZE

2019-01-05 15:17:00 (4500): Guest Log: Running boinc_app...

2019-01-05 15:17:00 (4500): Guest Log: Importing Docker image from BOINC...

2019-01-05 15:17:00 (4500): Guest Log: Filesystem                Size      Used Available Use% Mounted on

2019-01-05 15:17:00 (4500): Guest Log: tmpfs                     2.6G    164.1M      2.5G   6% /

2019-01-05 15:17:00 (4500): Guest Log: tmpfs                     1.5G         0      1.5G   0% /dev/shm

2019-01-05 15:17:00 (4500): Guest Log: cgroup                    1.5G         0      1.5G   0% /sys/fs/cgroup

2019-01-05 15:17:00 (4500): Guest Log: shared                  255.6G     86.8G    168.8G  34% /root/shared

2019-01-05 15:17:00 (4500): Guest Log: tmpfs                     2.6G    164.1M      2.5G   6% /var/lib/docker/aufs

2019-01-05 15:17:00 (4500): Guest Log: 00:00:10.064309 vminfo   Error: Unable to connect to system D-Bus (3/3): D-Bus not installed

2019-01-05 15:17:00 (4500): VM state change detected. (old = 'running', new = 'paused')
2019-01-05 15:17:10 (4500): VM state change detected. (old = 'paused', new = 'running')
2019-01-05 15:17:20 (4500): Guest Log: doing docker load...

2019-01-05 15:17:40 (4500): Guest Log: Filesystem                Size      Used Available Use% Mounted on

2019-01-05 15:17:40 (4500): Guest Log: tmpfs                     2.6G    631.9M      2.0G  23% /

2019-01-05 15:17:40 (4500): Guest Log: tmpfs                     1.5G         0      1.5G   0% /dev/shm

2019-01-05 15:17:40 (4500): Guest Log: cgroup                    1.5G         0      1.5G   0% /sys/fs/cgroup

2019-01-05 15:17:40 (4500): Guest Log: shared                  255.6G     86.6G    168.9G  34% /root/shared

2019-01-05 15:17:40 (4500): Guest Log: tmpfs                     2.6G    631.9M      2.0G  23% /var/lib/docker/aufs

2019-01-05 15:17:40 (4500): Guest Log:               total        used        free      shared  buff/cache   available

2019-01-05 15:17:40 (4500): Guest Log: Mem:           3007          45        2292         631         669        2293

2019-01-05 15:17:40 (4500): Guest Log: Swap:           702           0         702

2019-01-05 15:17:40 (4500): Guest Log: Building apps directory...

2019-01-05 15:17:55 (4500): Guest Log: Prerun diagnostics...

2019-01-05 15:17:55 (4500): Guest Log: REPOSITORY          TAG                 IMAGE ID            CREATED             SIZE

2019-01-05 15:17:55 (4500): Guest Log: nanohub_apps_base   11                  42db2bf7db3d        13 months ago       458.1 MB

2019-01-05 15:17:55 (4500): Guest Log: CONTAINER ID        IMAGE               COMMAND             CREATED             STATUS              PORTS               NAMES

2019-01-05 15:17:55 (4500): Guest Log: 467.8M	/var/lib/docker

2019-01-05 15:17:55 (4500): Guest Log:               total        used        free      shared  buff/cache   available

2019-01-05 15:17:55 (4500): Guest Log: Mem:           3007          46        2290         631         670        2291

2019-01-05 15:17:55 (4500): Guest Log: Swap:           702           0         702

2019-01-05 15:17:55 (4500): Guest Log: Filesystem                Size      Used Available Use% Mounted on

2019-01-05 15:17:55 (4500): Guest Log: tmpfs                     2.6G    631.9M      2.0G  23% /

2019-01-05 15:17:55 (4500): Guest Log: tmpfs                     1.5G         0      1.5G   0% /dev/shm

2019-01-05 15:17:55 (4500): Guest Log: cgroup                    1.5G         0      1.5G   0% /sys/fs/cgroup

2019-01-05 15:17:55 (4500): Guest Log: shared                  255.6G     86.7G    168.9G  34% /root/shared

2019-01-05 15:17:55 (4500): Guest Log: tmpfs                     2.6G    631.9M      2.0G  23% /var/lib/docker/aufs

2019-01-05 15:17:55 (4500): Guest Log: Running... 

2019-01-05 15:17:55 (4500): Guest Log: 07003412_129.boinc

2019-01-05 15:17:55 (4500): Guest Log: 07003412_129.sh

2019-01-05 16:22:20 (4500): VM state change detected. (old = 'running', new = 'paused')
2019-01-05 16:22:30 (4500): VM state change detected. (old = 'paused', new = 'running')
2019-01-05 16:57:18 (4500): Status Report: Elapsed Time: '6003.447897'
2019-01-05 16:57:18 (4500): Status Report: CPU Time: '5396.468750'
2019-01-05 17:01:14 (4500): VM state change detected. (old = 'running', new = 'paused')
2019-01-05 17:01:24 (4500): VM state change detected. (old = 'paused', new = 'running')
2019-01-05 17:04:14 (4500): VM state change detected. (old = 'running', new = 'paused')
2019-01-05 17:04:24 (4500): VM state change detected. (old = 'paused', new = 'running')
2019-01-05 17:14:15 (4500): VM state change detected. (old = 'running', new = 'paused')
2019-01-05 17:14:25 (4500): VM state change detected. (old = 'paused', new = 'running')
2019-01-05 17:45:57 (4500): VM state change detected. (old = 'running', new = 'paused')
2019-01-05 17:46:07 (4500): VM state change detected. (old = 'paused', new = 'running')
2019-01-05 18:08:09 (4500): VM state change detected. (old = 'running', new = 'paused')
2019-01-05 18:08:19 (4500): VM state change detected. (old = 'paused', new = 'running')
2019-01-05 18:11:54 (4500): VM state change detected. (old = 'running', new = 'paused')
2019-01-05 18:11:59 (4500): VM state change detected. (old = 'paused', new = 'running')
2019-01-05 18:38:46 (4500): Status Report: Elapsed Time: '12005.995493'
2019-01-05 18:38:46 (4500): Status Report: CPU Time: '10846.625000'
2019-01-05 18:51:07 (4500): VM state change detected. (old = 'running', new = 'paused')
2019-01-05 18:51:17 (4500): VM state change detected. (old = 'paused', new = 'running')
2019-01-05 19:46:02 (4500): VM state change detected. (old = 'running', new = 'paused')
2019-01-05 19:46:12 (4500): VM state change detected. (old = 'paused', new = 'running')
2019-01-05 19:50:52 (4500): VM state change detected. (old = 'running', new = 'paused')
2019-01-05 19:51:02 (4500): VM state change detected. (old = 'paused', new = 'running')
2019-01-05 20:14:49 (4500): Powering off VM.
2019-01-05 20:14:51 (4500): Successfully stopped VM.
2019-01-05 20:14:56 (4500): Deregistering VM. (boinc_14a25b68216e5f5a, slot#5)
2019-01-05 20:14:56 (4500): Removing virtual disk drive(s) from VM.
2019-01-05 20:14:56 (4500): Removing network bandwidth throttle group from VM.
2019-01-05 20:14:56 (4500): Removing storage controller(s) from VM.
2019-01-05 20:14:56 (4500): Removing VM from VirtualBox.

    Hypervisor System Log:

43:35:59.453165          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The given session is busy}, preserve=false aResultDetail=0
43:39:51.335245          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
43:39:51.350866          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
43:39:51.356719          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
43:39:51.356719          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
43:40:01.921894          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={0169423f-46b4-cde9-91af-1e9d5b6cd945} aComponent={VirtualBoxWrap} aText={Could not find a registered machine named 'boinc_ff9bbd0c3f04ba2c'}, preserve=false aResultDetail=0
43:40:07.242799          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The given session is busy}, preserve=false aResultDetail=0
43:43:48.987479          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
43:43:49.002119          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
43:43:49.008952          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
43:43:49.008952          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
43:44:00.984951          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={0169423f-46b4-cde9-91af-1e9d5b6cd945} aComponent={VirtualBoxWrap} aText={Could not find a registered machine named 'boinc_bbebf45c6a77e8a9'}, preserve=false aResultDetail=0
43:44:06.261206          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The given session is busy}, preserve=false aResultDetail=0
43:48:08.130332          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
43:48:08.144972          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
43:48:08.150828          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
43:48:08.150828          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
43:48:24.830437          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={0169423f-46b4-cde9-91af-1e9d5b6cd945} aComponent={VirtualBoxWrap} aText={Could not find a registered machine named 'boinc_1f4f880b7e9a6ea1'}, preserve=false aResultDetail=0
43:48:30.617133          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The given session is busy}, preserve=false aResultDetail=0
44:01:03.271279 USBPROXY Getting USB descriptor failed with error 31
44:01:03.271279 USBPROXY Getting USB descriptor failed with error 31
44:03:42.499156          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
44:03:42.520638          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
44:03:42.533334          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
44:03:42.533334          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
44:03:56.888047          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={0169423f-46b4-cde9-91af-1e9d5b6cd945} aComponent={VirtualBoxWrap} aText={Could not find a registered machine named 'boinc_6502d5b43681b54c'}, preserve=false aResultDetail=0
44:04:02.550533          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The given session is busy}, preserve=false aResultDetail=0
44:05:53.217486 USBPROXY Getting USB descriptor failed with error 31
44:05:53.217486 USBPROXY Getting USB descriptor failed with error 31
44:16:05.192356 USBPROXY Getting USB descriptor failed with error 31
44:16:05.192356 USBPROXY Getting USB descriptor failed with error 31
44:28:51.860760 USBPROXY Getting USB descriptor failed with error 31
44:28:51.860760 USBPROXY Getting USB descriptor failed with error 31
44:35:55.292164 USBPROXY Getting USB descriptor failed with error 31
44:35:55.292164 USBPROXY Getting USB descriptor failed with error 31
44:58:49.817922 USBPROXY Getting USB descriptor failed with error 31
44:58:49.818898 USBPROXY Getting USB descriptor failed with error 31
45:20:47.989836          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
45:20:48.005452          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
45:20:48.012285          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
45:20:48.012285          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0

    VM Execution Log:


    VM Startup Log:


    VM Trace Log:

20:15:06 (4500): called boinc_finish(194)

</stderr_txt>
]]>