Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

entrypoint: Remove reliance on pipes, retry fix_cgroup on failure #8974

Merged
merged 3 commits into from
Aug 17, 2020
Merged

entrypoint: Remove reliance on pipes, retry fix_cgroup on failure #8974

merged 3 commits into from
Aug 17, 2020

Conversation

tstromberg
Copy link
Contributor

@tstromberg tstromberg commented Aug 11, 2020 •
edited
Loading

NOTE: This is not yet tested on podman.

Fixes #8971 - in part by removing the SIGPIPE condition and using a for loop to iterate, but in part through implementing retry.

Blocked on #8982

@k8s-ci-robot k8s-ci-robot added the cncf-cla: yes Indicates the PR's author has signed the CNCF CLA. label Aug 11, 2020
@tstromberg
Copy link
Contributor Author

/ok-to-test

@k8s-ci-robot k8s-ci-robot added ok-to-test Indicates a non-member PR verified by an org member that is safe to test. size/M Denotes a PR that changes 30-99 lines, ignoring generated files. labels Aug 11, 2020
@k8s-ci-robot
Copy link
Contributor

[APPROVALNOTIFIER] This PR is APPROVED

This pull-request has been approved by: tstromberg

The full list of commands accepted by this bot can be found here.

The pull request process is described here

Needs approval from an approver in each of these files:

Approvers can indicate their approval by writing /approve in a comment
Approvers can cancel approval by writing /approve cancel in a comment

@k8s-ci-robot k8s-ci-robot added the approved Indicates a PR has been approved by an approver from all required OWNERS files. label Aug 11, 2020
@minikube-pr-bot
Copy link

kvm2 Driver
docker Driver

@TravisBuddy
Copy link

Travis tests have failed

Hey @tstromberg,
Please read the following log in order to understand the failure reason.
It'll be awesome if you fix what's wrong and commit the changes.

1st Build

View build log

make test 
which go-bindata || GO111MODULE=off GOBIN="/home/travis/gopath/bin" go get github.com/jteeuwen/go-bindata/...
PATH="/home/travis/gopath/bin:/home/travis/.gimme/versions/go1.14.6.linux.amd64/bin:/home/travis/bin:/home/travis/bin:/home/travis/.local/bin:/usr/local/lib/jvm/openjdk11/bin:/opt/pyenv/shims:/home/travis/.phpenv/shims:/home/travis/perl5/perlbrew/bin:/home/travis/.nvm/versions/node/v8.12.0/bin:/home/travis/.rvm/gems/ruby-2.5.3/bin:/home/travis/.rvm/gems/ruby-2.5.3@global/bin:/home/travis/.rvm/rubies/ruby-2.5.3/bin:/home/travis/gopath/bin:/home/travis/.gimme/versions/go1.11.1.linux.amd64/bin:/usr/local/maven-3.6.3/bin:/usr/local/cmake-3.12.4/bin:/usr/local/clang-7.0.0/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/home/travis/.rvm/bin:/home/travis/.phpenv/bin:/opt/pyenv/bin:/home/travis/.yarn/bin:/home/travis/gopath/bin" go-bindata -nomemcopy -o pkg/minikube/assets/assets.go -pkg assets deploy/addons/...
gofmt -s -w pkg/minikube/assets/assets.go
which go-bindata || GO111MODULE=off GOBIN="/home/travis/gopath/bin" go get github.com/jteeuwen/go-bindata/...
/home/travis/gopath/bin/go-bindata
PATH="/home/travis/gopath/bin:/home/travis/.gimme/versions/go1.14.6.linux.amd64/bin:/home/travis/bin:/home/travis/bin:/home/travis/.local/bin:/usr/local/lib/jvm/openjdk11/bin:/opt/pyenv/shims:/home/travis/.phpenv/shims:/home/travis/perl5/perlbrew/bin:/home/travis/.nvm/versions/node/v8.12.0/bin:/home/travis/.rvm/gems/ruby-2.5.3/bin:/home/travis/.rvm/gems/ruby-2.5.3@global/bin:/home/travis/.rvm/rubies/ruby-2.5.3/bin:/home/travis/gopath/bin:/home/travis/.gimme/versions/go1.11.1.linux.amd64/bin:/usr/local/maven-3.6.3/bin:/usr/local/cmake-3.12.4/bin:/usr/local/clang-7.0.0/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/home/travis/.rvm/bin:/home/travis/.phpenv/bin:/opt/pyenv/bin:/home/travis/.yarn/bin:/home/travis/gopath/bin" go-bindata -nomemcopy -o pkg/minikube/translate/translations.go -pkg translate translations/...
gofmt -s -w pkg/minikube/translate/translations.go
MINIKUBE_LDFLAGS="-X k8s.io/minikube/pkg/version.version=v1.12.3 -X k8s.io/minikube/pkg/version.isoVersion=v1.12.2 -X k8s.io/minikube/pkg/version.isoPath=minikube/iso -X k8s.io/minikube/pkg/version.gitCommitID="aa8be156aca6dac72d415c06eda560234a3d4244" -X k8s.io/minikube/pkg/version.storageProvisionerVersion=v2" ./test.sh
= schema_check ==========================================================
go: downloading github.com/xeipuuv/gojsonschema v0.0.0-20180618132009-1d523034197f
go: downloading github.com/xeipuuv/gojsonreference v0.0.0-20180127040603-bd5ef7bd5415
go: downloading github.com/xeipuuv/gojsonpointer v0.0.0-20180127040702-4e3ac2762d5f
ok
= go test ===============================================================
go: downloading github.com/shirou/gopsutil v2.18.12+incompatible
go: downloading k8s.io/client-go v0.17.3
go: downloading github.com/afbjorklund/go-containerregistry v0.0.0-20200602203322-347d93793dc9
go: downloading github.com/machine-drivers/machine v0.7.1-0.20200810185219-7d42fed1b770
go: downloading github.com/docker/docker v1.4.2-0.20190924003213-a8608b5b67c7
go: downloading k8s.io/apimachinery v0.17.3
go: downloading github.com/spf13/pflag v1.0.5
go: downloading github.com/pkg/profile v0.0.0-20161223203901-3a8809bd8a80
go: downloading github.com/blang/semver v3.5.0+incompatible
go: downloading github.com/cenkalti/backoff v2.2.1+incompatible
go: downloading github.com/google/slowjam v0.0.0-20200530021616-df27e642fe7b
go: downloading cloud.google.com/go v0.57.0
go: downloading k8s.io/klog v1.0.0
go: downloading github.com/otiai10/copy v1.0.2
go: downloading google.golang.org/api v0.25.0
go: downloading gopkg.in/yaml.v2 v2.3.0
go: downloading github.com/hooklift/iso9660 v0.0.0-20170318115843-1cf07e5970d8
go: downloading github.com/golang-collections/collections v0.0.0-20130729185459-604e922904d3
go: downloading github.com/mitchellh/go-ps v0.0.0-20190716172923-621e5597135b
go: downloading golang.org/x/sys v0.0.0-20200523222454-059865788121
go: downloading github.com/google/uuid v1.1.1
go: downloading github.com/afbjorklund/go-getter v1.4.1-0.20190910175809-eb9f6c26742c
go: downloading github.com/docker/go-units v0.4.0
go: downloading golang.org/x/crypto v0.0.0-20200510223506-06a226fb4e37
go: downloading github.com/spf13/cobra v1.0.0
go: downloading k8s.io/api v0.17.3
go: downloading github.com/intel-go/cpuid v0.0.0-20181003105527-1a4a6f06a1c6
go: downloading github.com/c4milo/gotoolkit v0.0.0-20170318115440-bcc06269efa9
go: downloading golang.org/x/sync v0.0.0-20200317015054-43a5402ce75a
go: downloading github.com/aws/aws-sdk-go v1.31.6
go: downloading github.com/hashicorp/go-version v1.2.0
go: downloading cloud.google.com/go/storage v1.8.0
go: downloading github.com/mitchellh/go-homedir v1.1.0
go: downloading github.com/kballard/go-shellquote v0.0.0-20180428030007-95032a82bc51
go: downloading k8s.io/kubernetes v1.17.3
go: downloading github.com/juju/clock v0.0.0-20190205081909-9c5c9712527c
go: downloading github.com/ulikunitz/xz v0.5.7
go: downloading github.com/pkg/errors v0.9.1
go: downloading github.com/googleapis/gax-go v2.0.2+incompatible
go: downloading github.com/hashicorp/go-cleanhttp v0.5.1
go: downloading golang.org/x/net v0.0.0-20200520182314-0ba52f642ac2
go: downloading github.com/pkg/browser v0.0.0-20160118053552-9302be274faa
go: downloading github.com/googleapis/gax-go/v2 v2.0.5
go: downloading github.com/hashicorp/go-safetemp v1.0.0
go: downloading github.com/juju/mutex v0.0.0-20180619145857-d21b13acf4bf
go: downloading github.com/golang/glog v0.0.0-20160126235308-23def4e6c14b
go: downloading go.opencensus.io v0.22.3
go: downloading github.com/mattn/go-isatty v0.0.12
go: downloading google.golang.org/grpc v1.29.1
go: downloading github.com/docker/go-connections v0.4.0
go: downloading github.com/phayes/freeport v0.0.0-20180830031419-95f893ade6f2
go: downloading k8s.io/cluster-bootstrap v0.17.3
go: downloading github.com/docker/cli v0.0.0-20200303162255-7d407207c304
go: downloading google.golang.org/genproto v0.0.0-20200527145253-8367513e4ece
go: downloading github.com/bgentry/go-netrc v0.0.0-20140422174119-9fd32a8b3d3d
go: downloading sigs.k8s.io/sig-storage-lib-external-provisioner v4.0.0+incompatible
go: downloading k8s.io/component-base v0.17.3
go: downloading golang.org/x/oauth2 v0.0.0-20200107190931-bf48bf16ab8d
go: downloading github.com/olekukonko/tablewriter v0.0.4
go: downloading github.com/opencontainers/go-digest v1.0.0-rc1
go: downloading github.com/modern-go/reflect2 v1.0.1
go: downloading github.com/cpuguy83/go-md2man v1.0.10
go: downloading github.com/mitchellh/go-testing-interface v1.0.0
go: downloading sigs.k8s.io/sig-storage-lib-external-provisioner/v5 v5.0.0
go: downloading k8s.io/utils v0.0.0-20200229041039-0a110f9eb7ab
go: downloading k8s.io/kubectl v0.17.3
go: downloading github.com/imdario/mergo v0.3.9
go: downloading k8s.io/kube-proxy v0.17.3
go: downloading github.com/gogo/protobuf v1.3.1
go: downloading github.com/davecgh/go-spew v1.1.1
go: downloading github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd
go: downloading github.com/spf13/viper v1.7.0
go: downloading github.com/cpuguy83/go-md2man/v2 v2.0.0
go: downloading github.com/mattn/go-runewidth v0.0.7
go: downloading github.com/opencontainers/image-spec v1.0.1
go: downloading github.com/prometheus/client_golang v1.4.1
go: downloading github.com/cloudevents/sdk-go/v2 v2.1.0
go: downloading google.golang.org/protobuf v1.24.0
go: downloading github.com/golang/protobuf v1.4.2
go: downloading github.com/googleapis/gnostic v0.3.0
go: downloading github.com/spf13/cast v1.3.1
go: downloading github.com/machine-drivers/docker-machine-driver-vmware v0.1.1
go: downloading github.com/hashicorp/hcl v1.0.0
go: downloading github.com/spf13/afero v1.2.2
go: downloading golang.org/x/time v0.0.0-20200416051211-89c76fbcd5d1
go: downloading github.com/google/gofuzz v1.0.0
go: downloading github.com/subosito/gotenv v1.2.0
go: downloading github.com/cheggaaa/pb v1.0.27
go: downloading github.com/miekg/dns v1.1.27
go: downloading github.com/russross/blackfriday v1.5.3-0.20200218234912-41c5fccfd6f6
go: downloading github.com/docker/docker-credential-helpers v0.6.3
go: downloading github.com/prometheus/client_model v0.2.0
go: downloading gopkg.in/inf.v0 v0.9.1
go: downloading github.com/juju/errors v0.0.0-20190806202954-0232dcc7464d
go: downloading github.com/cheggaaa/pb/v3 v3.0.1
go: downloading github.com/sayboras/dockerclient v1.0.0
go: downloading github.com/spf13/jwalterweatherman v1.1.0
go: downloading golang.org/x/text v0.3.2
go: downloading github.com/libvirt/libvirt-go v3.4.0+incompatible
go: downloading github.com/docker/distribution v2.7.1+incompatible
go: downloading github.com/russross/blackfriday/v2 v2.0.1
go: downloading github.com/MakeNowJust/heredoc v0.0.0-20170808103936-bb23615498cd
go: downloading github.com/fsnotify/fsnotify v1.4.9
go: downloading github.com/jmespath/go-jmespath v0.3.0
go: downloading github.com/prometheus/procfs v0.0.8
go: downloading github.com/cespare/xxhash v1.1.0
go: downloading github.com/mattn/go-colorable v0.1.6
go: downloading github.com/VividCortex/ewma v1.1.1
go: downloading github.com/beorn7/perks v1.0.1
go: downloading github.com/shurcooL/sanitized_anchor_name v1.0.0
go: downloading github.com/magiconair/properties v1.8.1
go: downloading github.com/cespare/xxhash/v2 v2.1.1
go: downloading github.com/prometheus/common v0.9.1
go: downloading github.com/sirupsen/logrus v1.6.0
go: downloading gopkg.in/ini.v1 v1.56.0
go: downloading github.com/json-iterator/go v1.1.9
go: downloading sigs.k8s.io/yaml v1.1.0
go: downloading github.com/fatih/color v1.9.0
go: downloading github.com/lightstep/tracecontext.go v0.0.0-20181129014701-1757c391b1ac
go: downloading github.com/containerd/containerd v1.3.1-0.20191213020239-082f7e3aed57
go: downloading github.com/pelletier/go-toml v1.8.0
go: downloading github.com/matttproud/golang_protobuf_extensions v1.0.1
go: downloading github.com/golang/groupcache v0.0.0-20200121045136-8c9f03a8e57e
go: downloading k8s.io/kube-openapi v0.0.0-20191107075043-30be4d16710a
go: downloading go.uber.org/zap v1.10.0
go: downloading github.com/mitchellh/go-wordwrap v1.0.0
go: downloading github.com/cloudfoundry-attic/jibber_jabber v0.0.0-20151120183258-bcc4c8345a21
go: downloading k8s.io/kubelet v0.17.3
go: downloading go.uber.org/atomic v1.4.0
go: downloading github.com/hashicorp/golang-lru v0.5.3
go: downloading github.com/mitchellh/mapstructure v1.3.1
go: downloading go.uber.org/multierr v1.1.0
go: downloading github.com/docker/spdystream v0.0.0-20160310174837-449fdfce4d96
go: downloading github.com/google/go-cmp v0.4.1
go: downloading github.com/pmezard/go-difflib v1.0.0
go: downloading golang.org/x/xerrors v0.0.0-20191204190536-9bdfabe68543
go: downloading github.com/evanphx/json-patch v4.5.0+incompatible
ok  	k8s.io/minikube/cmd/minikube/cmd	3.922s	coverage: 18.7% of statements
ok  	k8s.io/minikube/cmd/minikube/cmd/config	0.048s	coverage: 21.5% of statements
ok  	k8s.io/minikube/pkg/addons	0.111s	coverage: 52.5% of statements
ok  	k8s.io/minikube/pkg/drivers	0.013s	coverage: 19.6% of statements
ok  	k8s.io/minikube/pkg/drivers/hyperkit	0.003s	coverage: 77.3% of statements
ok  	k8s.io/minikube/pkg/drivers/kic/oci	0.014s	coverage: 3.5% of statements
ok  	k8s.io/minikube/pkg/drivers/kvm	0.026s	coverage: 2.3% of statements
ok  	k8s.io/minikube/pkg/minikube/bootstrapper	1.415s	coverage: 55.7% of statements
ok  	k8s.io/minikube/pkg/minikube/bootstrapper/bsutil	0.131s	coverage: 62.4% of statements
ok  	k8s.io/minikube/pkg/minikube/bootstrapper/bsutil/ktmpl	0.021s	coverage: 100.0% of statements
ok  	k8s.io/minikube/pkg/minikube/bootstrapper/images	0.003s	coverage: 84.2% of statements
ok  	k8s.io/minikube/pkg/minikube/cluster	0.061s	coverage: 15.3% of statements
ok  	k8s.io/minikube/pkg/minikube/command	0.034s	coverage: 4.3% of statements
ok  	k8s.io/minikube/pkg/minikube/config	0.128s	coverage: 70.0% of statements
ok  	k8s.io/minikube/pkg/minikube/cruntime	0.075s	coverage: 36.7% of statements
ok  	k8s.io/minikube/pkg/minikube/docker	0.029s	coverage: 20.8% of statements
ok  	k8s.io/minikube/pkg/minikube/driver	0.023s	coverage: 33.9% of statements
ok  	k8s.io/minikube/pkg/minikube/extract	0.005s	coverage: 58.8% of statements
ok  	k8s.io/minikube/pkg/minikube/image	0.020s	coverage: 3.0% of statements
ok  	k8s.io/minikube/pkg/minikube/kubeconfig	0.037s	coverage: 77.0% of statements
ok  	k8s.io/minikube/pkg/minikube/localpath	0.011s	coverage: 49.3% of statements
ok  	k8s.io/minikube/pkg/minikube/logs	0.041s	coverage: 1.3% of statements
127.0.0.1:46055
I0812 23:45:41.251414   13678 start.go:122] createHost starting for "minikube" (driver="mock")
E0812 23:45:41.251659   13678 register.go:123] unexpected first step: ""
W0812 23:45:41.254794   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:41.254872   13678 start.go:158] libmachine.API.Create for "" (driver="mock")
I0812 23:45:41.254928   13678 start.go:166] duration metric: libmachine.API.Create for "" took 56.363µs
I0812 23:45:41.254937   13678 start.go:207] post-start starting for "mock-machine-0.60466029" (driver="mock")
I0812 23:45:41.254942   13678 start.go:210] post-start completed in 204ns
I0812 23:45:41.255280   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.60466029/config.json ...
I0812 23:45:41.255426   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.60466029/config.json: {Name:mk3170c93f70b2cb494bf8741d37ff0e154f60ad Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:41.255539   13678 start.go:125] duration metric: createHost completed in 4.109466ms
I0812 23:45:41.255615   13678 start.go:122] createHost starting for "minikube" (driver="mock")
E0812 23:45:41.255624   13678 register.go:123] unexpected first step: ""
W0812 23:45:41.255761   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:41.255807   13678 start.go:158] libmachine.API.Create for "" (driver="mock")
I0812 23:45:41.255853   13678 start.go:166] duration metric: libmachine.API.Create for "" took 46.334µs
I0812 23:45:41.255859   13678 start.go:207] post-start starting for "mock-machine-0.94050909" (driver="mock")
I0812 23:45:41.255864   13678 start.go:210] post-start completed in 126ns
I0812 23:45:41.255941   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.94050909/config.json ...
I0812 23:45:41.255995   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.94050909/config.json: {Name:mke3a61728806aaaf83ca4ffce970a1569537d39 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:41.256094   13678 start.go:125] duration metric: createHost completed in 473.042µs
I0812 23:45:41.256120   13678 start.go:244] acquiring machines lock for mock-machine-0.94050909: {Name:mk6b752b56fd346dfffe418f01692610dcafbb9e Clock:{} Delay:500ms Timeout:15m0s Cancel:<nil>}
I0812 23:45:41.256161   13678 start.go:248] acquired machines lock for "mock-machine-0.94050909" in 33.612µs
I0812 23:45:41.256168   13678 start.go:89] Skipping create...Using existing machine configuration
I0812 23:45:41.256179   13678 fix.go:53] fixHost starting: mock-machine-0.94050909
I0812 23:45:41.256214   13678 fix.go:105] recreateIfNeeded on mock-machine-0.94050909: state=Running err=<nil>
W0812 23:45:41.256220   13678 fix.go:131] unexpected machine state, will restart: <nil>
W0812 23:45:41.256326   13678 out.go:101] [unset outFile]: * Updating the running mock "mock-machine-0.94050909" bare metal machine ...
I0812 23:45:41.256388   13678 fix.go:55] fixHost completed within 209.973µs
I0812 23:45:41.256402   13678 start.go:76] releasing machines lock for "mock-machine-0.94050909", held for 234.664µs
I0812 23:45:41.256467   13678 start.go:122] createHost starting for "minikube" (driver="mock")
E0812 23:45:41.256486   13678 register.go:123] unexpected first step: ""
W0812 23:45:41.256593   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:41.256649   13678 start.go:158] libmachine.API.Create for "" (driver="mock")
I0812 23:45:41.256708   13678 start.go:166] duration metric: libmachine.API.Create for "" took 59.4µs
I0812 23:45:41.256727   13678 start.go:207] post-start starting for "mock-machine-0.66456005" (driver="mock")
I0812 23:45:41.256741   13678 start.go:210] post-start completed in 69ns
I0812 23:45:41.256818   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.66456005/config.json ...
I0812 23:45:41.256895   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.66456005/config.json: {Name:mk17f65fe75e724567b086a049d5d140052f7365 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:41.256993   13678 start.go:125] duration metric: createHost completed in 512.142µs
I0812 23:45:41.257023   13678 start.go:244] acquiring machines lock for mock-machine-0.66456005: {Name:mk6b752b56fd346dfffe418f01692610dcafbb9e Clock:{} Delay:500ms Timeout:15m0s Cancel:<nil>}
I0812 23:45:41.257067   13678 start.go:248] acquired machines lock for "mock-machine-0.66456005" in 26.202µs
I0812 23:45:41.257082   13678 start.go:89] Skipping create...Using existing machine configuration
I0812 23:45:41.257093   13678 fix.go:53] fixHost starting: mock-machine-0.66456005
I0812 23:45:41.257148   13678 fix.go:105] recreateIfNeeded on mock-machine-0.66456005: state=Error err=machine does not exist
I0812 23:45:41.257166   13678 fix.go:110] machineExists: false. err=machine does not exist
W0812 23:45:41.257405   13678 out.go:101] [unset outFile]: * mock "mock-machine-0.66456005" bare metal machine is missing, will recreate.
I0812 23:45:41.257432   13678 delete.go:123] DEMOLISHING mock-machine-0.66456005 ...
Stopping "mock-machine-0.66456005"...
Error getting machine state: machine does not exist
Machine "mock-machine-0.66456005" was stopped.
I0812 23:45:41.257566   13678 stop.go:66] duration metric: stop complete within 120.101µs
W0812 23:45:41.257698   13678 out.go:101] [unset outFile]: * Deleting "mock-machine-0.66456005" in mock ...
W0812 23:45:41.257746   13678 delete.go:138] delete failed (probably ok) <nil>
I0812 23:45:41.257760   13678 fix.go:117] Sleeping 1 second for extra luck!
I0812 23:45:42.257872   13678 start.go:122] createHost starting for "mock-machine-0.66456005" (driver="mock")
E0812 23:45:42.257903   13678 register.go:123] unexpected first step: ""
W0812 23:45:42.258179   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:42.258283   13678 start.go:158] libmachine.API.Create for "mock-machine-0.66456005" (driver="mock")
I0812 23:45:42.258404   13678 start.go:166] duration metric: libmachine.API.Create for "mock-machine-0.66456005" took 120.965µs
I0812 23:45:42.258413   13678 start.go:207] post-start starting for "mock-machine-0.43771419" (driver="mock")
I0812 23:45:42.258419   13678 start.go:210] post-start completed in 286ns
I0812 23:45:42.258544   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.43771419/config.json ...
I0812 23:45:42.258636   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.43771419/config.json: {Name:mk7658848bd09615582780cd567cb6b759a18e97 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:42.258783   13678 start.go:125] duration metric: createHost completed in 883.775µs
W0812 23:45:42.258810   13678 fix.go:131] unexpected machine state, will restart: <nil>
I0812 23:45:42.258821   13678 fix.go:55] fixHost completed within 1.001728056s
I0812 23:45:42.258827   13678 start.go:76] releasing machines lock for "mock-machine-0.66456005", held for 1.001745144s
I0812 23:45:42.258849   13678 start.go:244] acquiring machines lock for mock-machine-0.43771419-mock-machine-0.43771419: {Name:mk6b752b56fd346dfffe418f01692610dcafbb9e Clock:{} Delay:500ms Timeout:15m0s Cancel:<nil>}
I0812 23:45:42.258892   13678 start.go:248] acquired machines lock for "mock-machine-0.43771419-mock-machine-0.43771419" in 33.506µs
I0812 23:45:42.258900   13678 start.go:85] Provisioning new machine with config: &{Name:mock-machine-0.43771419 KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage: Memory:0 CPUs:0 DiskSize:0 VMDriver: Driver:mock HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[MOCK_MAKE_IT_PROVISION=true] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR: HypervVirtualSwitch: HypervUseExternalSwitch:false HypervExternalAdapter: KVMNetwork: KVMQemuURI: KVMGPU:false KVMHidden:false DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot: UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:false HostOnlyNicType: NatNicType: KubernetesConfig:{KubernetesVersion: ClusterName: APIServerName: APIServerNames:[] APIServerIPs:[] DNSDomain: ContainerRuntime: CRISocket: NetworkPlugin: FeatureGates: ServiceCIDR: ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: ExtraOptions:[] ShouldLoadCachedImages:false EnableDefaultCNI:false CNI: NodeIP: NodePort:0 NodeName:} Nodes:[{Name:minikube IP:127.0.0.1 Port:0 KubernetesVersion: ControlPlane:false Worker:false} {Name:mock-machine-0.66456005 IP:127.0.0.1 Port:0 KubernetesVersion: ControlPlane:false Worker:false}] Addons:map[] VerifyComponents:map[] StartHostTimeout:6m0s} &{Name:mock-machine-0.43771419 IP:127.0.0.1 Port:0 KubernetesVersion: ControlPlane:false Worker:false}
I0812 23:45:42.258962   13678 start.go:122] createHost starting for "mock-machine-0.43771419" (driver="mock")
E0812 23:45:42.258971   13678 register.go:123] unexpected first step: ""
W0812 23:45:42.259210   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:42.259286   13678 start.go:158] libmachine.API.Create for "mock-machine-0.43771419" (driver="mock")
I0812 23:45:42.259356   13678 start.go:166] duration metric: libmachine.API.Create for "mock-machine-0.43771419" took 70.991µs
I0812 23:45:42.259364   13678 start.go:207] post-start starting for "mock-machine-0.42463750" (driver="mock")
I0812 23:45:42.259369   13678 start.go:210] post-start completed in 204ns
I0812 23:45:42.259463   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.42463750/config.json ...
I0812 23:45:42.259527   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.42463750/config.json: {Name:mk551194d8e51c3ef6795b15bf378178966682b9 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:42.259627   13678 start.go:125] duration metric: createHost completed in 658.19µs
I0812 23:45:42.259632   13678 start.go:76] releasing machines lock for "mock-machine-0.43771419-mock-machine-0.43771419", held for 734.121µs
I0812 23:45:42.259705   13678 start.go:122] createHost starting for "minikube" (driver="mock")
E0812 23:45:42.259711   13678 register.go:123] unexpected first step: ""
W0812 23:45:42.259854   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:42.259905   13678 start.go:158] libmachine.API.Create for "" (driver="mock")
I0812 23:45:42.259955   13678 start.go:166] duration metric: libmachine.API.Create for "" took 48.99µs
I0812 23:45:42.259960   13678 start.go:207] post-start starting for "mock-machine-0.68682307" (driver="mock")
I0812 23:45:42.259965   13678 start.go:210] post-start completed in 168ns
I0812 23:45:42.260039   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.68682307/config.json ...
I0812 23:45:42.260086   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.68682307/config.json: {Name:mkb3ca8047151030725d5bfa3235d9682f37dbe4 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:42.260172   13678 start.go:125] duration metric: createHost completed in 461.467µs
I0812 23:45:42.260182   13678 start.go:244] acquiring machines lock for mock-machine-0.68682307: {Name:mk6b752b56fd346dfffe418f01692610dcafbb9e Clock:{} Delay:500ms Timeout:15m0s Cancel:<nil>}
I0812 23:45:42.260217   13678 start.go:248] acquired machines lock for "mock-machine-0.68682307" in 27.157µs
I0812 23:45:42.260223   13678 start.go:89] Skipping create...Using existing machine configuration
I0812 23:45:42.260228   13678 fix.go:53] fixHost starting: mock-machine-0.68682307
I0812 23:45:42.260264   13678 fix.go:105] recreateIfNeeded on mock-machine-0.68682307: state=Stopped err=<nil>
W0812 23:45:42.260275   13678 fix.go:131] unexpected machine state, will restart: <nil>
W0812 23:45:42.260394   13678 out.go:101] [unset outFile]: * Restarting existing mock bare metal machine for "mock-machine-0.68682307" ...
I0812 23:45:42.260511   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.68682307/config.json ...
I0812 23:45:42.260714   13678 fix.go:55] fixHost completed within 485.592µs
I0812 23:45:42.260737   13678 start.go:76] releasing machines lock for "mock-machine-0.68682307", held for 513.626µs
I0812 23:45:42.260842   13678 start.go:244] acquiring machines lock for : {Name:mk6b752b56fd346dfffe418f01692610dcafbb9e Clock:{} Delay:500ms Timeout:15m0s Cancel:<nil>}
I0812 23:45:42.260920   13678 start.go:248] acquired machines lock for "" in 39.372µs
I0812 23:45:42.260934   13678 start.go:85] Provisioning new machine with config: &{Name: KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage: Memory:0 CPUs:0 DiskSize:0 VMDriver: Driver:mock HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[MOCK_MAKE_IT_PROVISION=true] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR: HypervVirtualSwitch: HypervUseExternalSwitch:false HypervExternalAdapter: KVMNetwork: KVMQemuURI: KVMGPU:false KVMHidden:false DockerOpt:[] DisableDriverMounts:false NFSShare:[] NFSSharesRoot: UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:false HostOnlyNicType: NatNicType: KubernetesConfig:{KubernetesVersion: ClusterName: APIServerName: APIServerNames:[] APIServerIPs:[] DNSDomain: ContainerRuntime: CRISocket: NetworkPlugin: FeatureGates: ServiceCIDR: ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: ExtraOptions:[] ShouldLoadCachedImages:false EnableDefaultCNI:false CNI: NodeIP: NodePort:0 NodeName:} Nodes:[{Name:minikube IP:127.0.0.1 Port:0 KubernetesVersion: ControlPlane:false Worker:false}] Addons:map[] VerifyComponents:map[] StartHostTimeout:6m0s} &{Name:minikube IP: Port:0 KubernetesVersion: ControlPlane:false Worker:false}
I0812 23:45:42.261009   13678 start.go:122] createHost starting for "minikube" (driver="mock")
E0812 23:45:42.261055   13678 register.go:123] unexpected first step: ""
W0812 23:45:42.261170   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:42.261240   13678 start.go:158] libmachine.API.Create for "" (driver="mock")
I0812 23:45:42.261343   13678 start.go:166] duration metric: libmachine.API.Create for "" took 120.064µs
I0812 23:45:42.261361   13678 start.go:207] post-start starting for "mock-machine-0.06563702" (driver="mock")
I0812 23:45:42.261373   13678 start.go:210] post-start completed in 127ns
I0812 23:45:42.261498   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.06563702/config.json ...
I0812 23:45:42.261582   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.06563702/config.json: {Name:mk6ae419bd97a515a45b4b2582af8c2923c492c3 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:42.261710   13678 start.go:125] duration metric: createHost completed in 655.1µs
I0812 23:45:42.261744   13678 start.go:76] releasing machines lock for "", held for 810.139µs
I0812 23:45:42.261884   13678 start.go:244] acquiring machines lock for -minikube: {Name:mk6b752b56fd346dfffe418f01692610dcafbb9e Clock:{} Delay:500ms Timeout:15m0s Cancel:<nil>}
I0812 23:45:42.261992   13678 start.go:248] acquired machines lock for "-minikube" in 69.602µs
I0812 23:45:42.262012   13678 start.go:85] Provisioning new machine with config: &{Name: KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage: Memory:0 CPUs:0 DiskSize:0 VMDriver: Driver:mock HyperkitVpnKitSock: HyperkitVSockPorts:[] DockerEnv:[FOO=BAR] InsecureRegistry:[] RegistryMirror:[] HostOnlyCIDR: HypervVirtualSwitch: HypervUseExternalSwitch:false HypervExternalAdapter: KVMNetwork: KVMQemuURI: KVMGPU:false KVMHidden:false DockerOpt:[param=value] DisableDriverMounts:false NFSShare:[] NFSSharesRoot: UUID: NoVTXCheck:false DNSProxy:false HostDNSResolver:false HostOnlyNicType: NatNicType: KubernetesConfig:{KubernetesVersion: ClusterName: APIServerName: APIServerNames:[] APIServerIPs:[] DNSDomain: ContainerRuntime: CRISocket: NetworkPlugin: FeatureGates: ServiceCIDR: ImageRepository: LoadBalancerStartIP: LoadBalancerEndIP: ExtraOptions:[] ShouldLoadCachedImages:false EnableDefaultCNI:false CNI: NodeIP: NodePort:0 NodeName:} Nodes:[] Addons:map[] VerifyComponents:map[] StartHostTimeout:0s} &{Name:minikube IP: Port:0 KubernetesVersion: ControlPlane:false Worker:false}
I0812 23:45:42.262084   13678 start.go:122] createHost starting for "minikube" (driver="mock")
E0812 23:45:42.262103   13678 register.go:123] unexpected first step: ""
W0812 23:45:42.262221   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:42.262287   13678 start.go:158] libmachine.API.Create for "" (driver="mock")
I0812 23:45:42.262381   13678 start.go:166] duration metric: libmachine.API.Create for "" took 94.086µs
I0812 23:45:42.262399   13678 start.go:207] post-start starting for "mock-machine-0.15651925" (driver="mock")
I0812 23:45:42.262431   13678 start.go:210] post-start completed in 121ns
I0812 23:45:42.262536   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.15651925/config.json ...
I0812 23:45:42.262622   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.15651925/config.json: {Name:mk960eb101a854f229168b325fd557803cded421 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:42.262766   13678 start.go:125] duration metric: createHost completed in 663.633µs
I0812 23:45:42.262785   13678 start.go:76] releasing machines lock for "-minikube", held for 773.905µs
I0812 23:45:42.262857   13678 stop.go:38] StopHost: mock-machine-0.15651925
I0812 23:45:42.262985   13678 start.go:122] createHost starting for "minikube" (driver="mock")
W0812 23:45:42.263059   13678 register.go:112] "Running on Localhost" was not found within the registered steps for "Stopping": [Stopping Done]
W0812 23:45:42.263150   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:42.263215   13678 start.go:158] libmachine.API.Create for "" (driver="mock")
I0812 23:45:42.263313   13678 start.go:166] duration metric: libmachine.API.Create for "" took 97.8µs
I0812 23:45:42.263352   13678 start.go:207] post-start starting for "mock-machine-0.09696952" (driver="mock")
I0812 23:45:42.263367   13678 start.go:210] post-start completed in 131ns
I0812 23:45:42.263463   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.09696952/config.json ...
I0812 23:45:42.263539   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.09696952/config.json: {Name:mkd4859af4fb57f6a54ef340245ee8cfa23e6a61 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:42.263675   13678 start.go:125] duration metric: createHost completed in 670.545µs
I0812 23:45:42.263697   13678 stop.go:38] StopHost: mock-machine-0.09696952
W0812 23:45:42.263831   13678 out.go:101] [unset outFile]: * Stopping node "mock-machine-0.09696952"  ...
Stopping "mock-machine-0.09696952"...
Machine "mock-machine-0.09696952" was stopped.
I0812 23:45:42.263933   13678 stop.go:66] duration metric: stop complete within 80.355µs
I0812 23:45:42.264016   13678 start.go:122] createHost starting for "minikube" (driver="mock")
W0812 23:45:42.264097   13678 register.go:112] "Running on Localhost" was not found within the registered steps for "Stopping": [Stopping Done]
W0812 23:45:42.264166   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:42.264213   13678 start.go:158] libmachine.API.Create for "" (driver="mock")
I0812 23:45:42.264305   13678 start.go:166] duration metric: libmachine.API.Create for "" took 91.144µs
I0812 23:45:42.264324   13678 start.go:207] post-start starting for "mock-machine-0.30091186" (driver="mock")
I0812 23:45:42.264343   13678 start.go:210] post-start completed in 139ns
I0812 23:45:42.264438   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.30091186/config.json ...
I0812 23:45:42.264508   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.30091186/config.json: {Name:mkf379be7e29e85f1820a7fd0db02a6439fa6ec8 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:42.264642   13678 start.go:125] duration metric: createHost completed in 607.69µs
W0812 23:45:42.264779   13678 register.go:112] "Running on Localhost" was not found within the registered steps for "Stopping": [Stopping Done]
W0812 23:45:42.264836   13678 out.go:101] [unset outFile]: * Deleting "mock-machine-0.30091186" in mock ...
I0812 23:45:42.264909   13678 start.go:122] createHost starting for "minikube" (driver="mock")
W0812 23:45:42.264976   13678 register.go:112] "Running on Localhost" was not found within the registered steps for "Stopping": [Stopping Done]
W0812 23:45:42.265035   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:42.265089   13678 start.go:158] libmachine.API.Create for "" (driver="mock")
I0812 23:45:42.265148   13678 start.go:166] duration metric: libmachine.API.Create for "" took 58.965µs
I0812 23:45:42.265153   13678 start.go:207] post-start starting for "mock-machine-0.51521263" (driver="mock")
I0812 23:45:42.265158   13678 start.go:210] post-start completed in 100ns
I0812 23:45:42.265258   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.51521263/config.json ...
I0812 23:45:42.265308   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.51521263/config.json: {Name:mk53d3afc462efe3a6f687bb990497d9138b73f9 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:42.265402   13678 start.go:125] duration metric: createHost completed in 486.55µs
I0812 23:45:42.265445   13678 start.go:122] createHost starting for "minikube" (driver="mock")
W0812 23:45:42.265499   13678 register.go:112] "Running on Localhost" was not found within the registered steps for "Stopping": [Stopping Done]
W0812 23:45:42.265564   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:42.265599   13678 start.go:158] libmachine.API.Create for "" (driver="mock")
I0812 23:45:42.265651   13678 start.go:166] duration metric: libmachine.API.Create for "" took 52.728µs
I0812 23:45:42.265656   13678 start.go:207] post-start starting for "mock-machine-0.81363996" (driver="mock")
I0812 23:45:42.265661   13678 start.go:210] post-start completed in 118ns
I0812 23:45:42.265733   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.81363996/config.json ...
I0812 23:45:42.265778   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.81363996/config.json: {Name:mkdcec76a54cf0aac3b6313c102437de1c77f7b7 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:42.265862   13678 start.go:125] duration metric: createHost completed in 411.071µs
I0812 23:45:42.265900   13678 start.go:122] createHost starting for "minikube" (driver="mock")
W0812 23:45:42.265945   13678 register.go:112] "Running on Localhost" was not found within the registered steps for "Stopping": [Stopping Done]
W0812 23:45:42.266012   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:42.266055   13678 start.go:158] libmachine.API.Create for "" (driver="mock")
I0812 23:45:42.266116   13678 start.go:166] duration metric: libmachine.API.Create for "" took 60.477µs
I0812 23:45:42.266121   13678 start.go:207] post-start starting for "mock-machine-0.21426387" (driver="mock")
I0812 23:45:42.266132   13678 start.go:210] post-start completed in 105ns
I0812 23:45:42.266197   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.21426387/config.json ...
I0812 23:45:42.266242   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.21426387/config.json: {Name:mk12e2b3f13729a0e17e010e337d15a8b4b03e20 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:42.266323   13678 start.go:125] duration metric: createHost completed in 417.15µs
I0812 23:45:42.266381   13678 start.go:122] createHost starting for "minikube" (driver="mock")
W0812 23:45:42.266415   13678 register.go:112] "Running on Localhost" was not found within the registered steps for "Stopping": [Stopping Done]
W0812 23:45:42.266480   13678 out.go:101] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0812 23:45:42.266512   13678 start.go:158] libmachine.API.Create for "mock-machine-0.21426387" (driver="mock")
I0812 23:45:42.266563   13678 start.go:166] duration metric: libmachine.API.Create for "mock-machine-0.21426387" took 50.297µs
I0812 23:45:42.266568   13678 start.go:207] post-start starting for "mock-machine-0.38065719" (driver="mock")
I0812 23:45:42.266573   13678 start.go:210] post-start completed in 103ns
I0812 23:45:42.266647   13678 profile.go:150] Saving config to /tmp/minipath614460054/.minikube/profiles/mock-machine-0.38065719/config.json ...
I0812 23:45:42.266693   13678 lock.go:35] WriteFile acquiring /tmp/minipath614460054/.minikube/profiles/mock-machine-0.38065719/config.json: {Name:mk63bd2b498ce0a02bbf11e7096e5083a3fb149f Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0812 23:45:42.266772   13678 start.go:125] duration metric: createHost completed in 385.227µs
I0812 23:45:42.266817   13678 stop.go:38] StopHost: mock-machine-0.38065719
W0812 23:45:42.266904   13678 out.go:101] [unset outFile]: * Stopping node "mock-machine-0.38065719"  ...
Stopping "mock-machine-0.38065719"...
Machine "mock-machine-0.38065719" was stopped.
I0812 23:45:42.266962   13678 stop.go:66] duration metric: stop complete within 51.064µs
I0812 23:45:42.510962   13678 host.go:65] Checking if "mock-machine-0.38065719" exists ...
Using SSH client type: native
&{{{<nil> 0 [] [] []} root [] 0xbe8020 <nil>  [] 0s} localhost 42315 <nil> <nil>}
--- FAIL: TestCreateSSHShell (0.25s)
    ssh_mock.go:193: Listening on 127.0.0.1:42315
    ssh_mock.go:83: Serving ...
    ssh_mock.go:87: [loop 1] Accepting for &{0xc0007d6ea0 map[] false  0 {map[]} false 0xc0003e5d20 0xc0007ad0e0}...
    api_mock.go:62: MockAPI.Load: &{ConfigVersion:0 Driver:0xc000026e70 DriverName: HostOptions:<nil> Name: RawDriver:[]} - <nil>
    driver_mock.go:49: MockDriver.GetState called from /home/travis/gopath/src/github.com/kubernetes/minikube/pkg/minikube/machine/cluster_test.go#457: returning "Running"
    ssh_mock.go:87: [loop 2] Accepting for &{0xc0007d6ea0 map[] false  0 {map[]} false 0xc0003e5d20 0xc0007ad0e0}...
    ssh_mock.go:175: pty request received: &{Type:pty-req WantReply:true Payload:[0 0 0 5 120 116 101 114 109 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 6 53 0 0 0 1 0] ch:0xc0003f49c0 mux:<nil>}
    ssh_mock.go:149: exec request received: &{Type:exec WantReply:true Payload:[0 0 0 4 101 120 105 116] ch:0xc0003f49c0 mux:<nil>}
    ssh_mock.go:161: returning output for exit ...
    ssh_mock.go:169: setting exit-status for exit ...
    ssh_mock.go:171: SendRequest failed: EOF
I0812 23:45:42.516047   13678 fix.go:209] guest clock: 1597275942.0000
I0812 23:45:42.516080   13678 fix.go:222] Guest: 2020-08-12 23:45:42 +0000 UTC Remote: 2020-08-12 23:45:42.516027143 +0000 UTC m=+1.312081122 (delta=-516.027143ms)
I0812 23:45:42.516490   13678 filesync.go:118] Scanning /tmp/minipath787933752/.minikube/addons for local assets ...
I0812 23:45:42.516631   13678 filesync.go:141] local asset: /tmp/minipath787933752/.minikube/addons/dir1/file1.txt -> file1.txt in /etc/kubernetes/addons
I0812 23:45:42.516669   13678 filesync.go:141] local asset: /tmp/minipath787933752/.minikube/addons/dir1/file2.txt -> file2.txt in /etc/kubernetes/addons
I0812 23:45:42.516711   13678 filesync.go:141] local asset: /tmp/minipath787933752/.minikube/addons/dir2/file1.txt -> file1.txt in /etc/kubernetes/addons
I0812 23:45:42.517421   13678 filesync.go:118] Scanning /tmp/minipath667676215/.minikube/files for local assets ...
I0812 23:45:42.517510   13678 filesync.go:141] local asset: /tmp/minipath667676215/.minikube/files/dir1/file1.txt -> file1.txt in /dir1
I0812 23:45:42.517555   13678 filesync.go:141] local asset: /tmp/minipath667676215/.minikube/files/dir1/file2.txt -> file2.txt in /dir1
I0812 23:45:42.517609   13678 filesync.go:141] local asset: /tmp/minipath667676215/.minikube/files/dir2/file1.txt -> file1.txt in /dir2
FAIL
coverage: 39.5% of statements
FAIL	k8s.io/minikube/pkg/minikube/machine	1.320s
ok  	k8s.io/minikube/pkg/minikube/mustload	0.063s	coverage: 10.5% of statements
ok  	k8s.io/minikube/pkg/minikube/notify	0.027s	coverage: 92.9% of statements
ok  	k8s.io/minikube/pkg/minikube/out	0.057s	coverage: 59.3% of statements
ok  	k8s.io/minikube/pkg/minikube/out/register	0.011s	coverage: 54.5% of statements
ok  	k8s.io/minikube/pkg/minikube/perf	4.140s	coverage: 33.8% of statements
ok  	k8s.io/minikube/pkg/minikube/problem	0.012s	coverage: 92.7% of statements
ok  	k8s.io/minikube/pkg/minikube/proxy	0.011s	coverage: 68.7% of statements
ok  	k8s.io/minikube/pkg/minikube/registry	0.011s	coverage: 75.5% of statements
ok  	k8s.io/minikube/pkg/minikube/service	0.073s	coverage: 84.2% of statements
ok  	k8s.io/minikube/pkg/minikube/shell	0.014s	coverage: 97.1% of statements
ok  	k8s.io/minikube/pkg/minikube/storageclass	0.044s	coverage: 100.0% of statements
ok  	k8s.io/minikube/pkg/minikube/sysinit	0.058s	coverage: 6.5% of statements
ok  	k8s.io/minikube/pkg/minikube/translate	0.005s	coverage: 10.5% of statements
ok  	k8s.io/minikube/pkg/minikube/tunnel	1.681s	coverage: 64.2% of statements
ok  	k8s.io/minikube/pkg/util	0.640s	coverage: 77.9% of statements
ok  	k8s.io/minikube/pkg/util/lock	0.013s	coverage: 18.2% of statements
ok  	k8s.io/minikube/pkg/util/retry	0.004s	coverage: 0.0% of statements
FAIL
Makefile:292: recipe for target 'test' failed
make: *** [test] Error 32
TravisBuddy Request Identifier: 1ac1de80-dcf6-11ea-b6e2-493429cddfc3

@minikube-pr-bot
Copy link

kvm2 Driver
Times for minikube: [62.339548500999996 62.363190529 63.020359895000006]
Average time for minikube: 62.57436630833333

Times for Minikube (PR 8974): [63.05438406299999 63.23591611 63.186196927]
Average time for Minikube (PR 8974): 63.158832366666665

Averages Time Per Log

+--------------------------------+-----------+--------------------+
|              LOG               | MINIKUBE  | MINIKUBE (PR 8974) |
+--------------------------------+-----------+--------------------+
| * minikube v1.12.3 on Debian   |  0.054777 |                    |
|                           9.11 |           |                    |
| * Using the kvm2 driver based  |  0.022397 |           0.017667 |
| on existing profile            |           |                    |
| * Starting control plane node  |  0.034991 |           0.003020 |
| minikube in cluster minikube   |           |                    |
| * Creating kvm2 VM (CPUs=2,    | 40.106355 |          40.068860 |
| Memory=3700MB, Disk=20000MB)   |           |                    |
| ...                            |           |                    |
| * Preparing Kubernetes v1.18.3 | 20.584217 |          21.348455 |
| on Docker 19.03.12 ...         |           |                    |
| * Verifying Kubernetes         |  1.420570 |           1.350024 |
| components...                  |           |                    |
| * Enabled addons:              |  0.266074 |           0.226236 |
| default-storageclass,          |           |                    |
| storage-provisioner            |           |                    |
| * Done! kubectl is now         |  0.076070 |           0.076546 |
| configured to use "minikube"   |           |                    |
|                                |  0.008915 |           0.013474 |
+--------------------------------+-----------+--------------------+

docker Driver
Times for minikube: [28.00440019 27.692591256 26.946729904]
Average time for minikube: 27.547907116666664

Times for Minikube (PR 8974): [28.78154445 26.819971215 28.630650863]
Average time for Minikube (PR 8974): 28.077388842666664

Averages Time Per Log

+--------------------------------+-----------+--------------------+
|              LOG               | MINIKUBE  | MINIKUBE (PR 8974) |
+--------------------------------+-----------+--------------------+
| * minikube v1.12.3 on Debian   |  0.064673 |                    |
|                           9.11 |           |                    |
| * Using the docker driver      |  0.101164 |           0.101501 |
| based on existing profile      |           |                    |
| * Starting control plane node  |  0.056192 |           0.056712 |
| minikube in cluster minikube   |           |                    |
| * Creating docker container    |  8.692555 |           8.605409 |
| (CPUs=2, Memory=3700MB) ...    |           |                    |
| * Preparing Kubernetes v1.18.3 | 17.658200 |          18.248363 |
| on Docker 19.03.8 ...          |           |                    |
| * Verifying Kubernetes         |  0.871225 |           0.887085 |
| components...                  |           |                    |
| * Enabled addons:              |  0.028797 |           0.041152 |
| default-storageclass,          |           |                    |
| storage-provisioner            |           |                    |
| * Done! kubectl is now         |  0.063289 |           0.065304 |
| configured to use "minikube"   |           |                    |
|                                |  0.011814 |           0.064824 |
+--------------------------------+-----------+--------------------+

@medyagh medyagh merged commit 119fbf4 into kubernetes:master Aug 17, 2020
@tstromberg tstromberg changed the title entrypoint: Retry fix_cgroup on failure entrypoint: Remove reliance on pipes, retry fix_cgroup on failure Sep 22, 2020
@tstromberg tstromberg mentioned this pull request Sep 22, 2020
Closed
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Reviewers

@medyagh medyagh medyagh approved these changes

@sharifelgamal sharifelgamal Awaiting requested review from sharifelgamal

Assignees
No one assigned
Labels
approved Indicates a PR has been approved by an approver from all required OWNERS files. cncf-cla: yes Indicates the PR's author has signed the CNCF CLA. ok-to-test Indicates a non-member PR verified by an org member that is safe to test. size/M Denotes a PR that changes 30-99 lines, ignoring generated files.
Projects
None yet
Milestone
No milestone
Development

Successfully merging this pull request may close these issues.

docker on v1.12.2: StartHost failed during initscript (fix_cgroup, SIGPIPE)
5 participants
@tstromberg @k8s-ci-robot @minikube-pr-bot @TravisBuddy @medyagh