kn service create with `--log-http` is pending for a long time

Open

#485 opened on Nov 6, 2019 16 comments 0 reactions 0 assignees View on GitHub

kind/bug triage/accepted

Dominant language: Go
Stars: 387
Forks: 274
Avg merge: 1d 6h
Merged PRs (30d): 8

Description

## What version of Knative Client?

$ ./kn version
Version: v20191106-local-f1f6cfb-dirty
Build Date: 2019-11-06 06:51:12
Git Revision: f1f6cfb
Supported APIs:
- serving.knative.dev/v1alpha1 (knative-serving v0.10.0)

## What version of Knative Serving running on your cluster?

I don't know

## Expected Behavior

`./kn service create --image docker.io/ibmcom/fib-knative fib-knative --log-http` could response correctly and print http logs to standard output.

## Actual Behavior

`./kn service create --image docker.io/ibmcom/fib-knative fib-knative --log-http` keeps pending for 10 minutes and response a timeout error. But, `./kn service create --image docker.io/ibmcom/fib-knative fib-knative` could response correctly.

## Steps to Reproduce the Problem

Call `./kn service create --image docker.io/ibmcom/fib-knative fib-knative --log-http`, it keeps pending and response timeout after 10 minutes.

```
$ ./kn service create --image docker.io/ibmcom/fib-knative fib-knative --log-http
===== REQUEST =====
GET /apis/serving.knative.dev/v1alpha1/namespaces/default/services/fib-knative HTTP/1.1
Host: c1.au-syd.containers.cloud.ibm.com:25013
User-Agent: kn/v0.0.0 (darwin/amd64) kubernetes/$Format
Accept: application/json, */*
Authorization: (REDACTED FOR PRIVACY)
Accept-Encoding: gzip

===== RESPONSE =====
HTTP/2.0 404 Not Found
Content-Length: 248
Content-Type: application/json
Date: Wed, 06 Nov 2019 06:59:12 GMT

{"kind":"Status","apiVersion":"v1","metadata":{},"status":"Failure","message":"services.serving.knative.dev \"fib-knative\" not found","reason":"NotFound","details":{"name":"fib-knative","group":"serving.knative.dev","kind":"services"},"code":404}

* * * * * *
===== REQUEST =====
POST /apis/serving.knative.dev/v1alpha1/namespaces/default/services HTTP/1.1
Host: c1.au-syd.containers.cloud.ibm.com:25013
User-Agent: kn/v0.0.0 (darwin/amd64) kubernetes/$Format
Content-Length: 414
Accept: application/json, */*
Authorization: (REDACTED FOR PRIVACY)
Content-Type: application/json
Accept-Encoding: gzip

{"kind":"Service","apiVersion":"serving.knative.dev/v1alpha1","metadata":{"name":"fib-knative","namespace":"default","creationTimestamp":null},"spec":{"template":{"metadata":{"name":"fib-knative-qmnll-1","creationTimestamp":null,"annotations":{"client.knative.dev/user-image":"docker.io/ibmcom/fib-knative"}},"spec":{"containers":[{"name":"","image":"docker.io/ibmcom/fib-knative","resources":{}}]}}},"status":{}}

===== RESPONSE =====
HTTP/2.0 201 Created
Content-Length: 876
Content-Type: application/json
Date: Wed, 06 Nov 2019 06:59:13 GMT

{"apiVersion":"serving.knative.dev/v1alpha1","kind":"Service","metadata":{"annotations":{"serving.knative.dev/creator":"IAM#guoyingc@cn.ibm.com","serving.knative.dev/lastModifier":"IAM#guoyingc@cn.ibm.com"},"creationTimestamp":"2019-11-06T06:59:13Z","generation":1,"name":"fib-knative","namespace":"default","resourceVersion":"306348","selfLink":"/apis/serving.knative.dev/v1alpha1/namespaces/default/services/fib-knative","uid":"f088b9fc-0062-11ea-b240-8217ff1c40ee"},"spec":{"template":{"metadata":{"annotations":{"client.knative.dev/user-image":"docker.io/ibmcom/fib-knative"},"creationTimestamp":null,"name":"fib-knative-qmnll-1"},"spec":{"containers":[{"image":"docker.io/ibmcom/fib-knative","name":"user-container","readinessProbe":{"successThreshold":1,"tcpSocket":{"port":0}},"resources":{}}],"timeoutSeconds":300}},"traffic":[{"latestRevision":true,"percent":100}]}}

* * * * * *
Creating service 'fib-knative' in namespace 'default':

===== REQUEST =====
GET /apis/serving.knative.dev/v1alpha1/namespaces/default/services?fieldSelector=metadata.name%3Dfib-knative&timeout=10m30s&timeoutSeconds=630&watch=true HTTP/1.1
Host: c1.au-syd.containers.cloud.ibm.com:25013
User-Agent: kn/v0.0.0 (darwin/amd64) kubernetes/$Format
Accept: application/json, */*
Authorization: (REDACTED FOR PRIVACY)
Accept-Encoding: gzip

^@^@^@^@^@^@^@^@^@===== RESPONSE =====
HTTP/2.0 200 OK
Connection: close
Content-Type: application/json
Date: Wed, 06 Nov 2019 06:59:13 GMT

* * * * * *
630.224s Configuration "fib-knative" is waiting for a Revision to become ready.
630.224s ...
630.225s Ingress has not yet been reconciled.
630.225s Waiting for VirtualService to be ready
timeout: service 'fib-knative' not ready after 600 seconds
```

The output of `./kn service create --image docker.io/ibmcom/fib-knative fib-knative` looks as below:
```
$ ./kn service create --image docker.io/ibmcom/fib-knative fib-knative
Creating service 'fib-knative' in namespace 'default':

0.189s Configuration "fib-knative" is waiting for a Revision to become ready.
15.955s ...
16.031s Ingress has not yet been reconciled.
16.091s Waiting for VirtualService to be ready
17.407s Ready to serve.

Service 'fib-knative' created with latest revision 'fib-knative-fzkzc-1' and URL:
http://fib-knative-default.159.23.66.2.xip.io
```

Contributor guide

Open the contributing guide

Research direction

Reproduce the reported `kn service create` command with and without `--log-http`, comparing the HTTP watch request and response shown in the issue. Trace the `service create` command and its HTTP logging path; done means creation completes without a timeout while HTTP request and response logs are still printed.

Written by the indexing model from the issue text.

Assessment

Tech stack: go, kubernetes
Domain: api, cli
Issue type: Bug
Difficulty: 4/5
Estimated time: 3-5 days
Activity status: Stale
Clarity: Mostly clear
Newbie friendliness: 42/100

kn service create with `--log-http` is pending for a long time

Description

Contributor guide

Research direction

Assessment

Get new issues in your inbox