Skip to content

Instantly share code, notes, and snippets.

@carmark
Created August 7, 2015 02:34
Show Gist options
  • Save carmark/ad8664b02937024746ae to your computer and use it in GitHub Desktop.
Save carmark/ad8664b02937024746ae to your computer and use it in GitHub Desktop.
sh-3.2# ./hyperd -v 4 --config=config --nondaemon
[HYPER INFO 0807 10:31:16 50024 hyperd.go] [:97] The config file is config
[HYPER INFO 0807 10:31:16 50024 docker.go] [:48] success to create docker
[HYPER INFO 0807 10:31:16 50024 daemon.go] [:193] The config: kernel=/var/lib/hyper/kernel, initrd=/var/lib/hyper/hyper-initrd.img
[HYPER INFO 0807 10:31:16 50024 daemon.go] [:195] The config: vbox image=/Users/lei/Downloads/hyper-vbox-boot.iso
[HYPER INFO 0807 10:31:16 50024 daemon.go] [:198] The config: bridge=, ip=
[HYPER INFO 0807 10:31:16 50024 daemon.go] [:201] The config: bios=, cbfs=
[HYPER INFO 0807 10:31:16 50024 driver.go] [:137] [graphdriver] using prior storage driver "vbox"
[HYPER INFO 0807 10:31:16 50024 daemon.go] [:632] Using graph driver vbox
[HYPER INFO 0807 10:31:16 50024 daemon.go] [:652] Creating images graph
[HYPER INFO 0807 10:31:16 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/1000000000000000000000000000000000000000000000000000000000000000.vdi
[HYPER INFO 0807 10:31:17 50024 graph.go] [:77] Restored 2 elements
[HYPER INFO 0807 10:31:17 50024 trusts.go] [:108] Reloaded graph with 3 grants expiring at 2017-03-22 19:04:46.713978458 +0000 UTC
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:677] Creating repository list
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:259] Loaded container 0268bd28c77f8d565ba8eb02c4567b9777ecdc5df4ffa65f66afe4be234d5a05
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:259] Loaded container 03e588d53988143e3ac37a63f58fc6f61ef223d34c40ff48ee0e27cde23fc928
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:259] Loaded container 1ccb7a45ac4b69b06fff07a4cefb2642c92c38ad3b272cb869bde8e7648730bd
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:259] Loaded container 2c66b5d9022c30e9d521b080a7504dc2310f5904fd04768acbf7dc2cc88878b0
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:259] Loaded container 2e1f3bf26bd86e7fac318e2bd1275f807fadd9b941e56e659c2cd6cfa517dabe
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:259] Loaded container 49acaf563c1d455bf8d6589e5ae61b130bc48d1f64218b0749eef4e5bd2ba3ec
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:259] Loaded container 4f3a109fe41aafb0e2603f0c87add12e185455c52544fedd65a064f1f3661cb6
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:259] Loaded container 5ea686d63d1b6e06e7c132daa49b9b48c6588d93f6024863acb5256fe3dfc481
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:259] Loaded container 6e524ec74da2809d487ebed3f33ff3492f3b87d0ce3d4d2bc0e53e2cd2936a4e
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:259] Loaded container 8a1b8b0e39d2cd181af89eee2479a3c7843df282faa6afe93cb5a25b92dda538
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:259] Loaded container 9e3895084573219531a28b0220f88208488c0d42f79b3f116f65e43f71af00f9
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:259] Loaded container bf3ae46315f80cb8dc30b97dcaf9fd1eb9347cbf6aa5f7ae626d143c47020ae5
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:259] Loaded container fb72131c6ceb0463b995b0d596f263e56eb331a6fd7ced528e7ef648ec22c2ad
[HYPER INFO 0807 10:31:17 50024 daemon.go] [:309] Loading containers: done.
[HYPER INFO 0807 10:31:17 50024 docker.go] [:101] Daemon has completed initialization
Vbox Driver Loaded.
[HYPER WARN 0807 10:31:17 50024 network.go] [:43] 192.168.123.0/24
[HYPER INFO 0807 10:31:17 50024 network.go] [:48] Try 192.168.123.3
[HYPER INFO 0807 10:31:17 50024 vbm.go] [:70] executing: VBoxManage showvminfo hyper-mac-pull-vm --machinereadable
[HYPER INFO 0807 10:31:17 50024 vbm.go] [:42] executing: VBoxManage createvm --name hyper-mac-pull-vm --register --basefolder /var/run/hyper/vm
Virtual machine 'hyper-mac-pull-vm' is created and registered.
UUID: de547503-de61-4718-80f5-701b640cf0b1
Settings file: '/var/run/hyper/vm/hyper-mac-pull-vm/hyper-mac-pull-vm.vbox'
[HYPER INFO 0807 10:31:17 50024 vbm.go] [:70] executing: VBoxManage showvminfo hyper-mac-pull-vm --machinereadable
[HYPER INFO 0807 10:31:17 50024 vbm.go] [:42] executing: VBoxManage modifyvm hyper-mac-pull-vm --uart1 0x03F8 4 --uartmode1 server /var/run/hyper/hyper-mac-pull-vm/hyper.sock
[HYPER INFO 0807 10:31:17 50024 vbm.go] [:42] executing: VBoxManage modifyvm hyper-mac-pull-vm --uart2 0x02F8 3 --uartmode2 server /var/run/hyper/hyper-mac-pull-vm/tty.sock
[HYPER INFO 0807 10:31:17 50024 vbm.go] [:42] executing: VBoxManage modifyvm hyper-mac-pull-vm --firmware bios --bioslogofadein off --bioslogofadeout off --bioslogodisplaytime 0 --biosbootmenu disabled --ostype Linux_64 --cpus 1 --memory 64 --vram 8 --acpi on --ioapic on --rtcuseutc off --cpuhotplug off --pae off --longmode on --hpet off --hwvirtex on --triplefaultreset off --nestedpaging off --largepages off --vtxvpid on --vtxux on --accelerate3d off --boot1 dvd
[HYPER INFO 0807 10:31:17 50024 vbm.go] [:70] executing: VBoxManage showvminfo hyper-mac-pull-vm --machinereadable
[HYPER INFO 0807 10:31:17 50024 vbm.go] [:42] executing: VBoxManage modifyvm hyper-mac-pull-vm --nic1 nat --nictype1 82545EM --cableconnected1 on --natnet1 192.168.123.0/24
[HYPER INFO 0807 10:31:17 50024 vbm.go] [:42] executing: VBoxManage storagectl hyper-mac-pull-vm --name hyper-mac-pull-vm --add sata --portcount 5 --hostiocache on --bootable on
[HYPER INFO 0807 10:31:17 50024 vbm.go] [:42] executing: VBoxManage storageattach hyper-mac-pull-vm --storagectl hyper-mac-pull-vm --port 0 --device 0 --type dvddrive --medium /Users/lei/Downloads/hyper-vbox-boot.iso
[HYPER INFO 0807 10:31:17 50024 vbm.go] [:42] executing: VBoxManage sharedfolder add hyper-mac-pull-vm --name share_dir --hostpath /var/run/hyper/hyper-mac-pull-vm/share_dir
[HYPER INFO 0807 10:31:17 50024 vbm.go] [:42] executing: VBoxManage startvm hyper-mac-pull-vm --type gui
Waiting for VM "hyper-mac-pull-vm" to power on...
VM "hyper-mac-pull-vm" has been successfully started.
[HYPER INFO 0807 10:31:18 50024 init_comm.go] [:110] Wating for init messages...
[HYPER INFO 0807 10:31:18 50024 init_comm.go] [:73] trying to read 8 bytes
[HYPER INFO 0807 10:31:18 50024 tty.go] [:126] tty socket connected
[HYPER INFO 0807 10:31:18 50024 tty.go] [:69] tty: trying to read 12 bytes
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:83] read 2/8 [length = 0]
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:73] trying to read 6 bytes
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:83] read 4/8 [length = 0]
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:73] trying to read 4 bytes
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:83] read 5/8 [length = 0]
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:73] trying to read 3 bytes
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:83] read 6/8 [length = 0]
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:73] trying to read 2 bytes
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:83] read 7/8 [length = 0]
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:73] trying to read 1 bytes
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:83] read 8/8 [length = 0]
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:87] data length is 8
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:120] Get init ready message
[HYPER INFO 0807 10:31:21 50024 hypervisor.go] [:29] main event loop got message 5(EVENT_INIT_CONNECTED)
[HYPER INFO 0807 10:31:21 50024 vm_states.go] [:301] begin to wait vm commands
[HYPER INFO 0807 10:31:21 50024 init_comm.go] [:73] trying to read 8 bytes
[HYPER INFO 0807 10:31:21 50024 vm.go] [:178] Get the response from VM, VM id is hyper-mac-pull-vm, response code is 1!
[HYPER INFO 0807 10:31:21 50024 vm.go] [:181] Got E_VM_RUNNING code response
[HYPER INFO 0807 10:31:21 50024 vbm.go] [:70] executing: VBoxManage showvminfo hyper-mac-pull-vm --machinereadable
[HYPER INFO 0807 10:31:21 50024 vbm.go] [:70] executing: VBoxManage storageattach hyper-mac-pull-vm --storagectl hyper-mac-pull-vm --port 4 --device 0 --type hdd --medium /private/var/lib/hyper/vbox/images/base.vdi --mtype normal
[HYPER INFO 0807 10:31:21 50024 vbm.go] [:42] executing: VBoxManage storageattach hyper-mac-pull-vm --storagectl hyper-mac-pull-vm --port 4 --device 0 --type hdd --medium none --mtype normal
[HYPER INFO 0807 10:31:21 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/0268bd28c77f8d565ba8eb02c4567b9777ecdc5df4ffa65f66afe4be234d5a05-init.vdi
[HYPER INFO 0807 10:31:21 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/1000000000000000000000000000000000000000000000000000000000000000.vdi
[HYPER INFO 0807 10:31:21 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/03e588d53988143e3ac37a63f58fc6f61ef223d34c40ff48ee0e27cde23fc928-init.vdi
[HYPER INFO 0807 10:31:21 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/2e1f3bf26bd86e7fac318e2bd1275f807fadd9b941e56e659c2cd6cfa517dabe-init.vdi
[HYPER INFO 0807 10:31:21 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/49acaf563c1d455bf8d6589e5ae61b130bc48d1f64218b0749eef4e5bd2ba3ec-init.vdi
[HYPER INFO 0807 10:31:21 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/5ea686d63d1b6e06e7c132daa49b9b48c6588d93f6024863acb5256fe3dfc481-init.vdi
[HYPER INFO 0807 10:31:21 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/6e524ec74da2809d487ebed3f33ff3492f3b87d0ce3d4d2bc0e53e2cd2936a4e-init.vdi
[HYPER INFO 0807 10:31:21 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/8a1b8b0e39d2cd181af89eee2479a3c7843df282faa6afe93cb5a25b92dda538-init.vdi
[HYPER INFO 0807 10:31:21 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/bf3ae46315f80cb8dc30b97dcaf9fd1eb9347cbf6aa5f7ae626d143c47020ae5-init.vdi
[HYPER INFO 0807 10:31:22 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/fb72131c6ceb0463b995b0d596f263e56eb331a6fd7ced528e7ef648ec22c2ad-init.vdi
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= build
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= podStart
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= list
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= tty
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= acceptconnections
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= vmKill
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= images
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= info
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= pull
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= push
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= podRm
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= podRun
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= version
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= podInfo
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= vmCreate
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= exec
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= imagesremove
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= attach
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= serveapi
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= auth
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= create
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= commit
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= podCreate
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:101] Engine Register: name= podStop
[HYPER INFO 0807 10:31:22 50024 hyperd.go] [:185] Hyper daemon: 0.2.1 0
[HYPER INFO 0807 10:31:22 50024 job.go] [:78] +job acceptconnections()
[HYPER INFO 0807 10:31:22 50024 job.go] [:84] -job acceptconnections() OK
[HYPER INFO 0807 10:31:22 50024 hyperd.go] [:217] Daemon has completed initialization
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:121] 1ccb7a45ac4b69b06fff07a4cefb2642c92c38ad3b272cb869bde8e7648730bd
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:121] bf3ae46315f80cb8dc30b97dcaf9fd1eb9347cbf6aa5f7ae626d143c47020ae5
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:121] 9e3895084573219531a28b0220f88208488c0d42f79b3f116f65e43f71af00f9
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:121] 4f3a109fe41aafb0e2603f0c87add12e185455c52544fedd65a064f1f3661cb6
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:121] 8a1b8b0e39d2cd181af89eee2479a3c7843df282faa6afe93cb5a25b92dda538
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:121] 49acaf563c1d455bf8d6589e5ae61b130bc48d1f64218b0749eef4e5bd2ba3ec
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:124] Get the pod item, pod is pod-pull-AmHejdTAgo!
[HYPER INFO 0807 10:31:22 50024 job.go] [:78] +job serveapi(unix:///var/run/hyper.sock)
[HYPER INFO 0807 10:31:22 50024 server.go] [:1071] Listening for HTTP on unix (/var/run/hyper.sock)
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /image/create
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /pod/create
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /pod/remove
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /pod/stop
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /vm/create
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /vm/kill
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /exec
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /auth
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /container/create
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /container/commit
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /attach
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /image/push
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /pod/start
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /pod/run
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /tty/resize
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /images/remove
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering POST, /image/build
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering OPTIONS,
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering GET, /pod/info
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering GET, /version
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering GET, /list
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering GET, /images/get
[HYPER INFO 0807 10:31:22 50024 server.go] [:934] Registering GET, /info
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:124] Get the pod item, pod is pod-pull-FesDqlMRQp!
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:124] Get the pod item, pod is pod-pull-FvVyVaidhp!
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:124] Get the pod item, pod is pod-pull-PhLJcGTWZl!
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:124] Get the pod item, pod is pod-pull-XwEeJszIuK!
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:124] Get the pod item, pod is pod-pull-kdkfkzHrMB!
[HYPER INFO 0807 10:31:22 50024 pod.go] [:115] podArgs: {"id":"mac-vm-disk-mount-layer","containers":[{"name":"mac-mount-disk","image":"puller:latest","command":["/pull-image.sh"],"workdir":"/","entrypoint":[],"ports":[],"envs":[],"volumes":[{"path":"/tmp/image","volume":"image","readOnly":false},{"path":"/tmp/imagecontent","volume":"imagecontent","readOnly":false},{"path":"/tmp/error","volume":"error","readOnly":false}],"files":[],"restartPolicy":"never"}],"resource":{"vcpu":1,"memory":64},"files":[],"volumes":[{"name":"imagecontent","source":"/private/var/lib/hyper/vbox/diff/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff","driver":"vfs"},{"name":"image","source":"/private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi","driver":"vdi"},{"name":"error","source":"/tmp/error/","driver":"vfs"}],"tty":false,"type":"","RestartPolicy":""}
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:144] leveldb: not found for pull-AmHejdTAgo
[HYPER INFO 0807 10:31:22 50024 pod.go] [:115] podArgs: {"id":"mac-vm-disk-mount-layer","containers":[{"name":"mac-mount-disk","image":"puller:latest","command":["/pull-image.sh"],"workdir":"/","entrypoint":[],"ports":[],"envs":[],"volumes":[{"path":"/tmp/image","volume":"image","readOnly":false},{"path":"/tmp/imagecontent","volume":"imagecontent","readOnly":false},{"path":"/tmp/error","volume":"error","readOnly":false}],"files":[],"restartPolicy":"never"}],"resource":{"vcpu":1,"memory":64},"files":[],"volumes":[{"name":"imagecontent","source":"/private/var/lib/hyper/vbox/diff/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff","driver":"vfs"},{"name":"image","source":"/private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi","driver":"vdi"},{"name":"error","source":"/tmp/error/","driver":"vfs"}],"tty":false,"type":"","RestartPolicy":""}
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:144] leveldb: not found for pull-FesDqlMRQp
[HYPER INFO 0807 10:31:22 50024 pod.go] [:115] podArgs: {"id":"mac-vm-disk-mount-layer","containers":[{"name":"mac-mount-disk","image":"puller:latest","command":["/pull-image.sh"],"workdir":"/","entrypoint":[],"ports":[],"envs":[],"volumes":[{"path":"/tmp/image","volume":"image","readOnly":false},{"path":"/tmp/imagecontent","volume":"imagecontent","readOnly":false},{"path":"/tmp/error","volume":"error","readOnly":false}],"files":[],"restartPolicy":"never"}],"resource":{"vcpu":1,"memory":64},"files":[],"volumes":[{"name":"imagecontent","source":"/private/var/lib/hyper/vbox/diff/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff","driver":"vfs"},{"name":"image","source":"/private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi","driver":"vdi"},{"name":"error","source":"/tmp/error/","driver":"vfs"}],"tty":false,"type":"","RestartPolicy":""}
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:144] leveldb: not found for pull-FvVyVaidhp
[HYPER INFO 0807 10:31:22 50024 pod.go] [:115] podArgs: {"id":"mac-vm-disk-mount-layer","containers":[{"name":"mac-mount-disk","image":"puller:latest","command":["/pull-image.sh"],"workdir":"/","entrypoint":[],"ports":[],"envs":[],"volumes":[{"path":"/tmp/image","volume":"image","readOnly":false},{"path":"/tmp/imagecontent","volume":"imagecontent","readOnly":false},{"path":"/tmp/error","volume":"error","readOnly":false}],"files":[],"restartPolicy":"never"}],"resource":{"vcpu":1,"memory":64},"files":[],"volumes":[{"name":"imagecontent","source":"/private/var/lib/hyper/vbox/diff/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff","driver":"vfs"},{"name":"image","source":"/private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi","driver":"vdi"},{"name":"error","source":"/tmp/error/","driver":"vfs"}],"tty":false,"type":"","RestartPolicy":""}
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:144] leveldb: not found for pull-PhLJcGTWZl
[HYPER INFO 0807 10:31:22 50024 pod.go] [:115] podArgs: {"id":"mac-vm-disk-mount-layer","containers":[{"name":"mac-mount-disk","image":"puller:latest","command":["/pull-image.sh"],"workdir":"/","entrypoint":[],"ports":[],"envs":[],"volumes":[{"path":"/tmp/image","volume":"image","readOnly":false},{"path":"/tmp/imagecontent","volume":"imagecontent","readOnly":false},{"path":"/tmp/error","volume":"error","readOnly":false}],"files":[],"restartPolicy":"never"}],"resource":{"vcpu":1,"memory":64},"files":[],"volumes":[{"name":"imagecontent","source":"/private/var/lib/hyper/vbox/diff/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff","driver":"vfs"},{"name":"image","source":"/private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi","driver":"vdi"},{"name":"error","source":"/tmp/error/","driver":"vfs"}],"tty":false,"type":"","RestartPolicy":""}
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:144] leveldb: not found for pull-XwEeJszIuK
[HYPER INFO 0807 10:31:22 50024 pod.go] [:115] podArgs: {"id":"mac-vm-disk-mount-layer","containers":[{"name":"mac-mount-disk","image":"puller:latest","command":["/pull-image.sh"],"workdir":"/","entrypoint":[],"ports":[],"envs":[],"volumes":[{"path":"/tmp/image","volume":"image","readOnly":false},{"path":"/tmp/imagecontent","volume":"imagecontent","readOnly":false},{"path":"/tmp/error","volume":"error","readOnly":false}],"files":[],"restartPolicy":"never"}],"resource":{"vcpu":1,"memory":64},"files":[],"volumes":[{"name":"imagecontent","source":"/private/var/lib/hyper/vbox/diff/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff","driver":"vfs"},{"name":"image","source":"/private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi","driver":"vdi"},{"name":"error","source":"/tmp/error/","driver":"vfs"}],"tty":false,"type":"","RestartPolicy":""}
[HYPER INFO 0807 10:31:22 50024 daemon.go] [:144] leveldb: not found for pull-kdkfkzHrMB
[HYPER INFO 0807 10:31:26 50024 server.go] [:829] Calling GET /images/get
[HYPER INFO 0807 10:31:26 50024 job.go] [:78] +job images(no)
[HYPER INFO 0807 10:31:26 50024 job.go] [:84] -job images(no) OK
[HYPER INFO 0807 10:31:28 50024 server.go] [:829] Calling POST /image/create
[HYPER INFO 0807 10:31:28 50024 server.go] [:628] Image name is busybox
[HYPER INFO 0807 10:31:28 50024 job.go] [:78] +job pull(busybox)
[HYPER INFO 0807 10:31:28 50024 pull.go] [:31] The Repository is busybox, and the tag is latest
[HYPER INFO 0807 10:31:28 50024 pull.go] [:32] pull the image from the repository!
[HYPER INFO 0807 10:31:28 50024 pull.go] [:77] pulling image from host "docker.io" with remote name "library/busybox"
[HYPER INFO 0807 10:31:28 50024 endpoint.go] [:59] pinging registry endpoint https://index.docker.io/v1/
[HYPER INFO 0807 10:31:28 50024 endpoint.go] [:167] attempting v1 ping for registry endpoint https://index.docker.io/v1/
[HYPER ERROR 0807 10:31:28 50024 init_comm.go] [:26] failed to connected to /var/run/hyper/hyper-mac-pull-vm/console.sock dial unix /var/run/hyper/hyper-mac-pull-vm/console.sock: no such file or directory
[HYPER INFO 0807 10:31:29 50024 trusts.go] [:177] Fetched 1 base graphs at 2015-08-07 10:31:29.781909161 +0800 CST
[HYPER INFO 0807 10:31:29 50024 pull.go] [:100] pulling v2 repository with local name "busybox"
[HYPER INFO 0807 10:31:29 50024 endpoint.go] [:59] pinging registry endpoint https://registry-1.docker.io/v2/
[HYPER INFO 0807 10:31:29 50024 endpoint.go] [:221] attempting v2 ping for registry endpoint https://registry-1.docker.io/v2/
[HYPER INFO 0807 10:31:29 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/registry-1.docker.io
[HYPER INFO 0807 10:31:29 50024 trusts.go] [:108] Reloaded graph with 3 grants expiring at 2017-03-22 19:04:46.713978458 +0000 UTC
[HYPER INFO 0807 10:31:32 50024 session_v2.go] [:60] Getting authorization for library/busybox [pull]
[HYPER INFO 0807 10:31:32 50024 pull.go] [:507] Pulling tag from V2 registry: "latest"
[HYPER INFO 0807 10:31:32 50024 session_v2.go] [:83] [registry] Calling "GET" https://registry-1.docker.io/v2/library/busybox/manifests/latest
[HYPER INFO 0807 10:31:32 50024 auth.go] [:52] Getting bearer token with map[realm:https://auth.docker.io/token service:registry.docker.io] for
[HYPER INFO 0807 10:31:32 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/auth.docker.io
[HYPER INFO 0807 10:31:33 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/registry-1.docker.io
[HYPER INFO 0807 10:31:36 50024 manifest.go] [:47] provided manifest reference "latest" is not a digest: invalid checksum digest format
[HYPER INFO 0807 10:31:36 50024 service.go] [:42] Verification failed for /library/busybox using key HX4C:4632:DIUC:LYE5:GKKZ:F6FA:5IUZ:WNT4:ZG2W:YCCD:Y4MA:2MJU
[HYPER INFO 0807 10:31:36 50024 manifest.go] [:125] Key check result: not verified
[HYPER INFO 0807 10:31:36 50024 manifest.go] [:131] Key check result: verified
[HYPER INFO 0807 10:31:36 50024 pull.go] [:522] Image manifest for busybox:latest has been verified
[HYPER INFO 0807 10:31:36 50024 pull.go] [:566] pulling blob "sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4" to V1 img cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff
[HYPER INFO 0807 10:31:36 50024 session_v2.go] [:204] [registry] Calling "GET" https://registry-1.docker.io/v2/library/busybox/blobs/sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4
[HYPER INFO 0807 10:31:36 50024 auth.go] [:43] Using cached token for
[HYPER INFO 0807 10:31:36 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/registry-1.docker.io
[HYPER INFO 0807 10:31:36 50024 pull.go] [:566] pulling blob "sha256:1db09adb5ddd7f1a07b6d585a7db747a51c7bd17418d47e91f901bdf420abd66" to V1 img 6ce2e90b0bc7224de3db1f0d646fe8e2c4dd37f1793928287f6074bc451a57ea
[HYPER INFO 0807 10:31:36 50024 session_v2.go] [:204] [registry] Calling "GET" https://registry-1.docker.io/v2/library/busybox/blobs/sha256:1db09adb5ddd7f1a07b6d585a7db747a51c7bd17418d47e91f901bdf420abd66
[HYPER INFO 0807 10:31:36 50024 auth.go] [:43] Using cached token for
[HYPER INFO 0807 10:31:36 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/registry-1.docker.io
[HYPER INFO 0807 10:31:36 50024 pull.go] [:566] pulling blob "sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4" to V1 img 8c2e06607696bd4afb3d03b687e361cc43cf8ec1a4a725bc96e39f05ba97dd55
[HYPER INFO 0807 10:31:36 50024 session_v2.go] [:204] [registry] Calling "GET" https://registry-1.docker.io/v2/library/busybox/blobs/sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4
[HYPER INFO 0807 10:31:36 50024 auth.go] [:43] Using cached token for
[HYPER INFO 0807 10:31:36 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/registry-1.docker.io
[HYPER INFO 0807 10:31:36 50024 pull.go] [:566] pulling blob "sha256:a3ed95caeb02ffe68cdd9fd84406680ae93d633cb16422d00e8a7c22955b46d4" to V1 img 8c2e06607696bd4afb3d03b687e361cc43cf8ec1a4a725bc96e39f05ba97dd55
[HYPER INFO 0807 10:31:37 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/dseasb33srnrn.cloudfront.net
[HYPER INFO 0807 10:31:37 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/dseasb33srnrn.cloudfront.net
[HYPER INFO 0807 10:31:37 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/dseasb33srnrn.cloudfront.net
[HYPER INFO 0807 10:31:39 50024 pull.go] [:614] Downloaded cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff to tempfile /private/var/lib/hyper/tmp/GetV2ImageBlob176947236
[HYPER INFO 0807 10:31:39 50024 pull.go] [:614] Downloaded 8c2e06607696bd4afb3d03b687e361cc43cf8ec1a4a725bc96e39f05ba97dd55 to tempfile /private/var/lib/hyper/tmp/GetV2ImageBlob624420086
[HYPER INFO 0807 10:31:39 50024 fsdiff.go] [:29] Start untar layer
ApplyLayer stdout: {"layerSize":0}
stderr:
[HYPER INFO 0807 10:31:39 50024 fsdiff.go] [:33] Untar time: 0.015253868s
[HYPER INFO 0807 10:31:39 50024 pod.go] [:115] podArgs: {"id":"mac-vm-disk-mount-layer","containers":[{"name":"mac-mount-disk","image":"puller:latest","command":["/pull-image.sh"],"workdir":"/","entrypoint":[],"ports":[],"envs":[],"volumes":[{"path":"/tmp/image","volume":"image","readOnly":false},{"path":"/tmp/imagecontent","volume":"imagecontent","readOnly":false},{"path":"/tmp/error","volume":"error","readOnly":false}],"files":[],"restartPolicy":"never"}],"resource":{"vcpu":1,"memory":64},"files":[],"volumes":[{"name":"imagecontent","source":"/private/var/lib/hyper/vbox/diff/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff","driver":"vfs"},{"name":"image","source":"/private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi","driver":"vdi"},{"name":"error","source":"/tmp/error/","driver":"vfs"}],"tty":false,"type":"","RestartPolicy":""}
[HYPER INFO 0807 10:31:39 50024 pod.go] [:137] leveldb: not found
[HYPER INFO 0807 10:31:39 50024 pod.go] [:146] Process the Containers section in POD SPEC
[HYPER INFO 0807 10:31:39 50024 tags.go] [:133] LookupImage Name is puller:latest
[HYPER INFO 0807 10:31:39 50024 pod.go] [:536] find vm:hyper-mac-pull-vm
POD id is pull-ycGCHKTAxG
[HYPER INFO 0807 10:31:39 50024 container.go] [:10] ready to get the container(ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96) info
[HYPER INFO 0807 10:31:39 50024 pod.go] [:353] Parsing envs for container 0: 0 Evs
[HYPER INFO 0807 10:31:39 50024 pod.go] [:354] The fs type is ext4
[HYPER INFO 0807 10:31:39 50024 pod.go] [:355] WorkingDir is
[HYPER INFO 0807 10:31:39 50024 pod.go] [:356] Image is /var/lib/hyper/vbox/images/ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96.vdi
[HYPER INFO 0807 10:31:39 50024 pod.go] [:367] Container Info is
&{ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96 /rootfs /var/lib/hyper/vbox/images/ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96.vdi ext4 [] [/bin/sh] map[]}
[HYPER INFO 0807 10:31:39 50024 pod.go] [:369] container 0 created ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96, workdir , env: map[]
[HYPER INFO 0807 10:31:39 50024 pod.go] [:428] trying to bind dir /private/var/lib/hyper/vbox/diff/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff to /var/run/hyper/hyper-mac-pull-vm/share_dir/cXmSlDnCJJ
[HYPER INFO 0807 10:31:39 50024 pod.go] [:447] dir /private/var/lib/hyper/vbox/diff/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff is bound to /var/run/hyper/hyper-mac-pull-vm/share_dir/cXmSlDnCJJ
[HYPER INFO 0807 10:31:39 50024 pod.go] [:419] bypass vdi volume image
[HYPER INFO 0807 10:31:39 50024 pod.go] [:428] trying to bind dir /tmp/error/ to /var/run/hyper/hyper-mac-pull-vm/share_dir/qgrDFsSxEU
[HYPER INFO 0807 10:31:39 50024 pod.go] [:447] dir /tmp/error/ is bound to /var/run/hyper/hyper-mac-pull-vm/share_dir/qgrDFsSxEU
[HYPER INFO 0807 10:31:39 50024 vm.go] [:190] hyperHandlePodEvent pod pull-ycGCHKTAxG, vm hyper-mac-pull-vm
[HYPER INFO 0807 10:31:39 50024 vm.go] [:197] hyperHandlePodEvent pod pull-ycGCHKTAxG, vm hyper-mac-pull-vm
[HYPER INFO 0807 10:31:39 50024 hypervisor.go] [:29] main event loop got message 21(COMMAND_RUN_POD)
[HYPER INFO 0807 10:31:39 50024 vm_states.go] [:314] got spec, prepare devices
[HYPER INFO 0807 10:31:39 50024 context.go] [:269] #0 Container Info:
[HYPER INFO 0807 10:31:39 50024 context.go] [:272]
{
...| "Id": "ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96",
...| "Rootfs": "/rootfs",
...| "Image": "/var/lib/hyper/vbox/images/ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96.vdi",
...| "Fstype": "ext4",
...| "Workdir": "",
...| "Entrypoint": null,
...| "Cmd": [
...| "/bin/sh"
...| ],
...| "Envs": {}
...|}
[HYPER INFO 0807 10:31:39 50024 devicemap.go] [:214] insert volume imagecontent to /tmp/imagecontent on 0
[HYPER INFO 0807 10:31:39 50024 devicemap.go] [:214] insert volume error to /tmp/error on 0
[HYPER INFO 0807 10:31:39 50024 vm_states.go] [:59] initial vm spec: {
"hostname": "mac-vm-disk-mount-layer",
"containers": [
{
"id": "ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96",
"rootfs": "/rootfs",
"fstype": "ext4",
"image": "",
"fsmap": [
{
"source": "cXmSlDnCJJ",
"path": "/tmp/imagecontent",
"readOnly": false
},
{
"source": "qgrDFsSxEU",
"path": "/tmp/error",
"readOnly": false
}
],
"workdir": "/",
"cmd": [
"/pull-image.sh"
],
"restartPolicy": "never"
}
],
"shareDir": "share_dir"
}
[HYPER INFO 0807 10:31:39 50024 vbm.go] [:42] executing: VBoxManage storageattach hyper-mac-pull-vm --storagectl hyper-mac-pull-vm --port 1 --device 0 --type hdd --medium /private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi
[HYPER INFO 0807 10:31:39 50024 vbox.go] [:273] Disk image (/private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi) add succeeded
[HYPER INFO 0807 10:31:39 50024 vbm.go] [:42] executing: VBoxManage storageattach hyper-mac-pull-vm --storagectl hyper-mac-pull-vm --port 2 --device 0 --type hdd --medium /var/lib/hyper/vbox/images/ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96.vdi
[HYPER INFO 0807 10:31:39 50024 vbox.go] [:273] Disk /var/lib/hyper/vbox/images/ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96.vdi (/var/lib/hyper/vbox/images/ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96.vdi) add succeeded
[HYPER INFO 0807 10:31:39 50024 context.go] [:243] VM hyper-mac-pull-vm: state change from to 'STARTING'
[HYPER INFO 0807 10:31:39 50024 hypervisor.go] [:29] main event loop got message 13(EVENT_INTERFACE_ADD)
[HYPER INFO 0807 10:31:39 50024 hypervisor.go] [:29] main event loop got message 10(EVENT_BLOCK_INSERTED)
[HYPER INFO 0807 10:31:39 50024 hypervisor.go] [:29] main event loop got message 10(EVENT_BLOCK_INSERTED)
[HYPER INFO 0807 10:31:39 50024 vbox.go] [:346] nic eth0 insert succeeded
[HYPER INFO 0807 10:31:39 50024 hypervisor.go] [:29] main event loop got message 15(EVENT_INTERFACE_INSERTED)
[HYPER INFO 0807 10:31:39 50024 vm_states.go] [:330] device ready, could run pod.
[HYPER INFO 0807 10:31:39 50024 init_comm.go] [:244] send command 1 to init, payload: '{"hostname":"mac-vm-disk-mount-layer","containers":[{"id":"ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96","rootfs":"/rootfs","fstype":"ext4","image":"sdb","volumes":[{"device":"sda","mount":"/tmp/image","fstype":"ext4","readOnly":false}],"fsmap":[{"source":"cXmSlDnCJJ","path":"/tmp/imagecontent","readOnly":false},{"source":"qgrDFsSxEU","path":"/tmp/error","readOnly":false}],"workdir":"/","cmd":["/pull-image.sh"],"restartPolicy":"never"}],"interfaces":[{"device":"eth0","ipAddress":"192.168.123.1","netMask":"255.255.255.0"}],"routes":[{"dest":"0.0.0.0/0","gateway":"192.168.123.0","device":"eth0"}],"shareDir":"share_dir"}'.
[HYPER INFO 0807 10:31:39 50024 init_comm.go] [:257] write 512 to init, payload: '�{"hostname":"mac-vm-disk-mount-layer","containers":[{"id":"ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96","rootfs":"/rootfs","fstype":"ext4","image":"sdb","volumes":[{"device":"sda","mount":"/tmp/image","fstype":"ext4","readOnly":false}],"fsmap":[{"source":"cXmSlDnCJJ","path":"/tmp/imagecontent","readOnly":false},{"source":"qgrDFsSxEU","path":"/tmp/error","readOnly":false}],"workdir":"/","cmd":["/pull-image.sh"],"restartPolicy":"never"}],"interfaces":[{"device":"eth0","ipAddress":'.
[HYPER INFO 0807 10:31:39 50024 init_comm.go] [:262] message sent, set pong timer
[HYPER INFO 0807 10:31:58 50024 pull.go] [:614] Downloaded 6ce2e90b0bc7224de3db1f0d646fe8e2c4dd37f1793928287f6074bc451a57ea to tempfile /private/var/lib/hyper/tmp/GetV2ImageBlob808650547
[HYPER INFO 0807 10:32:09 50024 hypervisor.go] [:29] main event loop got message 33(ERROR_INTERRUPTED)
[HYPER INFO 0807 10:32:09 50024 vm_states.go] [:191] Connection interrupted, quit...
[HYPER ERROR 0807 10:32:09 50024 vm_states.go] [:165] Shutting down because of an exception: connection to VM broken
[HYPER INFO 0807 10:32:09 50024 context.go] [:243] VM hyper-mac-pull-vm: state change from STARTING to 'TERMINATING'
[HYPER INFO 0807 10:32:09 50024 init_comm.go] [:244] send command 4 to init, payload: ''.
[HYPER INFO 0807 10:32:09 50024 vm.go] [:279] Get the response from QEMU, VM id is hyper-mac-pull-vm!
[HYPER ERROR 0807 10:32:09 50024 driver.go] [:325] Code is 7, Cause is connection to VM broken, QEMU response data is nil
[HYPER INFO 0807 10:32:09 50024 hypervisor.go] [:29] main event loop got message 24(COMMAND_SHUTDOWN)
[HYPER INFO 0807 10:32:09 50024 vm_states.go] [:512] got event during terminating
[HYPER INFO 0807 10:32:19 50024 hypervisor.go] [:29] main event loop got message 3(EVENT_VM_TIMEOUT)
[HYPER WARN 0807 10:32:19 50024 vm_states.go] [:507] Qemu did not exit in time, try to stop it
[HYPER ERROR 0807 10:32:19 50024 vm_states.go] [:174] Shutting down because of an exception: vm terminating timeout
[HYPER INFO 0807 10:32:19 50024 vbm.go] [:42] executing: VBoxManage controlvm hyper-mac-pull-vm poweroff
[HYPER INFO 0807 10:32:19 50024 vm.go] [:89] Got response: 7: vm terminating timeout
0%...10%...20%...[HYPER ERROR 0807 10:32:19 50024 init_comm.go] [:76] read init data failed
[HYPER INFO 0807 10:32:19 50024 hypervisor.go] [:29] main event loop got message 33(ERROR_INTERRUPTED)
[HYPER INFO 0807 10:32:19 50024 vm_states.go] [:510] Connection interrupted while terminating
30%...40%...50%...[HYPER ERROR 0807 10:32:19 50024 tty.go] [:72] read tty data failed
[HYPER INFO 0807 10:32:19 50024 tty.go] [:133] tty socket closed, quit the reading goroutine EOF
[HYPER INFO 0807 10:32:19 50024 hypervisor.go] [:29] main event loop got message 33(ERROR_INTERRUPTED)
[HYPER INFO 0807 10:32:19 50024 vm_states.go] [:510] Connection interrupted while terminating
[HYPER INFO 0807 10:32:19 50024 tty.go] [:100] tty chan closed, quit sent goroutine
60%...70%...80%...90%...100%
[HYPER INFO 0807 10:32:20 50024 vbm.go] [:42] executing: VBoxManage storageattach hyper-mac-pull-vm --storagectl hyper-mac-pull-vm --port 1 --device 0 --type hdd --medium none
[HYPER INFO 0807 10:32:20 50024 hypervisor.go] [:29] main event loop got message 1(EVENT_VM_EXIT)
[HYPER INFO 0807 10:32:20 50024 vm_states.go] [:479] Got VM shutdown event while terminating, go to cleaning up
[HYPER INFO 0807 10:32:20 50024 vm_states.go] [:21] qemu has exit...
[HYPER INFO 0807 10:32:20 50024 devicemap.go] [:388] need umount dir cXmSlDnCJJ
[HYPER INFO 0807 10:32:20 50024 devicemap.go] [:388] need umount dir qgrDFsSxEU
[HYPER INFO 0807 10:32:20 50024 devicemap.go] [:398] need remove dm file/var/lib/hyper/vbox/images/ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96.vdi
[HYPER INFO 0807 10:32:20 50024 devicemap.go] [:405] need remove dm file /private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi
[HYPER INFO 0807 10:32:20 50024 devicemap.go] [:468] remove network card 0: 192.168.123.1
[HYPER INFO 0807 10:32:20 50024 context.go] [:243] VM hyper-mac-pull-vm: state change from TERMINATING to 'DESTROYING'
[HYPER INFO 0807 10:32:20 50024 vm.go] [:89] Got response: 2: qemu shut down
[HYPER INFO 0807 10:32:20 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi
[HYPER INFO 0807 10:32:20 50024 hypervisor.go] [:29] main event loop got message 12(EVENT_BLOCK_EJECTED)
[HYPER INFO 0807 10:32:20 50024 devicemap.go] [:365] volume imagecontent umounted
[HYPER INFO 0807 10:32:20 50024 vm_states.go] [:247] Unplug block device return with true
[HYPER INFO 0807 10:32:20 50024 hypervisor.go] [:29] main event loop got message 9(EVENT_VOLUME_DELETE)
[HYPER INFO 0807 10:32:20 50024 devicemap.go] [:379] blockdev /var/lib/hyper/vbox/images/ff5e9cdb12ae5729d3b283bff45d0e89d9ba5ec0fc9395872fd75dd90190bd96.vdi deleted
[HYPER INFO 0807 10:32:20 50024 vm_states.go] [:250] release volume return with true
[HYPER INFO 0807 10:32:20 50024 hypervisor.go] [:29] main event loop got message 9(EVENT_VOLUME_DELETE)
[HYPER INFO 0807 10:32:20 50024 devicemap.go] [:379] blockdev image deleted
[HYPER INFO 0807 10:32:20 50024 vm_states.go] [:250] release volume return with true
[HYPER INFO 0807 10:32:20 50024 hypervisor.go] [:29] main event loop got message 14(EVENT_INTERFACE_DELETE)
[HYPER INFO 0807 10:32:20 50024 devicemap.go] [:356] interface 0 released
[HYPER INFO 0807 10:32:20 50024 vm_states.go] [:244] Unplug interface return with true
[HYPER INFO 0807 10:32:20 50024 hypervisor.go] [:29] main event loop got message 12(EVENT_BLOCK_EJECTED)
[HYPER INFO 0807 10:32:20 50024 devicemap.go] [:365] volume error umounted
[HYPER INFO 0807 10:32:20 50024 vm_states.go] [:247] Unplug block device return with true
[HYPER INFO 0807 10:32:20 50024 context.go] [:230] no more device to release/remove/umount, quit
[HYPER INFO 0807 10:32:20 50024 vm_states.go] [:567] resources reclaimed, quit...
[HYPER WARN 0807 10:32:20 50024 vm.go] [:205] panic during send shutdown message to channel
[HYPER INFO 0807 10:32:20 50024 init_comm.go] [:161] vm channel closed, quit
[HYPER WARN 0807 10:32:20 50024 vm.go] [:167] panic during send shutdown message to channel
[HYPER INFO 0807 10:32:20 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi
[HYPER INFO 0807 10:32:20 50024 vbm.go] [:42] executing: VBoxManage closemedium 030fb264-f93c-4a39-8d27-448a802f7973
[HYPER ERROR 0807 10:32:21 50024 pull.go] [:105] Error from V2 registry: QEMU response data is nil
[HYPER INFO 0807 10:32:21 50024 pull.go] [:108] image does not exist on v2 registry, falling back to v1
[HYPER INFO 0807 10:32:21 50024 pull.go] [:115] pulling v1 repository with local name "busybox"
[HYPER INFO 0807 10:32:21 50024 session.go] [:331] [registry] Calling GET https://index.docker.io/v1/repositories/library/busybox/images
[HYPER INFO 0807 10:32:21 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/index.docker.io
[HYPER INFO 0807 10:32:22 50024 pull.go] [:221] Retrieving the tag list
[HYPER INFO 0807 10:32:22 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/registry-1.docker.io
[HYPER INFO 0807 10:32:25 50024 session.go] [:290] Got status code 200 from https://registry-1.docker.io/v1/repositories/library/busybox/tags
[HYPER INFO 0807 10:32:25 50024 pull.go] [:236] Registering tags
[HYPER INFO 0807 10:32:25 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/registry-1.docker.io
[HYPER INFO 0807 10:32:27 50024 session.go] [:185] Ancestry: [8c2e06607696bd4afb3d03b687e361cc43cf8ec1a4a725bc96e39f05ba97dd55 6ce2e90b0bc7224de3db1f0d646fe8e2c4dd37f1793928287f6074bc451a57ea cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff]
[HYPER INFO 0807 10:32:27 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/registry-1.docker.io
[HYPER INFO 0807 10:32:28 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/registry-1.docker.io
[HYPER INFO 0807 10:32:30 50024 registry.go] [:92] hostDir: /etc/docker/certs.d/dseasb33srnrn.cloudfront.net
[HYPER INFO 0807 10:32:30 50024 session.go] [:273] server doesn't support resume
[HYPER INFO 0807 10:32:30 50024 fsdiff.go] [:29] Start untar layer
ApplyLayer stdout: {"layerSize":0}
stderr:
[HYPER INFO 0807 10:32:31 50024 fsdiff.go] [:33] Untar time: 0.014237829s
[HYPER INFO 0807 10:32:31 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi
[HYPER INFO 0807 10:32:31 50024 vbm.go] [:70] executing: VBoxManage showmediuminfo /private/var/lib/hyper/vbox/images/cf2616975b4a3cba083ca99bc3f0bf25f5f528c3c52be1596b30f60b0b1c37ff.vdi
[HYPER INFO 0807 10:32:31 50024 vbm.go] [:42] executing: VBoxManage closemedium c235307d-47ab-4d6f-b343-bb47d1c3202d
[HYPER INFO 0807 10:32:31 50024 job.go] [:84] -job pull(busybox) ERR: Error pulling image (latest) from busybox, can not find VM(hyper-mac-pull-vm)
^C[HYPER INFO 0807 10:32:33 50024 daemon.go] [:646] The daemon will stop all pod
[HYPER INFO 0807 10:32:33 50024 stop.go] [:34] Prepare to stop the POD: pull-FvVyVaidhp
[HYPER INFO 0807 10:32:33 50024 stop.go] [:34] Prepare to stop the POD: pull-PhLJcGTWZl
[HYPER INFO 0807 10:32:33 50024 stop.go] [:34] Prepare to stop the POD: pull-XwEeJszIuK
[HYPER INFO 0807 10:32:33 50024 stop.go] [:34] Prepare to stop the POD: pull-kdkfkzHrMB
[HYPER INFO 0807 10:32:33 50024 stop.go] [:34] Prepare to stop the POD: pull-ycGCHKTAxG
panic: runtime error: invalid memory address or nil pointer dereference
[signal 0xb code=0x1 addr=0x40 pc=0x40df2bf]
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment