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.

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

[WIP] Fix ValidateTunnelCmd test #8729

Closed

Conversation

11janci
Copy link
Contributor

@11janci 11janci commented Jul 15, 2020

Fixes #8304

On windows, when tests run minikube tunnel, it does not inherit the elevated privileges of the parent process - it needs to be set explicitly during the invocation. The chosen approach is using powershell and Start-Process because (almost) no additional code changes are required (especially for termination). Other possible approach would be through lower level win specific API, but the required code changes would be much larger.

@k8s-ci-robot k8s-ci-robot added do-not-merge/work-in-progress Indicates that a PR should not merge because it is a work in progress. cncf-cla: yes Indicates the PR's author has signed the CNCF CLA. labels Jul 15, 2020
@k8s-ci-robot
Copy link
Contributor

Hi @11janci. Thanks for your PR.

I'm waiting for a kubernetes member to verify that this patch is reasonable to test. If it is, they should reply with /ok-to-test on its own line. Until that is done, I will not automatically test new commits in this PR, but the usual testing commands by org members will still work. Regular contributors should join the org to skip this step.

Once the patch is verified, the new status will be reflected by the ok-to-test label.

I understand the commands that are listed here.

Instructions for interacting with me using PR comments are available here. If you have questions or suggestions related to my behavior, please file an issue against the kubernetes/test-infra repository.

@k8s-ci-robot k8s-ci-robot added needs-ok-to-test Indicates a PR that requires an org member to verify it is safe to test. size/S Denotes a PR that changes 10-29 lines, ignoring generated files. labels Jul 15, 2020
@k8s-ci-robot
Copy link
Contributor

[APPROVALNOTIFIER] This PR is NOT APPROVED

This pull-request has been approved by: 11janci
To complete the pull request process, please assign tstromberg
You can assign the PR to them by writing /assign @tstromberg in a comment when ready.

The full list of commands accepted by this bot can be found 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

@minikube-bot
Copy link
Collaborator

Can one of the admins verify this patch?

@medyagh
Copy link
Member

medyagh commented Jul 15, 2020

Failed test on windows hyperv
[
"TestFunctional/parallel/TunnelCmd/serial/AccessDirect"
]

@medyagh
Copy link
Member

medyagh commented Jul 15, 2020

@11janci thanks for picking this task

2020-07-15T18:45:18.4696923Z --- FAIL: TestFunctional/parallel/TunnelCmd/serial/AccessDirect (148.13s)
2020-07-15T18:45:18.4700569Z fn_tunnel_cmd_test.go:214: failed to hit nginx at "http://": Temporary Error: Get http:: http: no Host in request URL
2020-07-15T18:45:18.4701205Z fn_tunnel_cmd_test.go:216: (dbg) Run: kubectl --context functional-20200715183617-12392 get svc nginx-svc
2020-07-15T18:45:18.4702981Z fn_tunnel_cmd_test.go:220: failed to kubectl get svc nginx-svc:
2020-07-15T18:45:18.4704906Z NAME TYPE CLUSTER-IP EXTERNAL-IP PORT(S) AGE

@11janci 11janci force-pushed the jjanik-fix-validateTunnelCmd branch from 35efb9b to 74da82c Compare July 16, 2020 07:52
@codecov-commenter
Copy link

codecov-commenter commented Jul 16, 2020

Codecov Report

Merging #8729 into master will not change coverage.
The diff coverage is n/a.

Impacted file tree graph

@@           Coverage Diff           @@
##           master    #8729   +/-   ##
=======================================
  Coverage   31.52%   31.52%           
=======================================
  Files         166      166           
  Lines       11325    11325           
=======================================
  Hits         3570     3570           
  Misses       7322     7322           
  Partials      433      433           

@11janci 11janci force-pushed the jjanik-fix-validateTunnelCmd branch 3 times, most recently from e0d3848 to d58c6cd Compare July 16, 2020 09:39
@k8s-ci-robot k8s-ci-robot added size/M Denotes a PR that changes 30-99 lines, ignoring generated files. and removed size/S Denotes a PR that changes 10-29 lines, ignoring generated files. labels Jul 16, 2020
@TravisBuddy
Copy link

Travis tests have failed

Hey @11janci,
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.4.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.0/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.4.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.0/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.0 -X k8s.io/minikube/pkg/version.isoVersion=v1.12.0 -X k8s.io/minikube/pkg/version.isoPath=minikube/iso -X k8s.io/minikube/pkg/version.gitCommitID="f0baf664fb010cda050e865e6922079e5a89ddab"" ./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 cloud.google.com/go v0.57.0
go: downloading github.com/machine-drivers/machine v0.7.1-0.20200323212942-41eb826190d8
go: downloading github.com/blang/semver v3.5.0+incompatible
go: downloading github.com/cheggaaa/pb v1.0.27
go: downloading golang.org/x/crypto v0.0.0-20200510223506-06a226fb4e37
go: downloading k8s.io/client-go v0.17.3
go: downloading github.com/pkg/errors v0.9.1
go: downloading github.com/docker/go-units v0.4.0
go: downloading github.com/cheggaaa/pb/v3 v3.0.1
go: downloading k8s.io/kubernetes v1.17.3
go: downloading golang.org/x/sync v0.0.0-20200317015054-43a5402ce75a
go: downloading k8s.io/api v0.17.3
go: downloading github.com/golang/glog v0.0.0-20160126235308-23def4e6c14b
go: downloading k8s.io/apimachinery v0.17.3
go: downloading github.com/spf13/cobra v1.0.0
go: downloading cloud.google.com/go/storage v1.8.0
go: downloading golang.org/x/sys v0.0.0-20200523222454-059865788121
go: downloading github.com/spf13/pflag v1.0.5
go: downloading github.com/spf13/viper v1.7.0
go: downloading github.com/shirou/gopsutil v2.18.12+incompatible
go: downloading github.com/afbjorklund/go-getter v1.4.1-0.20190910175809-eb9f6c26742c
go: downloading github.com/gogo/protobuf v1.3.1
go: downloading github.com/mitchellh/go-ps v0.0.0-20190716172923-621e5597135b
go: downloading google.golang.org/api v0.25.0
go: downloading github.com/olekukonko/tablewriter v0.0.4
go: downloading github.com/spf13/jwalterweatherman v1.1.0
go: downloading github.com/sayboras/dockerclient v1.0.0
go: downloading github.com/hashicorp/hcl v1.0.0
go: downloading github.com/spf13/cast v1.3.1
go: downloading github.com/hooklift/iso9660 v0.0.0-20170318115843-1cf07e5970d8
go: downloading github.com/afbjorklund/go-containerregistry v0.0.0-20200602203322-347d93793dc9
go: downloading k8s.io/klog v1.0.0
go: downloading github.com/hashicorp/go-safetemp v1.0.0
go: downloading github.com/aws/aws-sdk-go v1.31.6
go: downloading github.com/mitchellh/go-homedir v1.1.0
go: downloading github.com/google/slowjam v0.0.0-20200530021616-df27e642fe7b
go: downloading github.com/pelletier/go-toml v1.8.0
go: downloading github.com/fatih/color v1.9.0
go: downloading github.com/docker/docker v1.4.2-0.20190924003213-a8608b5b67c7
go: downloading github.com/opencontainers/go-digest v1.0.0-rc1
go: downloading github.com/pkg/profile v0.0.0-20161223203901-3a8809bd8a80
go: downloading google.golang.org/grpc v1.29.1
go: downloading github.com/mitchellh/mapstructure v1.3.1
go: downloading github.com/mitchellh/go-testing-interface v1.0.0
go: downloading github.com/mattn/go-colorable v0.1.6
go: downloading github.com/otiai10/copy v1.0.2
go: downloading github.com/googleapis/gax-go v2.0.2+incompatible
go: downloading github.com/pkg/browser v0.0.0-20160118053552-9302be274faa
go: downloading github.com/ulikunitz/xz v0.5.7
go: downloading github.com/VividCortex/ewma v1.1.1
go: downloading github.com/cloudevents/sdk-go/v2 v2.1.0
go: downloading github.com/juju/clock v0.0.0-20190205081909-9c5c9712527c
go: downloading github.com/google/gofuzz v1.0.0
go: downloading github.com/googleapis/gnostic v0.3.0
go: downloading k8s.io/utils v0.0.0-20200229041039-0a110f9eb7ab
go: downloading github.com/json-iterator/go v1.1.8
go: downloading go.opencensus.io v0.22.3
go: downloading github.com/googleapis/gax-go/v2 v2.0.5
go: downloading github.com/fsnotify/fsnotify v1.4.9
go: downloading google.golang.org/genproto v0.0.0-20200527145253-8367513e4ece
go: downloading github.com/mattn/go-runewidth v0.0.7
go: downloading github.com/subosito/gotenv v1.2.0
go: downloading github.com/modern-go/reflect2 v1.0.1
go: downloading gopkg.in/ini.v1 v1.56.0
go: downloading golang.org/x/oauth2 v0.0.0-20200107190931-bf48bf16ab8d
go: downloading k8s.io/kube-proxy v0.17.3
go: downloading sigs.k8s.io/yaml v1.1.0
go: downloading gopkg.in/yaml.v2 v2.3.0
go: downloading github.com/bgentry/go-netrc v0.0.0-20140422174119-9fd32a8b3d3d
go: downloading k8s.io/kubelet v0.17.3
go: downloading k8s.io/kubectl v0.17.3
go: downloading github.com/mattn/go-isatty v0.0.12
go: downloading github.com/libvirt/libvirt-go v3.4.0+incompatible
go: downloading github.com/hashicorp/go-cleanhttp v0.5.1
go: downloading github.com/cpuguy83/go-md2man v1.0.10
go: downloading github.com/docker/go-connections v0.4.0
go: downloading github.com/magiconair/properties v1.8.1
go: downloading github.com/golang/groupcache v0.0.0-20200121045136-8c9f03a8e57e
go: downloading github.com/google/uuid v1.1.1
go: downloading github.com/cpuguy83/go-md2man/v2 v2.0.0
go: downloading google.golang.org/protobuf v1.24.0
go: downloading github.com/intel-go/cpuid v0.0.0-20181003105527-1a4a6f06a1c6
go: downloading github.com/docker/distribution v2.7.1+incompatible
go: downloading github.com/phayes/freeport v0.0.0-20180830031419-95f893ade6f2
go: downloading github.com/juju/mutex v0.0.0-20180619145857-d21b13acf4bf
go: downloading github.com/machine-drivers/docker-machine-driver-vmware v0.1.1
go: downloading github.com/hashicorp/go-version v1.2.0
go: downloading github.com/imdario/mergo v0.3.9
go: downloading github.com/golang/protobuf v1.4.2
go: downloading github.com/juju/errors v0.0.0-20190806202954-0232dcc7464d
go: downloading github.com/docker/cli v0.0.0-20200303162255-7d407207c304
go: downloading sigs.k8s.io/sig-storage-lib-external-provisioner v4.0.0+incompatible
go: downloading golang.org/x/net v0.0.0-20200520182314-0ba52f642ac2
go: downloading github.com/c4milo/gotoolkit v0.0.0-20170318115440-bcc06269efa9
go: downloading k8s.io/component-base v0.17.3
go: downloading golang.org/x/text v0.3.2
go: downloading k8s.io/cluster-bootstrap v0.17.3
go: downloading github.com/prometheus/client_golang v1.1.0
go: downloading github.com/cenkalti/backoff v2.2.1+incompatible
go: downloading github.com/mitchellh/go-wordwrap v1.0.0
go: downloading github.com/russross/blackfriday v1.5.3-0.20200218234912-41c5fccfd6f6
go: downloading gopkg.in/inf.v0 v0.9.1
go: downloading github.com/cloudfoundry-attic/jibber_jabber v0.0.0-20151120183258-bcc4c8345a21
go: downloading golang.org/x/time v0.0.0-20200416051211-89c76fbcd5d1
go: downloading github.com/golang-collections/collections v0.0.0-20130729185459-604e922904d3
go: downloading github.com/MakeNowJust/heredoc v0.0.0-20170808103936-bb23615498cd
go: downloading github.com/spf13/afero v1.2.2
go: downloading github.com/containerd/containerd v1.3.1-0.20191213020239-082f7e3aed57
go: downloading github.com/miekg/dns v1.1.4
go: downloading github.com/russross/blackfriday/v2 v2.0.1
go: downloading github.com/prometheus/procfs v0.0.5
go: downloading github.com/beorn7/perks v1.0.1
go: downloading github.com/hashicorp/golang-lru v0.5.3
go: downloading github.com/kballard/go-shellquote v0.0.0-20180428030007-95032a82bc51
go: downloading github.com/docker/docker-credential-helpers v0.6.3
go: downloading go.uber.org/zap v1.10.0
go: downloading github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd
go: downloading github.com/opencontainers/image-spec v1.0.1
go: downloading github.com/shurcooL/sanitized_anchor_name v1.0.0
go: downloading github.com/prometheus/client_model v0.0.0-20190812154241-14fe0d1b01d4
go: downloading github.com/lightstep/tracecontext.go v0.0.0-20181129014701-1757c391b1ac
go: downloading github.com/prometheus/common v0.6.0
go: downloading github.com/sirupsen/logrus v1.6.0
go: downloading github.com/google/go-cmp v0.4.1
go: downloading github.com/jmespath/go-jmespath v0.3.0
go: downloading github.com/davecgh/go-spew v1.1.1
go: downloading k8s.io/kube-openapi v0.0.0-20191107075043-30be4d16710a
go: downloading github.com/docker/spdystream v0.0.0-20160310174837-449fdfce4d96
go: downloading go.uber.org/atomic v1.4.0
go: downloading go.uber.org/multierr v1.1.0
go: downloading github.com/matttproud/golang_protobuf_extensions v1.0.1
go: downloading github.com/pmezard/go-difflib v1.0.0
go: downloading github.com/evanphx/json-patch v4.5.0+incompatible
go: downloading golang.org/x/xerrors v0.0.0-20191204190536-9bdfabe68543
ok  	k8s.io/minikube/cmd/minikube/cmd	4.156s	coverage: 19.7% of statements
ok  	k8s.io/minikube/cmd/minikube/cmd/config	0.149s	coverage: 21.5% of statements
ok  	k8s.io/minikube/pkg/addons	0.054s	coverage: 53.5% of statements
ok  	k8s.io/minikube/pkg/drivers	0.006s	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.011s	coverage: 0.0% of statements
ok  	k8s.io/minikube/pkg/drivers/kvm	0.037s	coverage: 2.3% of statements
ok  	k8s.io/minikube/pkg/minikube/bootstrapper	0.449s	coverage: 55.7% of statements
ok  	k8s.io/minikube/pkg/minikube/bootstrapper/bsutil	0.121s	coverage: 62.4% of statements
ok  	k8s.io/minikube/pkg/minikube/bootstrapper/bsutil/ktmpl	0.011s	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.058s	coverage: 16.7% of statements
ok  	k8s.io/minikube/pkg/minikube/command	0.022s	coverage: 4.3% of statements
ok  	k8s.io/minikube/pkg/minikube/config	0.123s	coverage: 70.0% of statements
ok  	k8s.io/minikube/pkg/minikube/cruntime	0.042s	coverage: 40.1% of statements
ok  	k8s.io/minikube/pkg/minikube/docker	0.048s	coverage: 20.8% of statements
ok  	k8s.io/minikube/pkg/minikube/driver	0.042s	coverage: 37.0% of statements
ok  	k8s.io/minikube/pkg/minikube/extract	0.010s	coverage: 58.8% of statements
ok  	k8s.io/minikube/pkg/minikube/image	0.021s	coverage: 3.0% of statements
ok  	k8s.io/minikube/pkg/minikube/kubeconfig	0.024s	coverage: 77.0% of statements
ok  	k8s.io/minikube/pkg/minikube/localpath	0.003s	coverage: 51.9% of statements
ok  	k8s.io/minikube/pkg/minikube/logs	0.033s	coverage: 1.3% of statements
127.0.0.1:43879
I0716 09:44:41.929846   13657 start.go:122] createHost starting for "minikube" (driver="mock")
W0716 09:44:41.933033   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:41.933117   13657 start.go:158] libmachine.API.Create for "" (driver="mock")
I0716 09:44:41.933184   13657 start.go:163] duration metric: libmachine.API.Create for "" took 67.519µs
I0716 09:44:41.933191   13657 start.go:204] post-start starting for "mock-machine-0.60466029" (driver="mock")
I0716 09:44:41.933196   13657 start.go:207] post-start completed in 178ns
I0716 09:44:41.933508   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.60466029/config.json ...
I0716 09:44:41.933612   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.60466029/config.json: {Name:mk4e84680c8fac3608e53ca185c41af661e6398e Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:41.933718   13657 start.go:125] duration metric: createHost completed in 3.856516ms
I0716 09:44:41.933788   13657 start.go:122] createHost starting for "minikube" (driver="mock")
W0716 09:44:41.934109   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:41.934153   13657 start.go:158] libmachine.API.Create for "" (driver="mock")
I0716 09:44:41.934215   13657 start.go:163] duration metric: libmachine.API.Create for "" took 61.735µs
I0716 09:44:41.934220   13657 start.go:204] post-start starting for "mock-machine-0.94050909" (driver="mock")
I0716 09:44:41.934225   13657 start.go:207] post-start completed in 193ns
I0716 09:44:41.934299   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.94050909/config.json ...
I0716 09:44:41.934345   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.94050909/config.json: {Name:mkd146d53cdbd505fa7ad140403db1000322d7cd Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:41.934438   13657 start.go:125] duration metric: createHost completed in 644.166µs
I0716 09:44:41.934461   13657 start.go:241] acquiring machines lock for mock-machine-0.94050909: {Name:mk9ef2a9d36ab9d0a1d972e835fc233057cd5583 Clock:{} Delay:500ms Timeout:15m0s Cancel:<nil>}
I0716 09:44:41.934502   13657 start.go:245] acquired machines lock for "mock-machine-0.94050909" in 33.907µs
I0716 09:44:41.934510   13657 start.go:89] Skipping create...Using existing machine configuration
I0716 09:44:41.934520   13657 fix.go:53] fixHost starting: mock-machine-0.94050909
I0716 09:44:41.934557   13657 fix.go:105] recreateIfNeeded on mock-machine-0.94050909: state=Running err=<nil>
W0716 09:44:41.934563   13657 fix.go:131] unexpected machine state, will restart: <nil>
W0716 09:44:41.934652   13657 out.go:100] [unset outFile]: * Updating the running mock "mock-machine-0.94050909" bare metal machine ...
I0716 09:44:41.934671   13657 fix.go:55] fixHost completed within 151.578µs
I0716 09:44:41.934683   13657 start.go:76] releasing machines lock for "mock-machine-0.94050909", held for 173.78µs
I0716 09:44:41.934793   13657 start.go:122] createHost starting for "minikube" (driver="mock")
W0716 09:44:41.935132   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:41.935196   13657 start.go:158] libmachine.API.Create for "" (driver="mock")
I0716 09:44:41.935285   13657 start.go:163] duration metric: libmachine.API.Create for "" took 88.248µs
I0716 09:44:41.935306   13657 start.go:204] post-start starting for "mock-machine-0.66456005" (driver="mock")
I0716 09:44:41.935317   13657 start.go:207] post-start completed in 263ns
I0716 09:44:41.935403   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.66456005/config.json ...
I0716 09:44:41.935464   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.66456005/config.json: {Name:mk16cfdead061ce8db178c65952dc7aaa56168c0 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:41.935565   13657 start.go:125] duration metric: createHost completed in 752.25µs
I0716 09:44:41.935595   13657 start.go:241] acquiring machines lock for mock-machine-0.66456005: {Name:mk9ef2a9d36ab9d0a1d972e835fc233057cd5583 Clock:{} Delay:500ms Timeout:15m0s Cancel:<nil>}
I0716 09:44:41.935632   13657 start.go:245] acquired machines lock for "mock-machine-0.66456005" in 23.903µs
I0716 09:44:41.935650   13657 start.go:89] Skipping create...Using existing machine configuration
I0716 09:44:41.935681   13657 fix.go:53] fixHost starting: mock-machine-0.66456005
I0716 09:44:41.935728   13657 fix.go:105] recreateIfNeeded on mock-machine-0.66456005: state=Error err=machine does not exist
I0716 09:44:41.935745   13657 fix.go:110] machineExists: false. err=machine does not exist
W0716 09:44:41.935855   13657 out.go:100] [unset outFile]: * mock "mock-machine-0.66456005" bare metal machine is missing, will recreate.
I0716 09:44:41.935883   13657 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.
I0716 09:44:41.935996   13657 stop.go:64] duration metric: stop complete within 98.081µs
W0716 09:44:41.936153   13657 out.go:100] [unset outFile]: * Deleting "mock-machine-0.66456005" in mock ...
W0716 09:44:41.936212   13657 delete.go:138] delete failed (probably ok) <nil>
I0716 09:44:41.936228   13657 fix.go:117] Sleeping 1 second for extra luck!
I0716 09:44:42.936340   13657 start.go:122] createHost starting for "mock-machine-0.66456005" (driver="mock")
W0716 09:44:42.936900   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:42.937015   13657 start.go:158] libmachine.API.Create for "mock-machine-0.66456005" (driver="mock")
I0716 09:44:42.937086   13657 start.go:163] duration metric: libmachine.API.Create for "mock-machine-0.66456005" took 71.781µs
I0716 09:44:42.937094   13657 start.go:204] post-start starting for "mock-machine-0.43771419" (driver="mock")
I0716 09:44:42.937100   13657 start.go:207] post-start completed in 197ns
I0716 09:44:42.937219   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.43771419/config.json ...
I0716 09:44:42.937297   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.43771419/config.json: {Name:mk1a6d34d14480ade4010d411dba512ca9779832 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:42.937427   13657 start.go:125] duration metric: createHost completed in 1.064948ms
W0716 09:44:42.937450   13657 fix.go:131] unexpected machine state, will restart: <nil>
I0716 09:44:42.937461   13657 fix.go:55] fixHost completed within 1.00178005s
I0716 09:44:42.937466   13657 start.go:76] releasing machines lock for "mock-machine-0.66456005", held for 1.001815945s
I0716 09:44:42.937487   13657 start.go:241] acquiring machines lock for mock-machine-0.43771419-mock-machine-0.43771419: {Name:mk9ef2a9d36ab9d0a1d972e835fc233057cd5583 Clock:{} Delay:500ms Timeout:15m0s Cancel:<nil>}
I0716 09:44:42.937525   13657 start.go:245] acquired machines lock for "mock-machine-0.43771419-mock-machine-0.43771419" in 29.213µs
I0716 09:44:42.937533   13657 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 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[]} &{Name:mock-machine-0.43771419 IP:127.0.0.1 Port:0 KubernetesVersion: ControlPlane:false Worker:false}
I0716 09:44:42.937609   13657 start.go:122] createHost starting for "mock-machine-0.43771419" (driver="mock")
W0716 09:44:42.938034   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:42.938109   13657 start.go:158] libmachine.API.Create for "mock-machine-0.43771419" (driver="mock")
I0716 09:44:42.938170   13657 start.go:163] duration metric: libmachine.API.Create for "mock-machine-0.43771419" took 60.549µs
I0716 09:44:42.938176   13657 start.go:204] post-start starting for "mock-machine-0.42463750" (driver="mock")
I0716 09:44:42.938181   13657 start.go:207] post-start completed in 211ns
I0716 09:44:42.938271   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.42463750/config.json ...
I0716 09:44:42.938344   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.42463750/config.json: {Name:mk385a6b0829516d5c694b3732e7797d3c310d6c Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:42.938445   13657 start.go:125] duration metric: createHost completed in 829.189µs
I0716 09:44:42.938452   13657 start.go:76] releasing machines lock for "mock-machine-0.43771419-mock-machine-0.43771419", held for 919.946µs
I0716 09:44:42.938531   13657 start.go:122] createHost starting for "minikube" (driver="mock")
W0716 09:44:42.939006   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:42.939104   13657 start.go:158] libmachine.API.Create for "" (driver="mock")
I0716 09:44:42.939201   13657 start.go:163] duration metric: libmachine.API.Create for "" took 96.214µs
I0716 09:44:42.939238   13657 start.go:204] post-start starting for "mock-machine-0.68682307" (driver="mock")
I0716 09:44:42.939251   13657 start.go:207] post-start completed in 198ns
I0716 09:44:42.939351   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.68682307/config.json ...
I0716 09:44:42.939439   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.68682307/config.json: {Name:mk8d267de796bbac6677a877e1454f983bbe953e Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:42.939592   13657 start.go:125] duration metric: createHost completed in 1.054953ms
I0716 09:44:42.939623   13657 start.go:241] acquiring machines lock for mock-machine-0.68682307: {Name:mk9ef2a9d36ab9d0a1d972e835fc233057cd5583 Clock:{} Delay:500ms Timeout:15m0s Cancel:<nil>}
I0716 09:44:42.939685   13657 start.go:245] acquired machines lock for "mock-machine-0.68682307" in 32.572µs
I0716 09:44:42.939705   13657 start.go:89] Skipping create...Using existing machine configuration
I0716 09:44:42.939732   13657 fix.go:53] fixHost starting: mock-machine-0.68682307
I0716 09:44:42.939786   13657 fix.go:105] recreateIfNeeded on mock-machine-0.68682307: state=Stopped err=<nil>
W0716 09:44:42.939842   13657 fix.go:131] unexpected machine state, will restart: <nil>
W0716 09:44:42.939975   13657 out.go:100] [unset outFile]: * Restarting existing mock bare metal machine for "mock-machine-0.68682307" ...
I0716 09:44:42.940091   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.68682307/config.json ...
I0716 09:44:42.940271   13657 fix.go:55] fixHost completed within 539.267µs
I0716 09:44:42.940305   13657 start.go:76] releasing machines lock for "mock-machine-0.68682307", held for 600.51µs
I0716 09:44:42.940406   13657 start.go:241] acquiring machines lock for : {Name:mk9ef2a9d36ab9d0a1d972e835fc233057cd5583 Clock:{} Delay:500ms Timeout:15m0s Cancel:<nil>}
I0716 09:44:42.940480   13657 start.go:245] acquired machines lock for "" in 51.045µs
I0716 09:44:42.940494   13657 start.go:85] Provisioning new machine with config: &{Name: KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage: Memory:0 CPUs:0 DiskSize:0 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[]} &{Name:minikube IP: Port:0 KubernetesVersion: ControlPlane:false Worker:false}
I0716 09:44:42.940559   13657 start.go:122] createHost starting for "minikube" (driver="mock")
W0716 09:44:42.940929   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:42.941001   13657 start.go:158] libmachine.API.Create for "" (driver="mock")
I0716 09:44:42.941083   13657 start.go:163] duration metric: libmachine.API.Create for "" took 81.756µs
I0716 09:44:42.941101   13657 start.go:204] post-start starting for "mock-machine-0.06563702" (driver="mock")
I0716 09:44:42.941112   13657 start.go:207] post-start completed in 131ns
I0716 09:44:42.941210   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.06563702/config.json ...
I0716 09:44:42.941288   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.06563702/config.json: {Name:mk805b6e4f78b4c73bbd7b3574c7bc5276852c7e Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:42.941435   13657 start.go:125] duration metric: createHost completed in 852.88µs
I0716 09:44:42.941456   13657 start.go:76] releasing machines lock for "", held for 961.576µs
I0716 09:44:42.941548   13657 start.go:241] acquiring machines lock for -minikube: {Name:mk9ef2a9d36ab9d0a1d972e835fc233057cd5583 Clock:{} Delay:500ms Timeout:15m0s Cancel:<nil>}
I0716 09:44:42.941618   13657 start.go:245] acquired machines lock for "-minikube" in 31.997µs
I0716 09:44:42.941633   13657 start.go:85] Provisioning new machine with config: &{Name: KeepContext:false EmbedCerts:false MinikubeISO: KicBaseImage: Memory:0 CPUs:0 DiskSize:0 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[]} &{Name:minikube IP: Port:0 KubernetesVersion: ControlPlane:false Worker:false}
I0716 09:44:42.941761   13657 start.go:122] createHost starting for "minikube" (driver="mock")
W0716 09:44:42.942170   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:42.942252   13657 start.go:158] libmachine.API.Create for "" (driver="mock")
I0716 09:44:42.942315   13657 start.go:163] duration metric: libmachine.API.Create for "" took 63.201µs
I0716 09:44:42.942351   13657 start.go:204] post-start starting for "mock-machine-0.15651925" (driver="mock")
I0716 09:44:42.942365   13657 start.go:207] post-start completed in 137ns
I0716 09:44:42.942457   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.15651925/config.json ...
I0716 09:44:42.942538   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.15651925/config.json: {Name:mk2cec12cd9daef835f2bf62b4e8047d8fe52a05 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:42.942694   13657 start.go:125] duration metric: createHost completed in 918.774µs
I0716 09:44:42.942717   13657 start.go:76] releasing machines lock for "-minikube", held for 1.084732ms
I0716 09:44:42.942780   13657 stop.go:36] StopHost: mock-machine-0.15651925
I0716 09:44:42.942882   13657 start.go:122] createHost starting for "minikube" (driver="mock")
W0716 09:44:42.943298   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:42.943360   13657 start.go:158] libmachine.API.Create for "" (driver="mock")
I0716 09:44:42.943426   13657 start.go:163] duration metric: libmachine.API.Create for "" took 65.326µs
I0716 09:44:42.943456   13657 start.go:204] post-start starting for "mock-machine-0.09696952" (driver="mock")
I0716 09:44:42.943469   13657 start.go:207] post-start completed in 125ns
I0716 09:44:42.943544   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.09696952/config.json ...
I0716 09:44:42.943614   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.09696952/config.json: {Name:mkb75fb02053c55d116cdf9ac7e39d08c42bcdf2 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:42.943729   13657 start.go:125] duration metric: createHost completed in 812.649µs
I0716 09:44:42.943752   13657 stop.go:36] StopHost: mock-machine-0.09696952
W0716 09:44:42.944002   13657 out.go:100] [unset outFile]: * Stopping "mock-machine-0.09696952" in mock ...
Stopping "mock-machine-0.09696952"...
Machine "mock-machine-0.09696952" was stopped.
I0716 09:44:42.944084   13657 stop.go:64] duration metric: stop complete within 56.386µs
I0716 09:44:42.944151   13657 start.go:122] createHost starting for "minikube" (driver="mock")
W0716 09:44:42.945354   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:42.945416   13657 start.go:158] libmachine.API.Create for "" (driver="mock")
I0716 09:44:42.945480   13657 start.go:163] duration metric: libmachine.API.Create for "" took 63.671µs
I0716 09:44:42.945511   13657 start.go:204] post-start starting for "mock-machine-0.30091186" (driver="mock")
I0716 09:44:42.945522   13657 start.go:207] post-start completed in 124ns
I0716 09:44:42.945590   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.30091186/config.json ...
I0716 09:44:42.945654   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.30091186/config.json: {Name:mk22eeafe1cd05a07370cccc5b468b06a07802f0 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:42.945769   13657 start.go:125] duration metric: createHost completed in 1.590873ms
W0716 09:44:42.947862   13657 out.go:100] [unset outFile]: * Deleting "mock-machine-0.30091186" in mock ...
I0716 09:44:42.947947   13657 start.go:122] createHost starting for "minikube" (driver="mock")
W0716 09:44:42.948320   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:42.948387   13657 start.go:158] libmachine.API.Create for "" (driver="mock")
I0716 09:44:42.948453   13657 start.go:163] duration metric: libmachine.API.Create for "" took 65.992µs
I0716 09:44:42.948483   13657 start.go:204] post-start starting for "mock-machine-0.51521263" (driver="mock")
I0716 09:44:42.948496   13657 start.go:207] post-start completed in 124ns
I0716 09:44:42.948571   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.51521263/config.json ...
I0716 09:44:42.948638   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.51521263/config.json: {Name:mk0512b6107a9f6197972253ad8395c30216c06a Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:42.948754   13657 start.go:125] duration metric: createHost completed in 772.802µs
I0716 09:44:42.948830   13657 start.go:122] createHost starting for "minikube" (driver="mock")
W0716 09:44:42.949162   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:42.949223   13657 start.go:158] libmachine.API.Create for "" (driver="mock")
I0716 09:44:42.949286   13657 start.go:163] duration metric: libmachine.API.Create for "" took 62.61µs
I0716 09:44:42.949318   13657 start.go:204] post-start starting for "mock-machine-0.81363996" (driver="mock")
I0716 09:44:42.949330   13657 start.go:207] post-start completed in 120ns
I0716 09:44:42.949408   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.81363996/config.json ...
I0716 09:44:42.949473   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.81363996/config.json: {Name:mkbcb757219c8471328cfa1fc5c68f029939f8a0 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:42.949587   13657 start.go:125] duration metric: createHost completed in 742.551µs
I0716 09:44:42.949653   13657 start.go:122] createHost starting for "minikube" (driver="mock")
W0716 09:44:42.952914   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:42.952993   13657 start.go:158] libmachine.API.Create for "" (driver="mock")
I0716 09:44:42.953079   13657 start.go:163] duration metric: libmachine.API.Create for "" took 71.665µs
I0716 09:44:42.953098   13657 start.go:204] post-start starting for "mock-machine-0.21426387" (driver="mock")
I0716 09:44:42.953110   13657 start.go:207] post-start completed in 118ns
I0716 09:44:42.953203   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.21426387/config.json ...
I0716 09:44:42.953266   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.21426387/config.json: {Name:mk97769264695896ab7c24061aae9526eb7afc83 Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:42.953373   13657 start.go:125] duration metric: createHost completed in 3.706873ms
I0716 09:44:42.953455   13657 start.go:122] createHost starting for "minikube" (driver="mock")
W0716 09:44:42.953770   13657 out.go:100] [unset outFile]: * Running on localhost (CPUs=2, Memory=7976MB, Disk=69422MB) ...
I0716 09:44:42.953834   13657 start.go:158] libmachine.API.Create for "mock-machine-0.21426387" (driver="mock")
I0716 09:44:42.953903   13657 start.go:163] duration metric: libmachine.API.Create for "mock-machine-0.21426387" took 69.626µs
I0716 09:44:42.953942   13657 start.go:204] post-start starting for "mock-machine-0.38065719" (driver="mock")
I0716 09:44:42.953955   13657 start.go:207] post-start completed in 126ns
I0716 09:44:42.954027   13657 profile.go:150] Saving config to /tmp/minipath302362522/.minikube/profiles/mock-machine-0.38065719/config.json ...
I0716 09:44:42.954097   13657 lock.go:35] WriteFile acquiring /tmp/minipath302362522/.minikube/profiles/mock-machine-0.38065719/config.json: {Name:mk57cbabf1803e63efe126955ee1ef5716ca560a Clock:{} Delay:500ms Timeout:1m0s Cancel:<nil>}
I0716 09:44:42.954209   13657 start.go:125] duration metric: createHost completed in 730.69µs
I0716 09:44:42.954258   13657 stop.go:36] StopHost: mock-machine-0.38065719
W0716 09:44:42.954358   13657 out.go:100] [unset outFile]: * Stopping "mock-machine-0.38065719" in mock ...
Stopping "mock-machine-0.38065719"...
Machine "mock-machine-0.38065719" was stopped.
I0716 09:44:42.954435   13657 stop.go:64] duration metric: stop complete within 57.043µs
I0716 09:44:43.094984   13657 host.go:65] Checking if "mock-machine-0.38065719" exists ...
Using SSH client type: native
&{{{<nil> 0 [] [] []} root [] 0xbe4f20 <nil>  [] 0s} localhost 43391 <nil> <nil>}
--- FAIL: TestCreateSSHShell (0.15s)
    ssh_mock.go:193: Listening on 127.0.0.1:43391
    ssh_mock.go:83: Serving ...
    ssh_mock.go:87: [loop 1] Accepting for &{0xc0001a01a0 map[] false  0 {map[]} false 0xc000187320 0xc000192240}...
    api_mock.go:62: MockAPI.Load: &{ConfigVersion:0 Driver:0xc0004fef20 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 &{0xc0001a01a0 map[] false  0 {map[]} false 0xc000187320 0xc000192240}...
    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:0xc0003f35c0 mux:<nil>}
    ssh_mock.go:149: exec request received: &{Type:exec WantReply:true Payload:[0 0 0 4 101 120 105 116] ch:0xc0003f35c0 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
I0716 09:44:43.100068   13657 fix.go:209] guest clock: 1594892683.0000
I0716 09:44:43.100097   13657 fix.go:222] Guest: 2020-07-16 09:44:43 +0000 UTC Remote: 2020-07-16 09:44:43.100049451 +0000 UTC m=+1.215392154 (delta=-100.049451ms)
I0716 09:44:43.100487   13657 filesync.go:118] Scanning /tmp/minipath412795868/.minikube/addons for local assets ...
I0716 09:44:43.100585   13657 filesync.go:141] local asset: /tmp/minipath412795868/.minikube/addons/dir1/file1.txt -> file1.txt in /etc/kubernetes/addons
I0716 09:44:43.100618   13657 filesync.go:141] local asset: /tmp/minipath412795868/.minikube/addons/dir1/file2.txt -> file2.txt in /etc/kubernetes/addons
I0716 09:44:43.100663   13657 filesync.go:141] local asset: /tmp/minipath412795868/.minikube/addons/dir2/file1.txt -> file1.txt in /etc/kubernetes/addons
I0716 09:44:43.101243   13657 filesync.go:118] Scanning /tmp/minipath396607371/.minikube/files for local assets ...
I0716 09:44:43.101319   13657 filesync.go:141] local asset: /tmp/minipath396607371/.minikube/files/dir1/file1.txt -> file1.txt in /dir1
I0716 09:44:43.101346   13657 filesync.go:141] local asset: /tmp/minipath396607371/.minikube/files/dir1/file2.txt -> file2.txt in /dir1
I0716 09:44:43.101387   13657 filesync.go:141] local asset: /tmp/minipath396607371/.minikube/files/dir2/file1.txt -> file1.txt in /dir2
FAIL
coverage: 37.6% of statements
FAIL	k8s.io/minikube/pkg/minikube/machine	1.223s
ok  	k8s.io/minikube/pkg/minikube/notify	0.014s	coverage: 92.9% of statements
ok  	k8s.io/minikube/pkg/minikube/out	0.054s	coverage: 64.8% of statements
ok  	k8s.io/minikube/pkg/minikube/out/register	0.009s	coverage: 62.2% of statements
ok  	k8s.io/minikube/pkg/minikube/perf	4.251s	coverage: 33.8% of statements
ok  	k8s.io/minikube/pkg/minikube/problem	0.011s	coverage: 91.7% of statements
ok  	k8s.io/minikube/pkg/minikube/proxy	0.008s	coverage: 77.0% of statements
ok  	k8s.io/minikube/pkg/minikube/registry	0.008s	coverage: 75.5% of statements
ok  	k8s.io/minikube/pkg/minikube/service	0.087s	coverage: 84.2% of statements
ok  	k8s.io/minikube/pkg/minikube/shell	0.002s	coverage: 97.1% of statements
ok  	k8s.io/minikube/pkg/minikube/storageclass	0.037s	coverage: 100.0% of statements
ok  	k8s.io/minikube/pkg/minikube/sysinit	0.032s	coverage: 6.5% of statements
ok  	k8s.io/minikube/pkg/minikube/translate	0.012s	coverage: 10.5% of statements
ok  	k8s.io/minikube/pkg/minikube/tunnel	1.623s	coverage: 64.2% of statements
ok  	k8s.io/minikube/pkg/util	0.603s	coverage: 77.9% of statements
ok  	k8s.io/minikube/pkg/util/lock	0.007s	coverage: 18.2% of statements
ok  	k8s.io/minikube/pkg/util/retry	0.008s	coverage: 0.0% of statements
FAIL
Makefile:274: recipe for target 'test' failed
make: *** [test] Error 32
TravisBuddy Request Identifier: 35dbf8d0-c749-11ea-999c-bf5bae978323

@11janci 11janci force-pushed the jjanik-fix-validateTunnelCmd branch from eb79da3 to f4d2cdb Compare July 16, 2020 10:50
@k8s-ci-robot k8s-ci-robot added size/S Denotes a PR that changes 10-29 lines, ignoring generated files. and removed size/M Denotes a PR that changes 30-99 lines, ignoring generated files. labels Jul 16, 2020
@11janci 11janci force-pushed the jjanik-fix-validateTunnelCmd branch from f4d2cdb to 21b9eea Compare July 16, 2020 11:21
@11janci
Copy link
Contributor Author

11janci commented Jul 16, 2020

@medyagh I'm afraid I'm gonna need some help with this.

For docker_windows the fix helps and the service gets an IP, however it is set to loopback, which is quite strange, however that's an issue of minikube tunnel and/or the machine it runs on:
tmp
The AccessDirect test then fails on Temporary Error: Get http://127.0.0.1: dial tcp 127.0.0.1:80: connectex: No connection could be made because the target machine actively refused it. Any ideas? I don't have access and don't know anything about the underlying infrastructure so it's difficult for me to debug this (the test runs successfully on my windows with virtualbox driver).

For hyperv_windows it's even weirder. Apparently it looks like that in the very first run the IP was successfully obtained, however was set to the same one as the cluster IP:
tmp2
So I went and enabled the IngressIP step which was previously disabled for HyperV, however since then all the subsequent runs failed to obtain the IP (even if I disabled it again, which anyway shouldn't matter):
tmp3
That could hint towards a failed cleanup of the minikube tunnel process, however again I'm not able to verify that and on my local I'm able to run the test repeatedly.

Any help would be welcome!

@11janci 11janci force-pushed the jjanik-fix-validateTunnelCmd branch from 21b9eea to 8f37f21 Compare August 19, 2020 12:06
@k8s-ci-robot k8s-ci-robot added size/M Denotes a PR that changes 30-99 lines, ignoring generated files. and removed size/S Denotes a PR that changes 10-29 lines, ignoring generated files. labels Aug 19, 2020
@11janci 11janci force-pushed the jjanik-fix-validateTunnelCmd branch from 86c5b30 to bb681f0 Compare August 31, 2020 18:44
@tstromberg
Copy link
Contributor

@medyagh I'm afraid I'm gonna need some help with this.

For docker_windows the fix helps and the service gets an IP, however it is set to loopback, which is quite strange, however that's an issue of minikube tunnel and/or the machine it runs on:
tmp
The AccessDirect test then fails on Temporary Error: Get http://127.0.0.1: dial tcp 127.0.0.1:80: connectex: No connection could be made because the target machine actively refused it. Any ideas? I don't have access and don't know anything about the underlying infrastructure so it's difficult for me to debug this (the test runs successfully on my windows with virtualbox driver).

This is expected -- the Docker driver uses the loopback interface. I wouldn't expect it to be on port 80, however.

For hyperv_windows it's even weirder. Apparently it looks like that in the very first run the IP was successfully obtained, however was set to the same one as the cluster IP:
tmp2
So I went and enabled the IngressIP step which was previously disabled for HyperV, however since then all the subsequent runs failed to obtain the IP (even if I disabled it again, which anyway shouldn't matter):
tmp3
That could hint towards a failed cleanup of the minikube tunnel process, however again I'm not able to verify that and on my local I'm able to run the test repeatedly.

Any help would be welcome!

if runtime.GOOS == "windows" {
t.Skip("skipping: access direct test is broken on windows: https://github.com/kubernetes/minikube/issues/8304")
}
//if runtime.GOOS == "windows" {
Copy link
Contributor

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

This should continue to be disabled for the Docker driver.

@@ -435,7 +435,9 @@ jobs:
$env:MINIKUBE_HOME="${pwd}\testhome"
.\minikube-windows-amd64.exe delete --all --purge
Get-VM | Where-Object {$_.Name -ne "DockerDesktopVM"} | Foreach {
Stop-VM -Name $_.Name -Force
.\minikube-windows-amd64.exe delete -p $_.Name
Suspend-VM $_.Name
Copy link
Contributor

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

I don't think the Suspend-VM/Stop-VM call should be required.

@tstromberg
Copy link
Contributor

Closing this stale PR - please reopen when you have a chance to address the PR comments.

@tstromberg tstromberg closed this Dec 16, 2020
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
cncf-cla: yes Indicates the PR's author has signed the CNCF CLA. do-not-merge/work-in-progress Indicates that a PR should not merge because it is a work in progress. needs-ok-to-test Indicates a PR that requires an org member to verify it is safe to test. size/M Denotes a PR that changes 30-99 lines, ignoring generated files.
Projects
None yet
Development

Successfully merging this pull request may close these issues.

Fix Tunnel AccessDirect test on windows
7 participants