search

hyperhq / hyperd

HyperContainer Daemon
http://www.hypercontainer.io
Apache License 2.0
1.98k stars 194 forks source link

mysql Docker image is failed to launch #23

Closed holidayworking closed 9 years ago

holidayworking commented 9 years ago

I tried to launch mysql Docker image, but this image is failed to launch.

My Podfile is the following.

{
  "name": "mysql",
  "resource": {
    "vcpu": 1,
    "memory": 1024
  },
  "containers": [
    {
      "image": "mysql:5.6",
      "envs":  [{
        "env": "MYSQL_ALLOW_EMPTY_PASSWORD",
        "value": "yes"
      }]
    }
  ]
}

I was operated as follows.

vagrant@vagrant-ubuntu-trusty-64:/vagrant/hyper-rails-example$ sudo hyper create mysql.pod 
Pod ID is pod-uDuCYEwAVG
vagrant@vagrant-ubuntu-trusty-64:/vagrant/hyper-rails-example$ sudo hyper list
         POD ID                      POD Name             VM name    Status
 pod-uDuCYEwAVG                    m14h7ivlpg                       pending
vagrant@vagrant-ubuntu-trusty-64:/vagrant/hyper-rails-example$ sudo hyper start pod-uDuCYEwAVG
Successfully started the Pod(pod-uDuCYEwAVG)

After a few minutes , it became as follows.

vagrant@vagrant-ubuntu-trusty-64:/vagrant/hyper-rails-example$ sudo hyper list
         POD ID                      POD Name             VM name    Status
 pod-uDuCYEwAVG                    m14h7ivlpg                        failed

Log output is as follows.

[HYPER INFO  0703 14:17:59 02868 xen.go] Xen Driver Load failed: failed to initialize xen context
[HYPER INFO  0703 14:17:59 02868 vm.go] Qemu Driver Loaded
[HYPER INFO  0703 14:17:59 02868 hyperd.go] [:50] The config file is 
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:204] The config: kernel=/var/lib/hyper/kernel, initrd=/var/lib/hyper/hyper-initrd.img
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:207] The config: bridge=, ip=
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:210] The config: bios=/var/lib/hyper/bios-qboot.bin, cbfs=/var/lib/hyper/cbfs-qboot.rom
[HYPER INFO  0703 14:17:59 02868 network.go] [:251] bridge exist
[HYPER INFO  0703 14:17:59 02868 iptables.go] [:140] /sbin/iptables, [--wait -t nat -C POSTROUTING -s 192.168.123.1/24 ! -o hyper0 -j MASQUERADE]
[HYPER INFO  0703 14:17:59 02868 iptables.go] [:140] /sbin/iptables, [--wait -N HYPER]
[HYPER INFO  0703 14:17:59 02868 iptables.go] [:140] /sbin/iptables, [--wait -t filter -C FORWARD -o hyper0 -j HYPER]
[HYPER INFO  0703 14:17:59 02868 iptables.go] [:140] /sbin/iptables, [--wait -t filter -C FORWARD -i hyper0 -j ACCEPT]
[HYPER INFO  0703 14:17:59 02868 iptables.go] [:140] /sbin/iptables, [--wait -t filter -C FORWARD -o hyper0 -m conntrack --ctstate RELATED,ESTABLISHED -j ACCEPT]
[HYPER INFO  0703 14:17:59 02868 network.go] [:168] modprobe br_netfilter failed modprobe br_netfilter failed
[HYPER INFO  0703 14:17:59 02868 iptables.go] [:140] /sbin/iptables, [--wait -t nat -N HYPER]
[HYPER INFO  0703 14:17:59 02868 iptables.go] [:140] /sbin/iptables, [--wait -t nat -C OUTPUT -m addrtype --dst-type LOCAL ! -d 127.0.0.1/8 -j HYPER]
[HYPER INFO  0703 14:17:59 02868 iptables.go] [:140] /sbin/iptables, [--wait -t nat -C PREROUTING -m addrtype --dst-type LOCAL -j HYPER]
[HYPER INFO  0703 14:17:59 02868 docker.go] [:174] Finish the client request
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= info
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= version
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= create
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= podRun
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= list
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= podStart
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= vmCreate
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= vmKill
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= exec
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= attach
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= serveapi
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= acceptconnections
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= podRm
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= pull
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= podCreate
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= podInfo
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= podStop
[HYPER INFO  0703 14:17:59 02868 daemon.go] [:107] Engine Register: name= tty
[HYPER INFO  0703 14:17:59 02868 hyperd.go] [:76] Hyper daemon: 0.2 0
[HYPER INFO  0703 14:17:59 02868 job.go] [:78] +job acceptconnections()
[HYPER INFO  0703 14:17:59 02868 job.go] [:84] -job acceptconnections() OK
[HYPER INFO  0703 14:17:59 02868 hyperd.go] [:108] Daemon has completed initialization
[HYPER INFO  0703 14:17:59 02868 job.go] [:78] +job serveapi(unix:///var/run/hyper.sock)
[HYPER INFO  0703 14:17:59 02868 server.go] [:840] Listening for HTTP on unix (/var/run/hyper.sock)
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering GET, /list
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering GET, /info
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering GET, /pod/info
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering GET, /version
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering POST, /container/create
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering POST, /image/create
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering POST, /pod/create
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering POST, /pod/start
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering POST, /pod/remove
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering POST, /pod/stop
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering POST, /vm/create
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering POST, /vm/kill
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering POST, /tty/resize
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering POST, /pod/run
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering POST, /exec
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering POST, /attach
[HYPER INFO  0703 14:17:59 02868 server.go] [:703] Registering OPTIONS, 
[HYPER INFO  0703 14:18:04 02868 server.go] [:604] Calling POST /pod/create
[HYPER INFO  0703 14:18:04 02868 server.go] [:378] Args string is {
  "name": "mysql",
  "resource": {
    "vcpu": 1,
    "memory": 1024
  },
  "containers": [
    {
      "image": "mysql:5.6",
      "envs":  [{
        "env": "MYSQL_ALLOW_EMPTY_PASSWORD",
        "value": "yes"
      }]
    }
  ]
}

[HYPER INFO  0703 14:18:04 02868 job.go] [:78] +job podCreate({
  "name": "mysql",
  "resource": {
    "vcpu": 1,
    "memory": 1024
  },
  "containers": [
    {
      "image": "mysql:5.6",
      "envs":  [{
        "env": "MYSQL_ALLOW_EMPTY_PASSWORD",
        "value": "yes"
      }]
    }
  ]
}
)
[HYPER INFO  0703 14:18:04 02868 pod.go] [:190] leveldb: not found
[HYPER INFO  0703 14:18:04 02868 pod.go] [:198] Process the Containers section in POD SPEC
[HYPER INFO  0703 14:18:04 02868 create.go] [:108] The Repository is mysql, and the tag is 5.6
[HYPER INFO  0703 14:18:04 02868 docker.go] [:174] Finish the client request
[HYPER INFO  0703 14:18:04 02868 create.go] [:110] The returned status code is 201!
[HYPER INFO  0703 14:18:04 02868 job.go] [:84] -job podCreate({
  "name": "mysql",
  "resource": {
    "vcpu": 1,
    "memory": 1024
  },
  "containers": [
    {
      "image": "mysql:5.6",
      "envs":  [{
        "env": "MYSQL_ALLOW_EMPTY_PASSWORD",
        "value": "yes"
      }]
    }
  ]
}
) OK
[HYPER INFO  0703 14:18:11 02868 server.go] [:604] Calling GET /list
[HYPER INFO  0703 14:18:11 02868 server.go] [:179] List type is pod
[HYPER INFO  0703 14:18:11 02868 job.go] [:78] +job list(pod)
[HYPER INFO  0703 14:18:11 02868 job.go] [:84] -job list(pod) OK
[HYPER INFO  0703 14:18:18 02868 server.go] [:604] Calling POST /pod/start
[HYPER INFO  0703 14:18:18 02868 job.go] [:78] +job podStart(pod-uDuCYEwAVG, )
[HYPER INFO  0703 14:18:18 02868 pod.go] [:63] pod:%s, vm:%spod-uDuCYEwAVG
[HYPER INFO  0703 14:18:18 02868 pod.go] [:288] The config: kernel=/var/lib/hyper/kernel, initrd=/var/lib/hyper/hyper-initrd.img
[HYPER INFO  0703 14:18:18 02868 container.go] [:62] ready to get the container(1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb) info
[HYPER INFO  0703 14:18:18 02868 qemu.go] [:171] kvm not exist change to no kvm mode
[HYPER INFO  0703 14:18:18 02868 qemu_process.go] [:176] cmdline arguments: -machine pc-i440fx-2.0,usb=off -cpu core2duo -drive if=pflash,file=/var/lib/hyper/bios-qboot.bin,readonly=on -drive if=pflash,file=/var/lib/hyper/cbfs-qboot.rom,readonly=on -realtime mlock=off -no-user-config -nodefaults -no-hpet -rtc base=utc,driftfix=slew -no-reboot -display none -boot strict=on -m 1024 -smp 1 -qmp unix:/var/run/hyper/vm-NFoFbPQjrt/qmp.sock,server,nowait -serial unix:/var/run/hyper/vm-NFoFbPQjrt/console.sock,server,nowait -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x2 -device virtio-scsi-pci,id=scsi0,bus=pci.0,addr=0x3 -chardev socket,id=charch0,path=/var/run/hyper/vm-NFoFbPQjrt/hyper.sock,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charch0,id=channel0,name=sh.hyper.channel.0 -chardev socket,id=charch1,path=/var/run/hyper/vm-NFoFbPQjrt/tty.sock,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charch1,id=channel1,name=sh.hyper.channel.1 -fsdev local,id=virtio9p,path=/var/run/hyper/vm-NFoFbPQjrt/share_dir,security_model=none -device virtio-9p-pci,fsdev=virtio9p,mount_tag=share_dir
[HYPER INFO  0703 14:18:18 02868 docker.go] [:174] Finish the client request
[HYPER INFO  0703 14:18:18 02868 qemu_process.go] [:107] qemu daemon pid 2902.
[HYPER INFO  0703 14:18:18 02868 qemu_process.go] [:59] I am the parent, exit, ps: child 2902
[HYPER INFO  0703 14:18:18 02868 qemu_process.go] [:206] starting daemon with pid: 2902
[HYPER INFO  0703 14:18:18 02868 pod.go] [:480] Parsing envs for container 0: 4 Evs
[HYPER INFO  0703 14:18:18 02868 pod.go] [:481] The fs type is dir
[HYPER INFO  0703 14:18:18 02868 pod.go] [:482] WorkingDir is 
[HYPER INFO  0703 14:18:18 02868 pod.go] [:483] Image is /1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/rootfs
[HYPER INFO  0703 14:18:18 02868 pod.go] [:494] Container Info is 
&{1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb  /1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/rootfs dir  [/entrypoint.sh] [mysqld] map[MYSQL_MAJOR:5.6 MYSQL_VERSION:5.6.25 MYSQL_ALLOW_EMPTY_PASSWORD:yes PATH:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin]}
[HYPER INFO  0703 14:18:18 02868 pod.go] [:496] container 0 created 1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb, workdir , env: map[MYSQL_VERSION:5.6.25 MYSQL_ALLOW_EMPTY_PASSWORD:yes PATH:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin MYSQL_MAJOR:5.6]
POD id is pod-uDuCYEwAVG
[HYPER INFO  0703 14:18:18 02868 qemu_process.go] [:90] collect child 2901
[HYPER INFO  0703 14:18:18 02868 hypervisor.go] [:19] main event loop got message 20(COMMAND_RUN_POD)
[HYPER INFO  0703 14:18:18 02868 vm_states.go] [:315] got spec, prepare devices
[HYPER INFO  0703 14:18:18 02868 context.go] [:265] #0 Container Info:
[HYPER INFO  0703 14:18:18 02868 context.go] [:268] 
{
...|    "Id": "1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb",
...|    "Rootfs": "",
...|    "Image": "/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/rootfs",
...|    "Fstype": "dir",
...|    "Workdir": "",
...|    "Entrypoint": [
...|        "/entrypoint.sh"
...|    ],
...|    "Cmd": [
...|        "mysqld"
...|    ],
...|    "Envs": {
...|        "MYSQL_ALLOW_EMPTY_PASSWORD": "yes",
...|        "MYSQL_MAJOR": "5.6",
...|        "MYSQL_VERSION": "5.6.25",
...|        "PATH": "/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin"
...|    }
...|}
[HYPER INFO  0703 14:18:18 02868 vm_states.go] [:60] initial vm spec: {
        "hostname": "guihvtsh5d",
        "containers": [
            {
                "id": "1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb",
                "rootfs": "",
                "image": "/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/rootfs",
                "workdir": "/",
                "cmd": [
                    "/entrypoint.sh",
                    "mysqld"
                ],
                "envs": [
                    {
                        "env": "MYSQL_ALLOW_EMPTY_PASSWORD",
                        "value": "yes"
                    },
                    {
                        "env": "PATH",
                        "value": "/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin"
                    },
                    {
                        "env": "MYSQL_MAJOR",
                        "value": "5.6"
                    },
                    {
                        "env": "MYSQL_VERSION",
                        "value": "5.6.25"
                    }
                ],
                "restartPolicy": "never"
            }
        ],
        "interfaces": null,
        "routes": null,
        "shareDir": "share_dir"
    }
[HYPER INFO  0703 14:18:18 02868 context.go] [:239] VM vm-NFoFbPQjrt: state change from  to 'STARTING'
[HYPER INFO  0703 14:18:18 02868 hypervisor.go] [:19] main event loop got message 12(EVENT_INTERFACE_ADD)
[HYPER INFO  0703 14:18:18 02868 qmp_wrapper.go] [:74] send net to qemu at 16
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:373] got new session during initializing
[HYPER INFO  0703 14:18:18 02868 init_comm.go] [:110] Wating for init messages...
[HYPER INFO  0703 14:18:18 02868 init_comm.go] [:73] trying to read 8 bytes
[HYPER INFO  0703 14:18:18 02868 tty.go] [:126] tty socket connected
[HYPER INFO  0703 14:18:18 02868 tty.go] [:69] tty: trying to read 12 bytes
[HYPER INFO  0703 14:18:18 02868 init_comm.go] [:30] connected to /var/run/hyper/vm-NFoFbPQjrt/console.sock
[HYPER INFO  0703 14:18:18 02868 init_comm.go] [:37] connected /var/run/hyper/vm-NFoFbPQjrt/console.sock as telnet mode.
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:166] connected to /var/run/hyper/vm-NFoFbPQjrt/qmp.sock
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:176] begin qmp init...
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:185] got qmp welcome, now sending command qmp_capabilities
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:200] waiting for response
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:101] got a message {"return": {}}
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:209] got for response
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:212] QMP connection initialized
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:349] QMP initialzed, go into main QMP loop
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:135] Begin receive QMP message
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:224] Begin process command session
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:237] send cmd with scm (24 bytes) (1) {"execute":"getfd","arguments":{"fdname":"fdeth0"}}
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:101] got a message {"return": {}}
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:242] sending command (1) {"execute":"netdev_add","arguments":{"fd":"fdeth0","id":"eth0","type":"tap"}}
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:101] got a message {"return": {}}
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:242] sending command (1) {"execute":"device_add","arguments":{"addr":"0x5","bus":"pci.0","driver":"virtio-net-pci","id":"eth0","mac":"52:54:b9:7e:ef:f1","netdev":"eth0"}}
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:101] got a message {"return": {}}
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:301] session finished, buffer size 1
[HYPER INFO  0703 14:18:18 02868 qmp_handler.go] [:304] success 
[HYPER INFO  0703 14:18:18 02868 hypervisor.go] [:19] main event loop got message 14(EVENT_INTERFACE_INSERTED)
[HYPER INFO  0703 14:18:18 02868 vm_states.go] [:331] device ready, could run pod.
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Initializing cgroup subsys cpu
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Linux version 4.0.0-rc5+ (root@thinkdonkey) (gcc version 4.9.2 20150212 (Red Hat 4.9.2-6) (GCC) ) #15 SMP Sat Apr 11 13:03:47 CST 2015
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Command line: console=ttyS0,panic=1
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] 
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] e820: BIOS-provided physical RAM map:
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] BIOS-e820: [mem 0x00000000000d0000-0x00000000000effff] ACPI NVS
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] BIOS-e820: [mem 0x0000000000100000-0x000000003fffffff] usable
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] NX (Execute Disable) protection: active
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] DMI not present or invalid.
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] e820: last_pfn = 0x40000 max_arch_pfn = 0x400000000
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] PAT configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- UC  
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] CPU MTRRs all blank - virtualized system.
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] init_memory_mapping: [mem 0x00000000-0x000fffff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] init_memory_mapping: [mem 0x3fc00000-0x3fdfffff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] init_memory_mapping: [mem 0x20000000-0x3fbfffff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] init_memory_mapping: [mem 0x00100000-0x1fffffff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] init_memory_mapping: [mem 0x3fe00000-0x3fffffff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] RAMDISK: [mem 0x3ff09000-0x3fffffff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: Early table checksum verification disabled
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: RSDP 0x00000000000F2AE0 000014 (v00 BOCHS )
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: RSDT 0x00000000000EFB95 000034 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: FACP 0x00000000000EF1C0 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: DSDT 0x00000000000EE040 001180 (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: FACS 0x00000000000EE000 000040
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: SSDT 0x00000000000EF234 0008E9 (v01 BOCHS  BXPCSSDT 00000001 BXPC 00000001)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: APIC 0x00000000000EFB1D 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: RSDT 0x00000000000EFB95 000034 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] No NUMA configuration found
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Faking a node at [mem 0x0000000000000000-0x000000003fffffff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] NODE_DATA(0) allocated [mem 0x3fef5000-0x3ff08fff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Zone ranges:
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console]   DMA32    [mem 0x0000000001000000-0x000000003fffffff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console]   Normal   empty
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Movable zone start for each node
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Early memory node ranges
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console]   node   0: [mem 0x0000000000100000-0x000000003fffffff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Initmem setup node 0 [mem 0x0000000000001000-0x000000003fffffff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: PM-Timer IO Port: 0x608
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0])
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Using ACPI (MADT) for SMP configuration information
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] e820: [mem 0x40000000-0xffffffff] available for PCI devices
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Booting paravirtualized kernel on bare hardware
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:1 nr_node_ids:1
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] PERCPU: Embedded 28 pages/cpu @ffff88003fc00000 s77144 r8192 d29352 u2097152
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 258441
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Policy zone: DMA32
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Kernel command line: console=ttyS0,panic=1
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] 
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] PID hash table entries: 4096 (order: 3, 32768 bytes)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Memory: 1025724K/1048184K available (3566K kernel code, 437K rwdata, 1160K rodata, 892K init, 680K bss, 22460K reserved, 0K cma-reserved)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Hierarchical RCU implementation.
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console]     RCU dyntick-idle grace-period acceleration is enabled.
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console]     RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=1.
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] NR_IRQS:4352 nr_irqs:256 16
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console]     Offload RCU callbacks from all CPUs
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console]     Offload RCU callbacks from CPUs: 0.
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Console: colour *CGA 80x25
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] console [ttyS0] enabled
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] tsc: Fast TSC calibration failed
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] tsc: Unable to calibrate against PIT
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] tsc: using PMTIMER reference calibration
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] tsc: Detected 2202.296 MHz processor
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Calibrating delay loop (skipped), value calculated using timer frequency.. 4404.59 BogoMIPS (lpj=2202296)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pid_max: default: 32768 minimum: 301
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: Core revision 20150204
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: All ACPI Tables successfully acquired
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Freeing SMP alternatives memory: 16K (ffffffff815ed000 - ffffffff815f1000)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] APIC calibration not consistent with PM-Timer: 104ms instead of 100ms
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] APIC delta adjusted to PM-Timer: 6250395 (6522340)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] smpboot: CPU0: Intel(R) Core(TM)2 Duo CPU     T7700  @ 2.40GHz (fam: 06, model: 0f, stepping: 0b)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Performance Events: unsupported p6 CPU model 15 no PMU driver, software events only.
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] x86: Booted up 1 node, 1 CPUs
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] smpboot: Total of 1 processors activated (4404.59 BogoMIPS)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] devtmpfs: initialized
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] NET: Registered protocol family 16
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: bus type PCI registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] PCI: Using configuration type 1 for base access
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: Added _OSI(Module Device)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: Added _OSI(Processor Device)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: Added _OSI(3.0 _SCP Extensions)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: Added _OSI(Processor Aggregator Device)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: Interpreter enabled
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: (supports S0 S5)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: Using IOAPIC for interrupt routing
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [2] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [3] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [4] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [5] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [6] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [7] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [8] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [9] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [10] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [11] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [12] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [13] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [14] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [15] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [16] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [17] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [18] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [19] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [20] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [21] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [22] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [23] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [24] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [25] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [26] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [27] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [28] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [29] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [30] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] acpiphp: Slot [31] registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] PCI host bridge to bus 0000:00
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci_bus 0000:00: root bus resource [bus 00-ff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci_bus 0000:00: root bus resource [io  0x0d00-0xadff window]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci_bus 0000:00: root bus resource [io  0xae0f-0xaeff window]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci_bus 0000:00: root bus resource [io  0xaf20-0xafdf window]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci_bus 0000:00: root bus resource [io  0xafe4-0xffff window]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] ACPI: Enabled 16 GPEs in block 00 to 0F
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] vgaarb: loaded
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] SCSI subsystem initialized
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] PCI: Using ACPI for IRQ routing
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Switched to clocksource refined-jiffies
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pnp: PnP ACPI init
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pnp: PnP ACPI: found 5 devices
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Switched to clocksource acpi_pm
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:05.0: BAR 6: assigned [mem 0x40000000-0x4003ffff pref]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:02.0: BAR 1: assigned [mem 0x40040000-0x40040fff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:03.0: BAR 1: assigned [mem 0x40041000-0x40041fff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:04.0: BAR 1: assigned [mem 0x40042000-0x40042fff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:05.0: BAR 1: assigned [mem 0x40043000-0x40043fff]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:03.0: BAR 0: assigned [io  0x1000-0x103f]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:04.0: BAR 0: assigned [io  0x1040-0x107f]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:02.0: BAR 0: assigned [io  0x1080-0x109f]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:05.0: BAR 0: assigned [io  0x10a0-0x10bf]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:01.1: BAR 4: assigned [io  0x10c0-0x10cf]
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] NET: Registered protocol family 2
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] TCP: Hash tables configured (established 8192 bind 8192)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] TCP: reno registered
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] UDP hash table entries: 512 (order: 2, 16384 bytes)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] NET: Registered protocol family 1
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Trying to unpack rootfs image as initramfs...
[HYPER INFO  0703 14:18:19 02868 init_comm.go] [:45] [console] Freeing initrd memory: 988K (ffff88003ff09000 - ffff880040000000)
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] futex hash table entries: 256 (order: 2, 16384 bytes)
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 9p: Installing v9fs 9p2000 file system support
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] alg: No test for stdrng (krng)
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 254)
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] io scheduler noop registered
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] io scheduler cfq registered (default)
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 10
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] virtio-pci 0000:00:02.0: virtio_pci: leaving for legacy driver
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] virtio-pci 0000:00:04.0: virtio_pci: leaving for legacy driver
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] brd: module loaded
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] loop: module loaded
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] scsi host0: Virtio SCSI HBA
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] serio: i8042 KBD port at 0x60,0x64 irq 1
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] serio: i8042 AUX port at 0x60,0x64 irq 12
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] device-mapper: uevent: version 1.0.3
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] device-mapper: ioctl: 4.30.0-ioctl (2014-12-22) initialised: dm-devel@redhat.com
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] TCP: cubic registered
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] NET: Registered protocol family 17
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 9pnet: Installing 9P2000 support
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] registered taskstats version 1
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] Freeing unused kernel memory: 892K (ffffffff8150e000 - ffffffff815ed000)
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /dev
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /sys
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /proc
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] uptime 0.73 0.14
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /dev/pts
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /dev
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] open hyper channel /dev/vport0p1
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] send ready message
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper send type 8, len 0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:83] read 8/8 [length = 0]
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:87] data length is 8
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:120] Get init ready message
[HYPER INFO  0703 14:18:20 02868 hypervisor.go] [:19] main event loop got message 5(EVENT_INIT_CONNECTED)
[HYPER INFO  0703 14:18:20 02868 vm_states.go] [:345] begin to wait vm commands
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:227] send command 1 to init, payload: '{"hostname":"guihvtsh5d","containers":[{"id":"1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb","rootfs":"","image":"/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/rootfs","workdir":"/","cmd":["/entrypoint.sh","mysqld"],"envs":[{"env":"MYSQL_ALLOW_EMPTY_PASSWORD","value":"yes"},{"env":"PATH","value":"/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin"},{"env":"MYSQL_MAJOR","value":"5.6"},{"env":"MYSQL_VERSION","value":"5.6.25"}],"restartPolicy":"never"}],"interfaces":[{"device":"eth0","ipAddress":"192.168.123.2","netMask":"255.255.255.0"}],"routes":[{"dest":"0.0.0.0/0","gateway":"192.168.123.1","device":"eth0"}],"shareDir":"share_dir"}'.
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:232] message sent, set pong timer
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:73] trying to read 8 bytes
[HYPER INFO  0703 14:18:20 02868 pod.go] [:658] Get the response from QEMU, VM id is vm-NFoFbPQjrt!
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] channel sh.hyper.channel.1, directory sh.hyper.channel.0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] open hyper channel /dev/vport0p2
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_init_event hyper channel event 0x60e518, ops 0x60e300, fd 4
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_add_event add event fd 4, 0x60e300
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_init_event hyper ttyfd event 0x60e4f0, ops 0x60e2e0, fd 5
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_add_event add event fd 5, 0x60e2e0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_init_event hyper signal event 0x60e4c8, ops 0x60e2c0, fd 3
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_add_event add event fd 3, 0x60e2c0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_loop epoll_wait 1
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_event_read
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] read 696 bytes data, total data 0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] get length 696
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 0 0 0 1 0 0 2 b8 7b 22 68 6f 73 74 6e 61 6d 65 22 3a 22 67 75 69 68 76 74 73 68 35 64 22 2c 22 63 6f 6e 74 61 69 6e 65 72 73 22 3a 5b 7b 22 69 64 22 3a 22 31 61 36 39 63 37 64 62 61 36 65 38 33 33 34 61 32 65 63 31 65 31 33 38 61 33 61 31 64 34 30 64 35 34 33 37 64 30 34 37 65 64 65 66 35 37 30 65 32 63 66 37 61 31 31 34 64 62 63 30 38 65 62 62 22 2c 22 72 6f 6f 74 66 73 22 3a 22 22 2c 22 69 6d 61 67 65 22 3a 22 2f 31 61 36 39 63 37 64 62 61 36 65 38 33 33 34 61 32 65 63 31 65 31 33 38 61 33 61 31 64 34 30 64 35 34 33 37 64 30 34 37 65 64 65 66 35 37 30 65 32 63 66 37 61 31 31 34 64 62 63 30 38 65 62 62 2f 72 6f 6f 74 66 73 22 2c 22 77 6f 72 6b 64 69 72 22 3a 22 2f 22 2c 22 63 6d 64 22 3a 5b 22 2f 65 6e 74 72 79 70 6f 69 6e 74 2e 73 68 22 2c 22 6d 79 73 71 6c 64 22 5d 2c 22 65 6e 76 73 22 3a 5b 7b 22 65 6e 76 22 3a 22 4d 59 53 51 4c 5f 41 4c 4c 4f 57 5f 45 4d 50 54 59 5f 50 41 53 53 57 4f 52 44 22 2c 22 76 61 6c 75 65 22 3a 22 79 65 73 22 7d 2c 7b 22 65 6e 76 22 3a 22 50 41 54 48 22 2c 22 76 61 6c 75 65 22 3a 22 2f 75 73 72 2f 6c 6f 63 61 6c 2f 73 62 69 6e 3a 2f 75 73 72 2f 6c 6f 63 61 6c 2f 62 69 6e 3a 2f 75 73 72 2f 73 62 69 6e 3a 2f 75 73 72 2f 62 69 6e 3a 2f 73 62 69 6e 3a 2f 62 69 6e 22 7d 2c 7b 22 65 6e 76 22 3a 22 4d 59 53 51 4c 5f 4d 41 4a 4f 52 22 2c 22 76 61 6c 75 65 22 3a 22 35 2e 36 22 7d 2c 7b 22 65 6e 76 22 3a 22 4d 59 53 51 4c 5f 56 45 52 53 49 4f 4e 22 2c 22 76 61 6c 75 65 22 3a 22 35 2e 36 2e 32 35 22 7d 5d 2c 22 72 65 73 74 61 72 74 50 6f 6c 69 63 79 22 3a 22 6e 65 76 65 72 22 7d 5d 2c 22 69 6e 74 65 72 66 61 63 65 73 22 3a 5b 7b 22 64 65 76 69 63 65 22 3a 22 65 74 68 30 22 2c 22 69 70 41 64 64 72 65 73 73 22 3a 22 31 39 32 2e 31 36 38 2e 31 32 33 2e 32 22 2c 22 6e 65 74 4d 61 73 6b 22 3a 22 32 35 35 2e 32 35 35 2e 32 35 35 2e 30 22 7d 5d 2c 22 72 6f 75 74 65 73 22 3a 5b 7b 22 64 65 73 74 22 3a 22 30 2e 30 2e 30 2e 30 2f 30 22 2c 22 67 61 74 65 77 61 79 22 3a 22 31 39 32 2e 31 36 38 2e 31 32 33 2e 31 22 2c 22 64 65 76 69 63 65 22 3a 22 65 74 68 30 22 7d 5d 2c 22 73 68 61 72 65 44 69 72 22 3a 22 73 68 61 72 65 5f 64 69 72 22 7d 
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console]  hyper_channel_handle, type 1, len 696
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] call hyper_start_pod, json {"hostname":"guihvtsh5d","containers":[{"id":"1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb","rootfs":"","image":"/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/rootfs","workdir":"/","cmd":["/entrypoint.sh","mysqld"],"envs":[{"env":"MYSQL_ALLOW_EMPTY_PASSWORD","value":"yes"},{"env":"PATH","value":"/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin"},{"env":"MYSQL_MAJOR","value":"5.6"},{"env":"MYSQL_VERSION","value":"5.6.25"}],"restartPolicy":"never"}],"interfaces":[{"device":"eth0","ipAddress":"192.168.123.2","netMask":"255.255.255.0"}],"routes":[{"dest":"0.0.0.0/0","gateway":"192.168.123.1","device":"eth0"}],"shareDir":"share_dir"}, len 688
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] call hyper_start_pod, json {"hostname":"guihvtsh5d","containers":[{"id":"1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb","rootfs":"","image":"/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/rootfs","workdir":"/","cmd":["/entrypoint.sh","mysqld"],"envs":[{"env":"MYSQL_ALLOW_EMPTY_PASSWORD","value":"yes"},{"env":"PATH","value":"/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin"},{"env":"MYSQL_MAJOR","value":"5.6"},{"env":"MYSQL_VERSION","value":"5.6.25"}],"restartPolicy":"never"}],"interfaces":[{"device":"eth0","ipAddress":"192.168.123.2","netMask":"255.255.255.0"}],"routes":[{"dest":"0.0.0.0/0","gateway":"192.168.123.1","device":"eth0"}],"shareDir":"share_dir"}, len 688
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] jsmn parse successed, n is 62
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] token 0, type is 1, size is 5
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] token 1, type is 3, size is 1
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hostname is guihvtsh5d
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] token 3, type is 3, size is 1
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] container count 1
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] next container 7
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 2 name id
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] container id 1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 4 name rootfs
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] container rootfs 
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 6 name image
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] container image /1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/rootfs
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 8 name workdir
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] container workdir /
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 10 name cmd
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] container init arg 0 /entrypoint.sh
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] container init arg 1 mysqld
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 14 name envs
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] envs num 4
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] envs 0 env MYSQL_ALLOW_EMPTY_PASSWORD
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] envs 0 value yes
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] envs 1 env PATH
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] envs 1 value /usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] envs 2 env MYSQL_MAJOR
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] envs 2 value 5.6
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] envs 3 env MYSQL_VERSION
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] envs 3 value 5.6.25
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 36 name restartPolicy
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] restartPolicy never
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] token 42, type is 3, size is 1
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] network interfaces num 1
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] net device is eth0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] net ipaddress is 192.168.123.2
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] net mask is 255.255.255.0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] token 51, type is 3, size is 1
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] network routes num 1
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] route 0 dest is 0.0.0.0/0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] route 0 gateway is 192.168.123.1
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] route 0 device is eth0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] token 60, type is 3, size is 1
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 9p tag is share_dir
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp/hyper/proc
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp/hyper
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] finish rescan
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] net device eth0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] net device sys path is /sys/class/net/eth0/ifindex
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] get ifindex 2
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] interface get netamsk 24 255.255.255.0
[HYPER INFO  0703 14:18:20 02868 qmp_handler.go] [:101] got a message {"timestamp": {"seconds": 1435933100, "microseconds": 426423}, "event": "NIC_RX_FILTER_CHANGED", "data": {"name": "eth0", "path": "/machine/peripheral/eth0/virtio-backend"}}
[HYPER INFO  0703 14:18:20 02868 qmp_handler.go] [:105] got event: NIC_RX_FILTER_CHANGED
[HYPER INFO  0703 14:18:20 02868 qmp_handler.go] [:326] got QMP event NIC_RX_FILTER_CHANGED
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] net device eth0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] net device sys path is /sys/class/net/eth0/ifindex
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] get ifindex 2
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp/hyper/shared
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp/hyper
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/devpts/
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/devpts
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp/hyper
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] pod init pid 35
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_container_init in
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] path /sys/class/scsi_host/host0/scan
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] finish scan scsi
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] container root directory /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] src directory /tmp/hyper/shared//1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/rootfs/
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] root directory for container is /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root//, init task /entrypoint.sh
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] list /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get ..
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get .
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get .dockerenv
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get .dockerinit
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get dev
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get etc
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get entrypoint.sh
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get run
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get bin
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get var
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get tmp
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get usr
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get lib
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get mnt
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get opt
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get root
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get media
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get sys
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get selinux
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get boot
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get srv
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get home
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get sbin
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get lib64
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root/ get proc
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root///.oldroot
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root//
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] create directory /dev/pts
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] umount /.oldroot/tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/root
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] umount /.oldroot/proc
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] umount /.oldroot/tmp/hyper/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/devpts
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] umount /.oldroot/tmp/hyper/shared
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] umount /.oldroot/dev/pts
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] umount /.oldroot/dev
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] umount /.oldroot/sys
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] umount /.oldroot
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] setup pty device /dev/null for exec
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper send type 8, len 0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] container 1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb init pid is 2
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] pod ctl_pipe 8
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper send type 8, len 0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_init_event container pts event 0x15da8b0, ops 0x60e460, fd 0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_add_event add event fd 0, 0x60e460
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_init_event hyper ctl pipe fd 7
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_init_event hyper ctl pipe fd 7
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_init_event pod ctl pipe event 0x60e420, ops 0x60e360, fd 8
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_add_event add event fd 7, 0x60e320
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_add_event add event fd 8, 0x60e360
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_init_event pod signal event 0x60e3f8, ops 0x60e340, fd 3
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_init_event pod signal event 0x60e3f8, ops 0x60e340, fd 3
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] uptime 1.08 0.16
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] 
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper_add_event add event fd 3, 0x60e340
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] hyper send type 9, len 0
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:83] read 8/8 [length = 0]
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:87] data length is 8
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:73] trying to read 8 bytes
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:182] ack got, clear pong timer
[HYPER INFO  0703 14:18:20 02868 hypervisor.go] [:19] main event loop got message 29(COMMAND_ACK)
[HYPER INFO  0703 14:18:20 02868 vm_states.go] [:359] [starting] got init ack to 1
[HYPER INFO  0703 14:18:20 02868 context.go] [:239] VM vm-NFoFbPQjrt: state change from STARTING to 'RUNNING'
[HYPER INFO  0703 14:18:20 02868 vm_states.go] [:372] pod start success 
[HYPER INFO  0703 14:18:20 02868 pod.go] [:658] Get the response from QEMU, VM id is vm-NFoFbPQjrt!
[HYPER INFO  0703 14:18:20 02868 daemon.go] [:549] Add or Update the VM info for pod(pod-uDuCYEwAVG)
[HYPER INFO  0703 14:18:20 02868 daemon.go] [:567] success to add or  update the VM info for pod(pod-uDuCYEwAVG)
[HYPER INFO  0703 14:18:20 02868 job.go] [:84] -job podStart(pod-uDuCYEwAVG, ) OK
[HYPER INFO  0703 14:18:20 02868 init_comm.go] [:45] [console] tsc: Refined TSC clocksource calibration: 2202.361 MHz
[HYPER INFO  0703 14:18:22 02868 init_comm.go] [:45] [console] Switched to clocksource tsc
[HYPER INFO  0703 14:18:23 02868 init_comm.go] [:45] [console] random: mysql_install_d urandom read with 95 bits of entropy available
[HYPER INFO  0703 14:18:25 02868 init_comm.go] [:45] [console] random: nonblocking pool is initialized
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:189] Send ping message to init
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:227] send command 12 to init, payload: ''.
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:232] message sent, set pong timer
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:45] [console] hyper_loop epoll_wait 1
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:45] [console] hyper_event_read
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:45] [console] read 8 bytes data, total data 0
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:45] [console] get length 8
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:45] [console] 0 0 0 c 0 0 0 8 
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:45] [console]  hyper_channel_handle, type 12, len 8
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:45] [console] hyper send type 9, len 0
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:83] read 8/8 [length = 0]
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:87] data length is 8
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:73] trying to read 8 bytes
[HYPER INFO  0703 14:18:50 02868 init_comm.go] [:182] ack got, clear pong timer
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] pod_init_loop epoll_wait 1
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] signal_loop write to 8
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] pid 2 exit normally, status 1
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] hyper send type 7, len 5
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] hyper_loop epoll_wait 1
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] hyper_event_read
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] read 8 bytes data, total data 0
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] get length 13
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] read 5 bytes data, total data 8
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] hyper_ctl_pipe_handle
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] exec pid 2, pid 2
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] hyper_send_exec_eof exec pid 2, seq 0, container 1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] hyper_release_exec exit code 1
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] hyper_release_exec container init exited, type 12, remains 1, policy 0
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] hyper send type 13, len 4
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:83] read 8/8 [length = 0]
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:87] data length is 12
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:73] trying to read 4 bytes
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:83] read 12/12 [length = 12]
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:73] trying to read 8 bytes
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:220] Pod finished, returned 1 values
[HYPER INFO  0703 14:19:01 02868 hypervisor.go] [:19] main event loop got message 4(EVENT_POD_FINISH)
[HYPER INFO  0703 14:19:01 02868 context.go] [:239] VM vm-NFoFbPQjrt: state change from RUNNING to 'TERMINATING'
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:227] send command 4 to init, payload: ''.
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:232] message sent, set pong timer
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] umount /tmp/hyper/shared
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] Sending SIGTERM
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 103
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 35
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] pod_init_loop epoll_wait -1
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 34
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 33
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 32
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 31
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 30
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 29
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 23
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 22
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 21
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 20
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 19
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 18
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 17
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 16
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 15
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 14
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 13
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 12
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 11
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 10
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 9
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 8
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 7
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 6
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 5
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 4
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 3
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] kill process 2
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] ACPI: Preparing to enter system sleep state S5
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:45] [console] reboot: Power down
[HYPER INFO  0703 14:19:01 02868 qmp_handler.go] [:101] got a message {"timestamp": {"seconds": 1435933141, "microseconds": 588803}, "event": "SHUTDOWN"}
[HYPER INFO  0703 14:19:01 02868 qmp_handler.go] [:105] got event: SHUTDOWN
[HYPER INFO  0703 14:19:01 02868 qmp_handler.go] [:151] Shutdown, quit QMP receiver
[HYPER INFO  0703 14:19:01 02868 qmp_handler.go] [:326] got QMP event SHUTDOWN
[HYPER INFO  0703 14:19:01 02868 qmp_handler.go] [:328] got QMP shutdown event, quit...
[HYPER INFO  0703 14:19:01 02868 hypervisor.go] [:19] main event loop got message 1(EVENT_VM_EXIT)
[HYPER INFO  0703 14:19:01 02868 vm_states.go] [:478] Got VM shutdown event while terminating, go to cleaning up
[HYPER INFO  0703 14:19:01 02868 vm_states.go] [:21] qemu has exit...
[HYPER INFO  0703 14:19:01 02868 devicemap.go] [:418] need unmount aufs /1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/rootfs
[HYPER INFO  0703 14:19:01 02868 devicemap.go] [:455] remove network card 0: 192.168.123.2
[HYPER INFO  0703 14:19:01 02868 context.go] [:239] VM vm-NFoFbPQjrt: state change from TERMINATING to 'DESTROYING'
[HYPER INFO  0703 14:19:01 02868 tty.go] [:304] Input byte chan closed, close the output string chan
[HYPER ERROR 0703 14:19:01 02868 tty.go] [:72] read tty data failed
[HYPER INFO  0703 14:19:01 02868 tty.go] [:133] tty socket closed, quit the reading goroutine EOF
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:47] console output end
[HYPER ERROR 0703 14:19:01 02868 init_comm.go] [:76] read init data failed
[HYPER INFO  0703 14:19:01 02868 hypervisor.go] [:19] main event loop got message 32(ERROR_INTERRUPTED)
[HYPER INFO  0703 14:19:01 02868 vm_states.go] [:583] Connection interrupted while destroying
[HYPER INFO  0703 14:19:01 02868 hypervisor.go] [:19] main event loop got message 32(ERROR_INTERRUPTED)
[HYPER INFO  0703 14:19:01 02868 vm_states.go] [:583] Connection interrupted while destroying
[HYPER INFO  0703 14:19:01 02868 tty.go] [:100] tty chan closed, quit sent goroutine
[HYPER INFO  0703 14:19:01 02868 aufs.go] [:221] Ready to unmount the target : /var/run/hyper/vm-NFoFbPQjrt/share_dir/1a69c7dba6e8334a2ec1e138a3a1d40d5437d047edef570e2cf7a114dbc08ebb/rootfs
[HYPER INFO  0703 14:19:01 02868 hypervisor.go] [:19] main event loop got message 13(EVENT_INTERFACE_DELETE)
[HYPER INFO  0703 14:19:01 02868 devicemap.go] [:343] interface 0 released
[HYPER INFO  0703 14:19:01 02868 vm_states.go] [:245] Unplug interface return with true
[HYPER INFO  0703 14:19:01 02868 hypervisor.go] [:19] main event loop got message 7(EVENT_CONTAINER_DELETE)
[HYPER INFO  0703 14:19:01 02868 devicemap.go] [:325] container 0 umounted
[HYPER INFO  0703 14:19:01 02868 vm_states.go] [:242] Unplug container return with true
[HYPER INFO  0703 14:19:01 02868 context.go] [:226] no more device to release/remove/umount, quit
[HYPER INFO  0703 14:19:01 02868 vm_states.go] [:566] resources reclaimed, quit...
[HYPER INFO  0703 14:19:01 02868 qemu_process.go] [:23] quit watch dog.
[HYPER INFO  0703 14:19:01 02868 init_comm.go] [:157] vm channel closed, quit
[HYPER INFO  0703 14:19:37 02868 server.go] [:604] Calling GET /list
[HYPER INFO  0703 14:19:37 02868 server.go] [:179] List type is pod
[HYPER INFO  0703 14:19:37 02868 job.go] [:78] +job list(pod)
[HYPER INFO  0703 14:19:37 02868 job.go] [:84] -job list(pod) OK
carmark commented 9 years ago

Hi @holidayworking

Thanks for reporting this issue, I did reproduce this, and I will figure out.

For my local test, it works well to run a pod by sudo hyper run -p mysql.pod, you may try that as a workaround.

Thanks, -Lei

holidayworking commented 9 years ago

@carmark

Thank you for the reply.

I tried sudo hyper run -p mysql.pod, but did't resolve.

gnawux commented 9 years ago

Hi @holidayworking

We have located the root cause, it is a filesystem issue, and we will soon push a work around.

holidayworking commented 9 years ago

@gnawux I understand. I'm looking forward to that this problem is fixed.

gnawux commented 9 years ago

@holidayworking we have pushed a bug fix version to solve this issue, you can found it here: https://docs.hyper.sh/get_started/install.html

The root cause is the database init script script used multi-line cat (<<), and it is broken by the virtfs. After investigating for a weekend, we found a clean and simple solution. It looks good in our tests.

holidayworking commented 9 years ago

@gnawux Thanks for resolving this issue. I launch MySQL.

vagrant@vagrant-ubuntu-trusty-64:/vagrant/hyper-rails-example$ sudo hyper run -p mysql.pod
POD id is pod-GUMNkDosbs
Time to run a POD is 2053 ms
vagrant@vagrant-ubuntu-trusty-64:/vagrant/hyper-rails-example$ sudo hyper exec pod-GUMNkDosbs mysql -uroot
Welcome to the MySQL monitor.  Commands end with ; or \g.
Your MySQL connection id is 2
Server version: 5.6.25 MySQL Community Server (GPL)

Copyright (c) 2000, 2015, Oracle and/or its affiliates. All rights reserved.

Oracle is a registered trademark of Oracle Corporation and/or its
affiliates. Other names may be trademarks of their respective
owners.

Type 'help;' or '\h' for help. Type '\c' to clear the current input statement.

mysql> show databases;
+--------------------+
| Database           |
+--------------------+
| information_schema |
| mysql              |
| performance_schema |
+--------------------+
3 rows in set (0.01 sec)