Task 265598

Name 06674624_33_0
Workunit 167515
Created 18 Jun 2018, 8:45:25 UTC
Sent 18 Jun 2018, 8:58:21 UTC
Report deadline 21 Jun 2018, 8:58:21 UTC
Received 18 Jun 2018, 13:11:53 UTC
Server state Over
Outcome Computation error
Client state Compute error
Exit status 125 (0x0000007D) Unknown error code
Computer ID 416
Run time 2 min 48 sec
CPU time 34 sec
Validate state Invalid
Credit 0.00
Device peak FLOPS 1.88 GFLOPS
Application version boinc2docker v1.11 (vbox64_mt)
x86_64-pc-linux-gnu

Stderr output

<core_client_version>7.2.33</core_client_version>
<![CDATA[
<message>
process exited with code 125 (0x7d, -131)
</message>
<stderr_txt>
2018-06-18 08:06:57 (826): vboxwrapper (7.7.26197): starting
2018-06-18 08:06:57 (826): Feature: Checkpoint interval offset (302 seconds)
2018-06-18 08:06:57 (826): Detected: VirtualBox VboxManage Interface (Version: 5.2.12)
2018-06-18 08:06:57 (826): Detected: Minimum checkpoint interval (600.000000 seconds)
2018-06-18 08:06:57 (826): Create VM. (boinc_9cf55d48e0177c78, slot#2)
2018-06-18 08:06:57 (826): Updating drive controller type and model for desired configuration.
2018-06-18 08:07:01 (826): Setting Memory Size for VM. (3072MB)
2018-06-18 08:07:01 (826): Setting CPU Count for VM. (1)
2018-06-18 08:07:02 (826): Setting Chipset Options for VM.
2018-06-18 08:07:02 (826): Setting Boot Options for VM.
2018-06-18 08:07:02 (826): Setting Network Configuration for NAT.
2018-06-18 08:07:02 (826): Enabling VM Network Access.
2018-06-18 08:07:02 (826): Disabling USB Support for VM.
2018-06-18 08:07:03 (826): Disabling COM Port Support for VM.
2018-06-18 08:07:03 (826): Disabling LPT Port Support for VM.
2018-06-18 08:07:03 (826): Disabling Audio Support for VM.
2018-06-18 08:07:03 (826): Disabling Clipboard Support for VM.
2018-06-18 08:07:03 (826): Disabling Drag and Drop Support for VM.
2018-06-18 08:07:03 (826): Adding storage controller(s) to VM.
2018-06-18 08:07:03 (826): Adding virtual ISO 9660 disk drive to VM. (vm_isocontext.iso)
2018-06-18 08:07:04 (826): Adding VirtualBox Guest Additions to VM.
2018-06-18 08:07:04 (826): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2018-06-18 08:07:04 (826): Enabling shared directory for VM.
2018-06-18 08:07:04 (826): Starting VM. (boinc_9cf55d48e0177c78, slot#2)
2018-06-18 08:07:06 (826): Successfully started VM. (PID = '1359')
2018-06-18 08:07:06 (826): Reporting VM Process ID to BOINC.
2018-06-18 08:07:06 (826): Guest Log: BIOS: VirtualBox 5.2.12
2018-06-18 08:07:06 (826): Guest Log: CPUID EDX: 0x078bfbff
2018-06-18 08:07:06 (826): VM state change detected. (old = 'poweroff', new = 'running')
2018-06-18 08:07:06 (826): Preference change detected
2018-06-18 08:07:06 (826): Setting CPU throttle for VM. (100%)
2018-06-18 08:07:07 (826): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2018-06-18 08:07:09 (826): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-06-18 08:07:09 (826): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80
2018-06-18 08:07:09 (826): Guest Log: BIOS: Boot from Hard Disk 0 failed
2018-06-18 08:07:09 (826): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003
2018-06-18 08:07:09 (826): Guest Log: BIOS: Booting from CD-ROM...
2018-06-18 08:07:11 (826): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-06-18 08:07:11 (826): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2018-06-18 08:07:22 (826): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2018-06-18 08:07:22 (826): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-06-18 08:07:24 (826): Guest Log: net.ipv4.ip_forward = 1
2018-06-18 08:07:24 (826): Guest Log: sysctl: cannot stat /proc/sys/net/ipv6/conf/all/forwarding: No such file or directory
2018-06-18 08:07:24 (826): Guest Log: sysctl: setting key "cannot stat %s": No such file or directory
2018-06-18 08:07:24 (826): Guest Log: sysctl: "cannot stat %s" is an unknown key
2018-06-18 08:07:24 (826): Guest Log: sysctl: setting key "cannot stat %s": No such file or directory
2018-06-18 08:07:24 (826): Guest Log: Segmentation fault
2018-06-18 08:07:24 (826): Guest Log: automount ...
2018-06-18 08:07:24 (826): Guest Log: Is the disk unpartitioned?, test for the 'boot2docker format-me' string
2018-06-18 08:07:24 (826): Guest Log: automount over.
2018-06-18 08:07:25 (826): Guest Log: Setting hostname to boot2docker Done.
2018-06-18 08:07:25 (826): Guest Log: Mon Jun 18 08:07:22 UTC 2018 dhcp -------------------------------
2018-06-18 08:07:30 (826): Guest Log: udevadm settle - timeout of 5 seconds reached, the event queue contains:
2018-06-18 08:07:30 (826): Guest Log:   /sys/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:00/PNP0700:00 (1336)
2018-06-18 08:07:32 (826): Guest Log: Mon Jun 18 08:07:28 UTC 2018 dhcp -------------------------------
2018-06-18 08:07:32 (826): Guest Log: VBoxService 5.0.16 r105871 (verbosity: 0) linux.amd64 (Mar  4 2016 17:38:55) release log
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.000540 main     Log opened 2018-06-18T08:07:29.233620000Z
2018-06-18 08:07:32 (826): Guest Log: VBoxService 5.0.16 r105871 (verbosity: 0) linux.amd64 (Mar  4 2016 17:38:55) release log
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.000540 m00:00:00.003714 main     OS Product: Linux
2018-06-18 08:07:32 (826): Guest Log: ain     Log opened 2018-06-18T08:07:29.233620000Z
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.003714 main     OS Product: Linux
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.006813 main     OS Release: 4.1.19-boot2docker
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.006861 main     OS Version: #1 SMP Sun Apr 17 19:40:54 UTC 2016
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.006903 main     Executable: /sbin/VBoxService
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.006904 main     Process ID: 788
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.006905 main     Package type: LINUX_64BITS_GENERIC
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.006813 main     OS Release: 4.1.19-boot2docker
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.006861 main     OS Version: #1 SMP Sun Apr 17 19:40:54 UTC 2016
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.006903 main     Executable: /sbin/VBoxService
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.012654 main     5.0.16 r105871 started. Verbose level = 0
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.006904 main     Process ID: 788
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.006905 main     Package type: LINUX_64BITS_GENERIC
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.012654 main     5.0.16 r105871 started. Verbose level = 0
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.044413 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
2018-06-18 08:07:32 (826): Guest Log: 00:00:00.044540 vminfo   Error: Unable to connect to system D-Bus (1/3): D-Bus not installed
2018-06-18 08:07:32 (826): Guest Log: Mounting BOINC shared/...
2018-06-18 08:07:32 (826): Guest Log: -------------------
2018-06-18 08:07:32 (826): Guest Log: Mon Jun 18 12:07:32 UTC 2018
2018-06-18 08:07:37 (826): Guest Log: 00:00:05.056742 vminfo   Error: Unable to connect to system D-Bus (2/3): D-Bus not installed
2018-06-18 08:07:37 (826): Guest Log: Mon Jun 18 12:07:37 UTC 2018
2018-06-18 08:07:37 (826): Guest Log: 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default 
2018-06-18 08:07:37 (826): Guest Log:     link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
2018-06-18 08:07:37 (826): Guest Log:     inet 127.0.0.1/8 scope host lo
2018-06-18 08:07:37 (826): Guest Log:        valid_lft forever preferred_lft forever
2018-06-18 08:07:37 (826): Guest Log:     inet6 ::1/128 scope host 
2018-06-18 08:07:37 (826): Guest Log:        valid_lft forever preferred_lft forever
2018-06-18 08:07:37 (826): Guest Log: 2: dummy0: <BROADCAST,NOARP> mtu 1500 qdisc noop state DOWN group default 
2018-06-18 08:07:37 (826): Guest Log:     link/ether 9a:fd:9a:27:78:8b brd ff:ff:ff:ff:ff:ff
2018-06-18 08:07:37 (826): Guest Log: 3: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP group default qlen 1000
2018-06-18 08:07:37 (826): Guest Log:     link/ether 08:00:27:19:8d:6f brd ff:ff:ff:ff:ff:ff
2018-06-18 08:07:37 (826): Guest Log:     inet 10.0.2.15/24 brd 10.0.2.255 scope global eth0
2018-06-18 08:07:37 (826): Guest Log:        valid_lft forever preferred_lft forever
2018-06-18 08:07:37 (826): Guest Log:     inet6 fe80::a00:27ff:fe19:8d6f/64 scope link 
2018-06-18 08:07:37 (826): Guest Log:        valid_lft forever preferred_lft forever
2018-06-18 08:07:37 (826): Guest Log: -------------------
2018-06-18 08:07:37 (826): Guest Log: Linking /etc/docker to /var/lib/boot2docker for persistence
2018-06-18 08:07:37 (826): Guest Log: Waiting for Docker daemon to start...
2018-06-18 08:07:40 (826): Guest Log: REPOSITORY          TAG                 IMAGE ID            CREATED             SIZE
2018-06-18 08:07:40 (826): Guest Log: Running boinc_app...
2018-06-18 08:07:40 (826): Guest Log: Importing Docker image from BOINC...
2018-06-18 08:07:40 (826): Guest Log: Filesystem                Size      Used Available Use% Mounted on
2018-06-18 08:07:40 (826): Guest Log: tmpfs                     2.6G    164.2M      2.5G   6% /
2018-06-18 08:07:40 (826): Guest Log: tmpfs                     1.5G         0      1.5G   0% /dev/shm
2018-06-18 08:07:40 (826): Guest Log: cgroup                    1.5G         0      1.5G   0% /sys/fs/cgroup
2018-06-18 08:07:40 (826): Guest Log: shared                   15.6G      8.0G      7.7G  51% /root/shared
2018-06-18 08:07:40 (826): Guest Log: tmpfs                     2.6G    164.2M      2.5G   6% /var/lib/docker/aufs
2018-06-18 08:07:42 (826): Guest Log: 00:00:10.059811 vminfo   Error: Unable to connect to system D-Bus (3/3): D-Bus not installed
2018-06-18 08:07:50 (826): Guest Log: doing docker load...
2018-06-18 08:18:33 (826): Guest Log: Filesystem                Size      Used Available Use% Mounted on
2018-06-18 08:18:33 (826): Guest Log: tmpfs                     2.6G    631.9M      2.0G  23% /
2018-06-18 08:18:33 (826): Guest Log: tmpfs                     1.5G         0      1.5G   0% /dev/shm
2018-06-18 08:18:33 (826): Guest Log: cgroup                    1.5G         0      1.5G   0% /sys/fs/cgroup
2018-06-18 08:18:33 (826): Guest Log: shared                   15.6G      7.8G      7.8G  50% /root/shared
2018-06-18 08:18:33 (826): Guest Log: tmpfs                     2.6G    631.9M      2.0G  23% /var/lib/docker/aufs
2018-06-18 08:18:33 (826): Guest Log:               total        used        free      shared  buff/cache   available
2018-06-18 08:18:33 (826): Guest Log: Mem:           3007          47        2290         631         669        2290
2018-06-18 08:18:33 (826): Guest Log: Swap:           701           0         701
2018-06-18 08:18:33 (826): Guest Log: Building apps directory...
2018-06-18 08:18:33 (826): Guest Log: Prerun diagnostics...
2018-06-18 08:18:33 (826): Guest Log: REPOSITORY          TAG                 IMAGE ID            CREATED             SIZE
2018-06-18 08:18:33 (826): Guest Log: nanohub_apps_base   11                  42db2bf7db3d        6 months ago        458.1 MB
2018-06-18 08:18:33 (826): Guest Log: CONTAINER ID        IMAGE               COMMAND             CREATED             STATUS              PORTS               NAMES
2018-06-18 08:18:33 (826): Guest Log: 467.8M	/var/lib/docker
2018-06-18 08:18:33 (826): Guest Log:               total        used        free      shared  buff/cache   available
2018-06-18 08:18:33 (826): Guest Log: Mem:           3007          36        2299         631         670        2301
2018-06-18 08:18:33 (826): Guest Log: Swap:           701           0         701
2018-06-18 08:18:33 (826): Guest Log: Filesystem                Size      Used Available Use% Mounted on
2018-06-18 08:18:33 (826): Guest Log: tmpfs                     2.6G    631.9M      2.0G  23% /
2018-06-18 08:18:33 (826): Guest Log: tmpfs                     1.5G         0      1.5G   0% /dev/shm
2018-06-18 08:18:33 (826): Guest Log: cgroup                    1.5G         0      1.5G   0% /sys/fs/cgroup
2018-06-18 08:18:33 (826): Guest Log: shared                   15.6G      7.9G      7.7G  50% /root/shared
2018-06-18 08:18:33 (826): Guest Log: tmpfs                     2.6G    631.9M      2.0G  23% /var/lib/docker/aufs
2018-06-18 08:18:33 (826): Guest Log: Running... 
2018-06-18 08:18:33 (826): Guest Log: docker: Error response from daemon: rpc error: code = 2 desc = "containerd: container did not start before the specified timeout".
2018-06-18 08:18:33 (826): Guest Log: boinc_app exited (125)
2018-06-18 08:18:33 (826): Guest Log: -------------------docker.log-------------------
2018-06-18 08:18:33 (826): Guest Log: 1548020d76ea9233b780698907c480a1f2b51efc268b7a847d0a" 
2018-06-18 08:18:33 (826): Guest Log: time="2018-06-18T12:16:23.760594122Z" level=debug msg="Assigning addresses for endpoint prickly_lovelace's interface on network bridge" 
2018-06-18 08:18:33 (826): Guest Log: time="2018-06-18T12:16:23.760620778Z" level=debug msg="RequestAddress(LocalDefault/172.17.0.0/16, <nil>, map[])" 
2018-06-18 08:18:33 (826): Guest Log: time="2018-06-18T12:16:23.776030410Z" level=debug msg="Assigning addresses for endpoint prickly_lovelace's interface on network bridge" 
2018-06-18 08:18:33 (826): Guest Log: time="2018-06-18T12:16:23.779250103Z" level=debug msg="Programming external connectivity on endpoint prickly_lovelace (c7b0552b01f6c344ef2948525688934fa32dcd59421fdbcde9953a413f92a1dd)" 
2018-06-18 08:18:33 (826): Guest Log: time="2018-06-18T12:16:51.870790283Z" level=debug msg="sandbox set key processing took 1.260069615s for container ee3303e26220dc16c9ce68aba85a1e27e4fb30e8112fda63d24e012bb59f522d" 
2018-06-18 08:18:33 (826): Guest Log: time="2018-06-18T12:17:01Z" level=error msg="containerd: start container" error="containerd: container did not start before the specified timeout" id=ee3303e26220dc16c9ce68aba85a1e27e4fb30e8112fda63d24e012bb59f522d 
2018-06-18 08:18:33 (826): Guest Log: time="2018-06-18T12:17:03.869480821Z" level=debug msg="Revoking external connectivity on endpoint prickly_lovelace (c7b0552b01f6c344ef2948525688934fa32dcd59421fdbcde9953a413f92a1dd)" 
2018-06-18 08:18:33 (826): Guest Log: time="2018-06-18T12:17:04.155194084Z" level=debug msg="attach: stdout: end" 
2018-06-18 08:18:33 (826): Guest Log: time="2018-06-18T12:17:04.155299112Z" level=debug msg="attach: stderr: end" 
2018-06-18 08:18:33 (826): Guest Log: time="2018-06-18T12:17:04.378872187Z" level=debug msg="Releasing addresses for endpoint prickly_lovelace's interface on network bridge" 
2018-06-18 08:18:33 (826): Guest Log: time="2018-06-18T12:17:04.378928316Z" level=debug msg="ReleaseAddress(LocalDefault/172.17.0.0/16, 172.17.0.2)" 
2018-06-18 08:18:33 (826): Guest Log: time="2018-06-18T12:17:04.394311402Z" level=error msg="Handler for POST /v1.23/containers/ee3303e26220dc16c9ce68aba85a1e27e4fb30e8112fda63d24e012bb59f522d/start returned error: rpc error: code = 2 desc = \"containerd: container did not start before the specified timeout\"" 
2018-06-18 08:18:33 (826): Guest Log: time="2018-06-18T12:17:04.523447108Z" level=debug msg="Calling DELETE /v1.23/containers/ee3303e26220dc16c9ce68aba85a1e27e4fb30e8112fda63d24e012bb59f522d?v=1" 
2018-06-18 08:18:33 (826): Guest Log: -------------------dmesg-------------------
2018-06-18 08:18:33 (826): Guest Log: er_aux err=-71
2018-06-18 08:18:33 (826): Guest Log: sf_read_super_aux err=-71
2018-06-18 08:18:33 (826): Guest Log: sf_read_super_aux err=-71
2018-06-18 08:18:33 (826): Guest Log: sf_read_super_aux err=-71
2018-06-18 08:18:33 (826): Guest Log: sf_read_super_aux err=-71
2018-06-18 08:18:33 (826): Guest Log: sf_read_super_aux err=-71
2018-06-18 08:18:33 (826): Guest Log: sf_read_super_aux err=-71
2018-06-18 08:18:33 (826): Guest Log: sf_read_super_aux err=-22
2018-06-18 08:18:33 (826): Guest Log: floppy0: no floppy controllers found
2018-06-18 08:18:33 (826): Guest Log: work still pending
2018-06-18 08:18:33 (826): Guest Log: NET: Registered protocol family 10
2018-06-18 08:18:33 (826): Guest Log: 00:00:05.056742 vminfo   Error: Unable to connect to system D-Bus (2/3): D-Bus not installed
2018-06-18 08:18:33 (826): Guest Log: bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
2018-06-18 08:18:33 (826): Guest Log: Bridge firewalling registered
2018-06-18 08:18:33 (826): Guest Log: nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
2018-06-18 08:18:33 (826): Guest Log: IPv6: ADDRCONF(NETDEV_UP): docker0: link is not ready
2018-06-18 08:18:33 (826): Guest Log: random: nonblocking pool is initialized
2018-06-18 08:18:33 (826): Guest Log: 00:00:10.059811 vminfo   Error: Unable to connect to system D-Bus (3/3): D-Bus not installed
2018-06-18 08:18:33 (826): Guest Log: aufs au_opts_verify:1597:docker[994]: dirperm1 breaks the protection by the permission bits on the lower branch
2018-06-18 08:18:33 (826): Guest Log: aufs au_opts_verify:1597:docker[994]: dirperm1 breaks the protection by the permission bits on the lower branch
2018-06-18 08:18:33 (826): Guest Log: aufs au_opts_verify:1597:docker[994]: dirperm1 breaks the protection by the permission bits on the lower branch
2018-06-18 08:18:33 (826): Guest Log: aufs au_opts_verify:1597:docker[898]: dirperm1 breaks the protection by the permission bits on the lower branch
2018-06-18 08:18:33 (826): Guest Log: device veth507319c entered promiscuous mode
2018-06-18 08:18:33 (826): Guest Log: IPv6: ADDRCONF(NETDEV_UP): veth507319c: link is not ready
2018-06-18 08:18:33 (826): Guest Log: cgroup: docker-runc (1073) created nested cgroup for controller "memory" which has incomplete hierarchy support. Nested cgroups may change behavior in the future.
2018-06-18 08:18:33 (826): Guest Log: cgroup: "memory" requires setting use_hierarchy to 1 on the root
2018-06-18 08:18:33 (826): Guest Log: eth0: renamed from veth0025528
2018-06-18 08:18:33 (826): Guest Log: IPv6: ADDRCONF(NETDEV_CHANGE): veth507319c: link becomes ready
2018-06-18 08:18:33 (826): Guest Log: docker0: port 1(veth507319c) entered forwarding state
2018-06-18 08:18:33 (826): Guest Log: docker0: port 1(veth507319c) entered forwarding state
2018-06-18 08:18:33 (826): Guest Log: IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready
2018-06-18 08:18:33 (826): Guest Log: docker0: port 1(veth507319c) entered disabled state
2018-06-18 08:18:33 (826): Guest Log: veth0025528: renamed from eth0
2018-06-18 08:18:33 (826): Guest Log: docker0: port 1(veth507319c) entered disabled state
2018-06-18 08:18:33 (826): Guest Log: device veth507319c left promiscuous mode
2018-06-18 08:18:33 (826): Guest Log: docker0: port 1(veth507319c) entered disabled state
2018-06-18 08:18:33 (826): Guest Log: -------------------
2018-06-18 08:18:33 (826): Guest Log: Saving results...
2018-06-18 08:18:33 (826): Guest Log: tar: *: Cannot stat: No such file or directory
2018-06-18 08:18:33 (826): Guest Log: tar: Exiting with failure status due to previous errors
2018-06-18 08:18:34 (826): VM Completion File Detected.
2018-06-18 08:18:34 (826): Powering off VM.
2018-06-18 08:23:03 (826): Successfully stopped VM.
2018-06-18 08:23:03 (826): Deregistering VM. (boinc_9cf55d48e0177c78, slot#2)
2018-06-18 08:23:04 (826): Removing network bandwidth throttle group from VM.
2018-06-18 08:23:04 (826): Removing storage controller(s) from VM.
2018-06-18 08:23:04 (826): Removing VM from VirtualBox.
2018-06-18 08:23:05 (826): Removing virtual ISO 9660 disk from VirtualBox.
08:23:10 (826): called boinc_finish(125)

</stderr_txt>
]]>


©2024 COPYRIGHT 2017-2018 NCN