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

post-deployment hook not finishing #11069

Closed
jorgemoralespou opened this issue Sep 23, 2016 · 35 comments
Closed

post-deployment hook not finishing #11069

jorgemoralespou opened this issue Sep 23, 2016 · 35 comments

Comments

@jorgemoralespou
Copy link

jorgemoralespou commented Sep 23, 2016

I'm using origin 1.3.0 GA, and I have an application deployment that has a post deployment hook. It used to finish immediately, but now, with this release it's stuck and seems to never finish. I could think that the hook is failing and retrying, but it's configure to Ignore, and I don't even see traces of retries or errors.
It's been there for a very long time (I would say even more than the configured 10 m).
I'm using "oc cluster up", and the post-deployment hook just does a curl to an endpoint in the main service. I see the traces in the main application of the endpoint being called, but the trigger I don't see anything.

How could I raise the logs level? I have added LOGLEVEL=10 to the main deployment as well as the hook.

Version

Server https://127.0.0.1:8443
openshift v1.3.0
kubernetes v1.3.0+52492b4

Steps To Reproduce

oc cluster up --version=v1.3.0
oc new-project roadshow
oc policy add-role-to-user view system:serviceaccount:roadshow:default
oc create -f https://raw.githubusercontent.com/openshift-roadshow/nationalparks/master/ose3/application-template.json
oc new-app nationalparks

Wait for build to finish and deployment. Once last stack traces are shown in the console:

[INFO] load()
[DEBUG] MongoDBConnection.loadParks()
[DEBUG] MongoDBConnection.loadParks(InputStream)
[DEBUG] MongoDBConnection.init(...)

the post deployment hook should have been executed.

Current Result

Deployment never finishes, and status for the deployment is Running instead of Active.

Expected Result

Deployment finishes, and status is Active.

@jorgemoralespou
Copy link
Author

cc/ @smarterclayton

@0xmichalis
Copy link
Contributor

0xmichalis commented Sep 23, 2016

I am not able to reproduce.

$ oc logs -f dc/nationalparks
--> Scaling nationalparks-1 to 1
--> Waiting up to 10m0s for pods in deployment nationalparks-1 to become ready
--> post: Running hook pod ...
--> post: Success
--> Success

My server and deployer images are both v1.3.0

$ docker images | grep deployer
docker.io/openshift/origin-deployer          v1.3.0              efce6fac8b74        7 days ago          487.1 MB
$ oc version
oc v1.4.0-alpha.0+685c6b8-dirty
kubernetes v1.4.0-beta.3+d19513f
features: Basic-Auth

Server https://10.0.2.15:8443
openshift v1.3.0
kubernetes v1.3.0+52492b4

@mfojtik can you have a look as well?
@jorgemoralespou unrelated to the issue you are seeing but I wasn't able to use your current setup with respect to puting the DockerImage in its own ImageStreamTag - I had to use the DockerImage directly in the build config otherwise I couldn't build

$ oc logs -f bc/nationalparks
error: build error: unable to get jorgemoralespou/s2i-java@sha256:bd7903c84ea2bd05a01b7d0515f8262dfac9dd26ad36d870f0154edb06ed2f70

@0xmichalis
Copy link
Contributor

May it be that you need to update your deployer image? I am going to test with v1.2.0 shortly.

@0xmichalis
Copy link
Contributor

@csrwng is oc cluster up enforcing the use of the same version as the server for all components?

@0xmichalis
Copy link
Contributor

0xmichalis commented Sep 23, 2016

Ok, tricked oc cluster up and used a v1.2.0 deployer image.

Still I cannot reproduce this issue.

$ oc logs -f dc/nationalparks
I0923 15:19:22.599431       1 deployer.go:202] Deploying from roadshow/nationalparks-2 to roadshow/nationalparks-3 (replicas: 1)
I0923 15:19:23.634551       1 rolling.go:228] RollingUpdater: Continuing update with existing controller nationalparks-3.
I0923 15:19:23.648080       1 rolling.go:228] RollingUpdater: Scaling up nationalparks-3 from 0 to 1, scaling down nationalparks-2 from 1 to 0 (keep 1 pods available, don't exceed 2 pods)
I0923 15:19:23.648111       1 rolling.go:228] RollingUpdater: Scaling nationalparks-3 up to 1
I0923 15:19:55.044167       1 rolling.go:228] RollingUpdater: Scaling nationalparks-2 down to 0
I0923 15:19:58.141409       1 lifecycle.go:168] Created lifecycle pod roadshow/nationalparks-3-hook-post for deployment roadshow/nationalparks-3
I0923 15:19:58.142424       1 lifecycle.go:181] Watching logs for hook pod roadshow/nationalparks-3-hook-post while awaiting completion
I0923 15:20:02.269599       1 lifecycle.go:221] Finished reading logs for hook pod roadshow/nationalparks-3-hook-post
I0923 15:20:02.386408       1 rolling.go:219] Post hook finished

@jorgemoralespou
Copy link
Author

@Kargakis :-(
Not sure what would be the problem then.

I have deployed this some hours back, and if I tail the logs, like you did, I see:

[jmorales@mac-santander origin-labs] $oc logs -f dc/nationalparks
--> Scaling nationalparks-2 to 1
--> Waiting up to 10m0s for pods in deployment nationalparks-2 to become ready
--> post: Running hook pod ...

and I can see the pod still running, and even I can exec into it, but don't know what/how to troubleshoot.

I'm using docker native and not docker-machine and I have config and data mounted to the host. Other than that don't know what else to say.

@jorgemoralespou
Copy link
Author

$docker images | grep origin
openshift/origin-sti-builder                             v1.3.0              1d97be2a77ff        7 days ago          487.1 MB
openshift/origin-deployer                                v1.3.0              5bf464732ca8        7 days ago          487.1 MB
openshift/origin-docker-registry                         v1.3.0              59d447094a3c        7 days ago          345.5 MB
openshift/origin-haproxy-router                          v1.3.0              e33d4e33dffb        7 days ago          506.2 MB
openshift/origin                                         v1.3.0              7b24611e640f        7 days ago          487.1 MB
openshift/origin-pod                                     v1.3.0              35873f68181d        7 days ago          1.591 MB
openshift/origin-metrics-heapster                        v1.3.0              3f80c00bdd32        7 days ago          994.8 MB
openshift/origin-metrics-deployer                        v1.3.0              e261ae18fca5        7 days ago          647.2 MB
openshift/origin-metrics-cassandra                       v1.3.0              2a389f0b9ea8        7 days ago          665.6 MB
openshift/origin-metrics-hawkular-metrics                v1.3.0              cc940d946002        7 days ago          961.7 MB


$docker ps | grep deployer
16f571088cd8        openshift/origin-deployer:v1.3.0                                                                                         "/usr/bin/openshift-d"   3 hours ago         Up 3 hours                                                                             k8s_deployment.120ec0ae_mlbparks-2-deploy_nationalparks_ae1ba734-8188-11e6-8fa7-1e1e5ab82594_78eaab0a
27775d61a4bf        openshift/origin-deployer:v1.3.0                                                                                         "/usr/bin/openshift-d"   4 hours ago         Up 4 hours                                                                             k8s_deployment.2d4ac2c9_nationalparks-2-deploy_nationalparks_8f77d17d-8180-11e6-8fa7-1e1e5ab82594_cfa38252

@csrwng
Copy link
Contributor

csrwng commented Sep 23, 2016

@Kargakis by default it'll use the same version for everything, but if you specify --version=latest then you could get mismatched images

@jorgemoralespou
Copy link
Author

@Kargakis I'll summarise what I've found (with your help through irc).

I have diagnosed that I could curl from within the hook pod by changing the command to sleep and then rshing into the container and doing the curl. It worked.

nationalparks-7-hook-post 0/1 Pending 0 0s
nationalparks-7-hook-post 0/1 Pending 0 0s
nationalparks-7-hook-post 0/1 ContainerCreating 0 0s
nationalparks-7-hook-post 1/1 Running 0 2s
^C[jmorales@mac-santander origin-labs] $oc rsh nationalparks-7-hook-post
sh-4.2$ curl http://nationalparks:8080/ws/data/load
Items inserted in database: 2740sh-4.2$

I changed it back to the curl -s http://nationalparks:8080/data/ws/load and it failed.

[jmorales@mac-santander origin-labs] $oc logs -f dc/nationalparks
--> Scaling up nationalparks-10 from 0 to 1, scaling down nationalparks-9 from 1 to 0 (keep 1 pods available, don't exceed 2 pods)
    Scaling nationalparks-10 up to 1
    Scaling nationalparks-9 down to 0
--> post: Running hook pod ...

And ctrol+c after 1 minute:

But I see the pods:

[jmorales@mac-santander origin-labs] $oc get pods -w
NAME                         READY     STATUS      RESTARTS   AGE
nationalparks-10-70zcr       1/1       Running     0          1m
nationalparks-10-deploy      1/1       Running     0          1m
nationalparks-10-hook-post   0/1       Completed   0          1m

If I change the hook from "curl -s" to curl -v:

oc deploy nationalparks --latest
error: #10 is already in progress (Running).
Optionally, you can cancel this deployment using the --cancel option.

I need to cancel the previous, as it didn't finished:

[jmorales@mac-santander origin-labs] $oc deploy nationalparks --cancel
Cancelled deployment #10
[jmorales@mac-santander origin-labs] $oc deploy nationalparks --latest
Started deployment #11
Use 'oc logs -f dc/nationalparks' to track its progress.
[jmorales@mac-santander origin-labs] $oc logs -f dc/nationalparks
--> Scaling up nationalparks-11 from 0 to 1, scaling down nationalparks-9 from 1 to 0 (keep 1 pods available, don't exceed 2 pods)
    Scaling nationalparks-11 up to 1
    Scaling nationalparks-9 down to 0
--> post: Running hook pod ...
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0* About to connect() to nationalparks port 8080 (#0)
*   Trying 172.30.92.255...
* Connected to nationalparks (172.30.92.255) port 8080 (#0)
> GET /ws/data/load HTTP/1.1
> User-Agent: curl/7.29.0
> Host: nationalparks:8080
> Accept: */*
>
< HTTP/1.1 200
< X-Application-Context: nationalparks:kubernetes
< Content-Type: application/json;charset=UTF-8
< Content-Length: 32
< Date: Fri, 23 Sep 2016 16:25:51 GMT
<
{ [data not shown]
100    32  100    32    0     0     37      0 --:--:-- --:--:-- --:--:--    37
* Connection #0 to host nationalparks left intact
Items inserted in database: 2740
--> post: Success
--> Success

oc get pods -w
NAME                       READY     STATUS      RESTARTS   AGE
nationalparks-11-g7q1q     1/1       Running     0          1m

And now I can not see the hook pod with this command.

How can I raise loglevel of the deployment config?

@csrwng
Copy link
Contributor

csrwng commented Sep 23, 2016

@jorgemoralespou You can raise the loglevel of your deployment by specifying a command in the strategy.customParams (your strategy doesn't need to be of type custom). Here's a snippet from a test strategy:

spec:
  replicas: 1
  selector:
    app: simple-ruby
    deploymentconfig: simple-ruby
  strategy:
    customParams:
      command:
      - openshift-deploy
      - --loglevel=10
    resources: {}
    rollingParams:
      intervalSeconds: 1
      maxSurge: 25%
      maxUnavailable: 25%
      timeoutSeconds: 600
      updatePeriodSeconds: 1
    type: Rolling
  template:
  .... blah blah

@jorgemoralespou
Copy link
Author

Very easy and intuitive. Is this documented anywhere?

El 23 sept. 2016 20:55, "Cesar Wong" notifications@github.com escribió:

@jorgemoralespou https://github.com/jorgemoralespou You can raise the
loglevel of your deployment by specifying a command in the
strategy.customParams (your strategy doesn't need to be of type custom).
Here's a snippet from a test strategy:

spec:
replicas: 1
selector:
app: simple-ruby
deploymentconfig: simple-ruby
strategy:
customParams:
command:
- openshift-deploy
- --loglevel=10
resources: {}
rollingParams:
intervalSeconds: 1
maxSurge: 25%
maxUnavailable: 25%
timeoutSeconds: 600
updatePeriodSeconds: 1
type: Rolling
template:
.... blah blah


You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
#11069 (comment),
or mute the thread
https://github.com/notifications/unsubscribe-auth/AAEyDqATdDeTIQhNAdOVodaSmX7tseu_ks5qtCCjgaJpZM4KE4ny
.

@csrwng
Copy link
Contributor

csrwng commented Sep 23, 2016

I didn't see it documented anywhere. @Kargakis ?

@0xmichalis
Copy link
Contributor

I don't think we have docs for @smarterclayton's latest changes in custom deployments. Can you open an issue in openshift-docs? I may tackle it as part of openshift/openshift-docs#2890.

Regarding the behavior you see

[jmorales@mac-santander origin-labs] $oc get pods -w
NAME                         READY     STATUS      RESTARTS   AGE
nationalparks-10-70zcr       1/1       Running     0          1m
nationalparks-10-deploy      1/1       Running     0          1m
nationalparks-10-hook-post   0/1       Completed   0          1m

This is very strange. Can you reproduce it and post the logs from both the deployer and the post hook?

@jorgemoralespou
Copy link
Author

@kargaris How can I get the logs por the post? Those that get streamed into
the deployer once I raise loglevel, or some other?

El 23 sept. 2016 21:50, "Michail Kargakis" notifications@github.com
escribió:

I don't think we have docs for @smarterclayton
https://github.com/smarterclayton's latest changes in custom
deployments. Can you open an issue in openshift-docs? I may tackle it as
part of openshift/openshift-docs#2890
openshift/openshift-docs#2890.

Regarding the behavior you see

[jmorales@mac-santander origin-labs] $oc get pods -w
NAME READY STATUS RESTARTS AGE
nationalparks-10-70zcr 1/1 Running 0 1m
nationalparks-10-deploy 1/1 Running 0 1m
nationalparks-10-hook-post 0/1 Completed 0 1m

This is very strange. Can you reproduce it and post the logs from both the
deployer and the post hook?


You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
#11069 (comment),
or mute the thread
https://github.com/notifications/unsubscribe-auth/AAEyDvXCgkqmHcOlGAfsQqJ7ZYf0PBMWks5qtC2RgaJpZM4KE4ny
.

@0xmichalis
Copy link
Contributor

Try oc logs pod/nationalparks-X-hook-post

@jorgemoralespou
Copy link
Author

Hi Michail,
Thanks for the help.

I can reproduce this, almost always (funny though, sometimes worked). In any case, here are the logs:

$oc logs mlbparks-4-hook-post
Items inserted in database: 30

And:

I0923 17:20:12.789703       1 round_trippers.go:318] GET https://172.30.0.1:443/api/v1/namespaces/nationalparks/replicationcontrollers/mlbparks-4 200 OK in 3 milliseconds
I0923 17:20:12.789729       1 round_trippers.go:324] Response Headers:
I0923 17:20:12.789736       1 round_trippers.go:327]     Content-Type: application/json
I0923 17:20:12.789741       1 round_trippers.go:327]     Date: Fri, 23 Sep 2016 17:20:12 GMT
I0923 17:20:12.789746       1 round_trippers.go:327]     Cache-Control: no-store
I0923 17:20:12.789850       1 request.go:901] Response Body: {"kind":"ReplicationController","apiVersion":"v1","metadata":{"name":"mlbparks-4","namespace":"nationalparks","selfLink":"/api/v1/namespaces/nationalparks/replicationcontrollers/mlbparks-4","uid":"d413ac7b-81b1-11e6-aa7d-1e1e5ab82594","resourceVersion":"9850","generation":2,"creationTimestamp":"2016-09-23T17:19:04Z","labels":{"app":"mlbparks","application":"mlbparks","component":"mlbparks","createdBy":"mlbparks-template","openshift.io/deployment-config.name":"mlbparks"},"annotations":{"openshift.io/deployer-pod.name":"mlbparks-4-deploy","openshift.io/deployment-config.latest-version":"4","openshift.io/deployment-config.name":"mlbparks","openshift.io/deployment.phase":"Running","openshift.io/deployment.replicas":"0","openshift.io/deployment.status-reason":"caused by an image change","openshift.io/encoded-deployment-config":"{\"kind\":\"DeploymentConfig\",\"apiVersion\":\"v1\",\"metadata\":{\"name\":\"mlbparks\",\"namespace\":\"nationalparks\",\"selfLink\":\"/oapi/v1/namespaces/nationalparks/deploymentconfigs/mlbparks\",\"uid\":\"339c52b6-8188-11e6-8fa7-1e1e5ab82594\",\"resourceVersion\":\"9790\",\"generation\":7,\"creationTimestamp\":\"2016-09-23T12:21:06Z\",\"labels\":{\"app\":\"mlbparks\",\"application\":\"mlbparks\",\"component\":\"mlbparks\",\"createdBy\":\"mlbparks-template\"}},\"spec\":{\"strategy\":{\"type\":\"Rolling\",\"customParams\":{\"command\":[\"openshift-deploy\",\"--loglevel=10\"]},\"rollingParams\":{\"updatePeriodSeconds\":1,\"intervalSeconds\":1,\"timeoutSeconds\":600,\"maxUnavailable\":\"25%\",\"maxSurge\":\"25%\",\"post\":{\"failurePolicy\":\"Ignore\",\"execNewPod\":{\"command\":[\"curl\",\"-s\",\"http://mlbparks:8080/ws/data/load\"],\"containerName\":\"mlbparks\"}}},\"resources\":{}},\"triggers\":[{\"type\":\"ConfigChange\"},{\"type\":\"ImageChange\",\"imageChangeParams\":{\"automatic\":true,\"containerNames\":[\"mlbparks\"],\"from\":{\"kind\":\"ImageStreamTag\",\"namespace\":\"nationalparks\",\"name\":\"mlbparks:latest\"},\"lastTriggeredImage\":\"172.30.34.172:5000/nationalparks/mlbparks@sha256:5bd75289a025ceabec2265eb102541eae298e1d1081304a52e9a1924064f703f\"}}],\"replicas\":1,\"test\":false,\"selector\":{\"component\":\"mlbparks\"},\"template\":{\"metadata\":{\"creationTimestamp\":null,\"labels\":{\"component\":\"mlbparks\"}},\"spec\":{\"containers\":[{\"name\":\"mlbparks\",\"image\":\"172.30.34.172:5000/nationalparks/mlbparks@sha256:5bd75289a025ceabec2265eb102541eae298e1d1081304a52e9a1924064f703f\",\"ports\":[{\"containerPort\":8080,\"protocol\":\"TCP\"}],\"env\":[{\"name\":\"KUBERNETES_NAMESPACE\",\"valueFrom\":{\"fieldRef\":{\"apiVersion\":\"v1\",\"fieldPath\":\"metadata.namespace\"}}},{\"name\":\"DB_PORT\",\"value\":\"27017\"},{\"name\":\"DB_HOST\",\"value\":\"mongodb-mlbparks\"},{\"name\":\"DB_USERNAME\",\"valueFrom\":{\"configMapKeyRef\":{\"name\":\"mlbparks\",\"key\":\"db.user\"}}},{\"name\":\"DB_PASSWORD\",\"valueFrom\":{\"configMapKeyRef\":{\"name\":\"mlbparks\",\"key\":\"db.password\"}}},{\"name\":\"DB_NAME\",\"valueFrom\":{\"configMapKeyRef\":{\"name\":\"mlbparks\",\"key\":\"db.name\"}}}],\"resources\":{},\"livenessProbe\":{\"httpGet\":{\"path\":\"/ws/healthz/\",\"port\":8080,\"scheme\":\"HTTP\"},\"initialDelaySeconds\":120,\"timeoutSeconds\":1,\"periodSeconds\":10,\"successThreshold\":1,\"failureThreshold\":3},\"readinessProbe\":{\"httpGet\":{\"path\":\"/ws/healthz/\",\"port\":8080,\"scheme\":\"HTTP\"},\"initialDelaySeconds\":20,\"timeoutSeconds\":1,\"periodSeconds\":10,\"successThreshold\":1,\"failureThreshold\":3},\"terminationMessagePath\":\"/dev/termination-log\",\"imagePullPolicy\":\"IfNotPresent\"}],\"restartPolicy\":\"Always\",\"terminationGracePeriodSeconds\":30,\"dnsPolicy\":\"ClusterFirst\",\"securityContext\":{}}}},\"status\":{\"latestVersion\":4,\"observedGeneration\":6,\"replicas\":1,\"updatedReplicas\":1,\"availableReplicas\":1,\"details\":{\"message\":\"caused by an image change\",\"causes\":[{\"type\":\"ImageChange\",\"imageTrigger\":{\"from\":{\"kind\":\"ImageStreamTag\",\"namespace\":\"nationalparks\",\"name\":\"mlbparks:latest\"}}}]}}}\n"}},"spec":{"replicas":1,"selector":{"component":"mlbparks","deployment":"mlbparks-4","deploymentconfig":"mlbparks"},"template":{"metadata":{"creationTimestamp":null,"labels":{"component":"mlbparks","deployment":"mlbparks-4","deploymentconfig":"mlbparks"},"annotations":{"openshift.io/deployment-config.latest-version":"4","openshift.io/deployment-config.name":"mlbparks","openshift.io/deployment.name":"mlbparks-4"}},"spec":{"containers":[{"name":"mlbparks","image":"172.30.34.172:5000/nationalparks/mlbparks@sha256:5bd75289a025ceabec2265eb102541eae298e1d1081304a52e9a1924064f703f","ports":[{"containerPort":8080,"protocol":"TCP"}],"env":[{"name":"KUBERNETES_NAMESPACE","valueFrom":{"fieldRef":{"apiVersion":"v1","fieldPath":"metadata.namespace"}}},{"name":"DB_PORT","value":"27017"},{"name":"DB_HOST","value":"mongodb-mlbparks"},{"name":"DB_USERNAME","valueFrom":{"configMapKeyRef":{"name":"mlbparks","key":"db.user"}}},{"name":"DB_PASSWORD","valueFrom":{"configMapKeyRef":{"name":"mlbparks","key":"db.password"}}},{"name":"DB_NAME","valueFrom":{"configMapKeyRef":{"name":"mlbparks","key":"db.name"}}}],"resources":{},"livenessProbe":{"httpGet":{"path":"/ws/healthz/","port":8080,"scheme":"HTTP"},"initialDelaySeconds":120,"timeoutSeconds":1,"periodSeconds":10,"successThreshold":1,"failureThreshold":3},"readinessProbe":{"httpGet":{"path":"/ws/healthz/","port":8080,"scheme":"HTTP"},"initialDelaySeconds":20,"timeoutSeconds":1,"periodSeconds":10,"successThreshold":1,"failureThreshold":3},"terminationMessagePath":"/dev/termination-log","imagePullPolicy":"IfNotPresent"}],"restartPolicy":"Always","terminationGracePeriodSeconds":30,"dnsPolicy":"ClusterFirst","securityContext":{}}}},"status":{"replicas":1,"fullyLabeledReplicas":1,"observedGeneration":2}}
I0923 17:20:12.790800       1 request.go:562] Request Body: {"kind":"Event","apiVersion":"v1","metadata":{"name":"mlbparks.1477037709f8ec53","namespace":"nationalparks","creationTimestamp":null},"involvedObject":{"kind":"DeploymentConfig","namespace":"nationalparks","name":"mlbparks","uid":"339c52b6-8188-11e6-8fa7-1e1e5ab82594","apiVersion":"v1","resourceVersion":"9790"},"reason":"Started","message":"Running post-hook (\"curl -s http://mlbparks:8080/ws/data/load\") for deployment nationalparks/mlbparks-4","source":{"component":"mlbparks-4-deploy"},"firstTimestamp":"2016-09-23T17:20:12Z","lastTimestamp":"2016-09-23T17:20:12Z","count":1,"type":"Normal"}
I0923 17:20:12.790970       1 round_trippers.go:299] curl -k -v -XPOST  -H "Content-Type: application/json" -H "User-Agent: openshift-deploy/v1.3.0+52492b4 (linux/amd64) kubernetes/52492b4" -H "Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJuYXRpb25hbHBhcmtzIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZWNyZXQubmFtZSI6ImRlcGxveWVyLXRva2VuLTlyY3dzIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6ImRlcGxveWVyIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQudWlkIjoiZDNhNjQ4YzgtODE3ZC0xMWU2LThmYTctMWUxZTVhYjgyNTk0Iiwic3ViIjoic3lzdGVtOnNlcnZpY2VhY2NvdW50Om5hdGlvbmFscGFya3M6ZGVwbG95ZXIifQ.dWIkY4bJ6C5oE9-BWZLb2AMNaR9PiAUobl93TQu0MUF6y3C8w9kXGZoCB1D2KYTAgQHyxboDVfd7-Rjl_lrgk4dWx02sZXcnKe2Ita0eVh1RTBOjkhHNFcMd9EcApKXMcMdmnCvxqA-adUVzpiCTRIUvFv1bNnCk8qCpKl21K8l1hbmbK71YjU5IRYGx8gN1ebGtLp8j5BNwGkgVcuKMtRVESIQAphx9Y4_y716aRK5tM5aFuHii99haKwAQujxLc28wyVF7gwa4cWG5kOB_AvJmAYdHXd2D3yezdINCX9luQ9j6M06UX3wJP7jDmrhdrcrn579gKDrn9Luqv-s9RQ" -H "Accept: application/json, */*" https://172.30.0.1:443/api/v1/namespaces/nationalparks/events
I0923 17:20:12.794726       1 round_trippers.go:318] POST https://172.30.0.1:443/api/v1/namespaces/nationalparks/events 201 Created in 3 milliseconds
I0923 17:20:12.794748       1 round_trippers.go:324] Response Headers:
I0923 17:20:12.794753       1 round_trippers.go:327]     Date: Fri, 23 Sep 2016 17:20:12 GMT
I0923 17:20:12.794758       1 round_trippers.go:327]     Content-Length: 767
I0923 17:20:12.794773       1 round_trippers.go:327]     Cache-Control: no-store
I0923 17:20:12.794778       1 round_trippers.go:327]     Content-Type: application/json
I0923 17:20:12.794889       1 request.go:901] Response Body: {"kind":"Event","apiVersion":"v1","metadata":{"name":"mlbparks.1477037709f8ec53","namespace":"nationalparks","selfLink":"/api/v1/namespaces/nationalparks/events/mlbparks.1477037709f8ec53","uid":"fc90fba3-81b1-11e6-aa7d-1e1e5ab82594","resourceVersion":"9851","creationTimestamp":"2016-09-23T17:20:12Z"},"involvedObject":{"kind":"DeploymentConfig","namespace":"nationalparks","name":"mlbparks","uid":"339c52b6-8188-11e6-8fa7-1e1e5ab82594","apiVersion":"v1","resourceVersion":"9790"},"reason":"Started","message":"Running post-hook (\"curl -s http://mlbparks:8080/ws/data/load\") for deployment nationalparks/mlbparks-4","source":{"component":"mlbparks-4-deploy"},"firstTimestamp":"2016-09-23T17:20:12Z","lastTimestamp":"2016-09-23T17:20:12Z","count":1,"type":"Normal"}
I0923 17:20:12.795760       1 request.go:562] Request Body: {"kind":"Pod","apiVersion":"v1","metadata":{"name":"mlbparks-4-hook-post","creationTimestamp":null,"labels":{"openshift.io/deployer-pod-for.name":"mlbparks-4","openshift.io/deployer-pod.type":"hook-post"},"annotations":{"openshift.io/deployment.name":"mlbparks-4"}},"spec":{"containers":[{"name":"lifecycle","image":"172.30.34.172:5000/nationalparks/mlbparks@sha256:5bd75289a025ceabec2265eb102541eae298e1d1081304a52e9a1924064f703f","command":["curl","-s","http://mlbparks:8080/ws/data/load"],"env":[{"name":"KUBERNETES_NAMESPACE"},{"name":"DB_PORT","value":"27017"},{"name":"DB_HOST","value":"mongodb-mlbparks"},{"name":"DB_USERNAME"},{"name":"DB_PASSWORD"},{"name":"DB_NAME"},{"name":"OPENSHIFT_DEPLOYMENT_NAME","value":"mlbparks-4"},{"name":"OPENSHIFT_DEPLOYMENT_NAMESPACE","value":"nationalparks"}],"resources":{}}],"restartPolicy":"Never","terminationGracePeriodSeconds":10,"activeDeadlineSeconds":21600},"status":{}}
I0923 17:20:12.796046       1 round_trippers.go:299] curl -k -v -XPOST  -H "Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJuYXRpb25hbHBhcmtzIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZWNyZXQubmFtZSI6ImRlcGxveWVyLXRva2VuLTlyY3dzIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6ImRlcGxveWVyIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQudWlkIjoiZDNhNjQ4YzgtODE3ZC0xMWU2LThmYTctMWUxZTVhYjgyNTk0Iiwic3ViIjoic3lzdGVtOnNlcnZpY2VhY2NvdW50Om5hdGlvbmFscGFya3M6ZGVwbG95ZXIifQ.dWIkY4bJ6C5oE9-BWZLb2AMNaR9PiAUobl93TQu0MUF6y3C8w9kXGZoCB1D2KYTAgQHyxboDVfd7-Rjl_lrgk4dWx02sZXcnKe2Ita0eVh1RTBOjkhHNFcMd9EcApKXMcMdmnCvxqA-adUVzpiCTRIUvFv1bNnCk8qCpKl21K8l1hbmbK71YjU5IRYGx8gN1ebGtLp8j5BNwGkgVcuKMtRVESIQAphx9Y4_y716aRK5tM5aFuHii99haKwAQujxLc28wyVF7gwa4cWG5kOB_AvJmAYdHXd2D3yezdINCX9luQ9j6M06UX3wJP7jDmrhdrcrn579gKDrn9Luqv-s9RQ" -H "Accept: application/json, */*" -H "Content-Type: application/json" -H "User-Agent: openshift-deploy/v1.3.0+52492b4 (linux/amd64) kubernetes/52492b4" https://172.30.0.1:443/api/v1/namespaces/nationalparks/pods
I0923 17:20:12.808305       1 round_trippers.go:318] POST https://172.30.0.1:443/api/v1/namespaces/nationalparks/pods 201 Created in 12 milliseconds
I0923 17:20:12.808329       1 round_trippers.go:324] Response Headers:
I0923 17:20:12.808335       1 round_trippers.go:327]     Date: Fri, 23 Sep 2016 17:20:12 GMT
I0923 17:20:12.808341       1 round_trippers.go:327]     Content-Length: 1845
I0923 17:20:12.808346       1 round_trippers.go:327]     Cache-Control: no-store
I0923 17:20:12.808351       1 round_trippers.go:327]     Content-Type: application/json
I0923 17:20:12.808407       1 request.go:901] Response Body: {"kind":"Pod","apiVersion":"v1","metadata":{"name":"mlbparks-4-hook-post","namespace":"nationalparks","selfLink":"/api/v1/namespaces/nationalparks/pods/mlbparks-4-hook-post","uid":"fc92db68-81b1-11e6-aa7d-1e1e5ab82594","resourceVersion":"9852","creationTimestamp":"2016-09-23T17:20:12Z","labels":{"openshift.io/deployer-pod-for.name":"mlbparks-4","openshift.io/deployer-pod.type":"hook-post"},"annotations":{"openshift.io/deployment.name":"mlbparks-4","openshift.io/scc":"restricted"}},"spec":{"volumes":[{"name":"default-token-wx4aj","secret":{"secretName":"default-token-wx4aj"}}],"containers":[{"name":"lifecycle","image":"172.30.34.172:5000/nationalparks/mlbparks@sha256:5bd75289a025ceabec2265eb102541eae298e1d1081304a52e9a1924064f703f","command":["curl","-s","http://mlbparks:8080/ws/data/load"],"env":[{"name":"KUBERNETES_NAMESPACE"},{"name":"DB_PORT","value":"27017"},{"name":"DB_HOST","value":"mongodb-mlbparks"},{"name":"DB_USERNAME"},{"name":"DB_PASSWORD"},{"name":"DB_NAME"},{"name":"OPENSHIFT_DEPLOYMENT_NAME","value":"mlbparks-4"},{"name":"OPENSHIFT_DEPLOYMENT_NAMESPACE","value":"nationalparks"}],"resources":{},"volumeMounts":[{"name":"default-token-wx4aj","readOnly":true,"mountPath":"/var/run/secrets/kubernetes.io/serviceaccount"}],"terminationMessagePath":"/dev/termination-log","imagePullPolicy":"IfNotPresent","securityContext":{"capabilities":{"drop":["KILL","MKNOD","SETGID","SETUID","SYS_CHROOT"]},"privileged":false,"seLinuxOptions":{"level":"s0:c7,c4"},"runAsUser":1000050000}}],"restartPolicy":"Never","terminationGracePeriodSeconds":10,"activeDeadlineSeconds":21600,"dnsPolicy":"ClusterFirst","serviceAccountName":"default","serviceAccount":"default","securityContext":{"seLinuxOptions":{"level":"s0:c7,c4"},"fsGroup":1000050000},"imagePullSecrets":[{"name":"default-dockercfg-1w5gg"}]},"status":{"phase":"Pending"}}
--> post: Running hook pod ...
I0923 17:20:12.808761       1 reflector.go:211] Starting reflector *api.Pod (1m0s) from github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483
I0923 17:20:12.808815       1 reflector.go:249] Listing and watching *api.Pod from github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483
I0923 17:20:12.808988       1 round_trippers.go:299] curl -k -v -XGET  -H "Accept: application/json, */*" -H "User-Agent: openshift-deploy/v1.3.0+52492b4 (linux/amd64) kubernetes/52492b4" -H "Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJuYXRpb25hbHBhcmtzIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZWNyZXQubmFtZSI6ImRlcGxveWVyLXRva2VuLTlyY3dzIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6ImRlcGxveWVyIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQudWlkIjoiZDNhNjQ4YzgtODE3ZC0xMWU2LThmYTctMWUxZTVhYjgyNTk0Iiwic3ViIjoic3lzdGVtOnNlcnZpY2VhY2NvdW50Om5hdGlvbmFscGFya3M6ZGVwbG95ZXIifQ.dWIkY4bJ6C5oE9-BWZLb2AMNaR9PiAUobl93TQu0MUF6y3C8w9kXGZoCB1D2KYTAgQHyxboDVfd7-Rjl_lrgk4dWx02sZXcnKe2Ita0eVh1RTBOjkhHNFcMd9EcApKXMcMdmnCvxqA-adUVzpiCTRIUvFv1bNnCk8qCpKl21K8l1hbmbK71YjU5IRYGx8gN1ebGtLp8j5BNwGkgVcuKMtRVESIQAphx9Y4_y716aRK5tM5aFuHii99haKwAQujxLc28wyVF7gwa4cWG5kOB_AvJmAYdHXd2D3yezdINCX9luQ9j6M06UX3wJP7jDmrhdrcrn579gKDrn9Luqv-s9RQ" https://172.30.0.1:443/api/v1/namespaces/nationalparks/pods?fieldSelector=metadata.name%3Dmlbparks-4-hook-post
I0923 17:20:12.812276       1 round_trippers.go:318] GET https://172.30.0.1:443/api/v1/namespaces/nationalparks/pods?fieldSelector=metadata.name%3Dmlbparks-4-hook-post 200 OK in 3 milliseconds
I0923 17:20:12.812299       1 round_trippers.go:324] Response Headers:
I0923 17:20:12.812305       1 round_trippers.go:327]     Cache-Control: no-store
I0923 17:20:12.812310       1 round_trippers.go:327]     Content-Type: application/json
I0923 17:20:12.812314       1 round_trippers.go:327]     Date: Fri, 23 Sep 2016 17:20:12 GMT
I0923 17:20:12.812368       1 request.go:901] Response Body: {"kind":"PodList","apiVersion":"v1","metadata":{"selfLink":"/api/v1/namespaces/nationalparks/pods","resourceVersion":"9853"},"items":[{"metadata":{"name":"mlbparks-4-hook-post","namespace":"nationalparks","selfLink":"/api/v1/namespaces/nationalparks/pods/mlbparks-4-hook-post","uid":"fc92db68-81b1-11e6-aa7d-1e1e5ab82594","resourceVersion":"9853","creationTimestamp":"2016-09-23T17:20:12Z","labels":{"openshift.io/deployer-pod-for.name":"mlbparks-4","openshift.io/deployer-pod.type":"hook-post"},"annotations":{"openshift.io/deployment.name":"mlbparks-4","openshift.io/scc":"restricted"}},"spec":{"volumes":[{"name":"default-token-wx4aj","secret":{"secretName":"default-token-wx4aj"}}],"containers":[{"name":"lifecycle","image":"172.30.34.172:5000/nationalparks/mlbparks@sha256:5bd75289a025ceabec2265eb102541eae298e1d1081304a52e9a1924064f703f","command":["curl","-s","http://mlbparks:8080/ws/data/load"],"env":[{"name":"KUBERNETES_NAMESPACE"},{"name":"DB_PORT","value":"27017"},{"name":"DB_HOST","value":"mongodb-mlbparks"},{"name":"DB_USERNAME"},{"name":"DB_PASSWORD"},{"name":"DB_NAME"},{"name":"OPENSHIFT_DEPLOYMENT_NAME","value":"mlbparks-4"},{"name":"OPENSHIFT_DEPLOYMENT_NAMESPACE","value":"nationalparks"}],"resources":{},"volumeMounts":[{"name":"default-token-wx4aj","readOnly":true,"mountPath":"/var/run/secrets/kubernetes.io/serviceaccount"}],"terminationMessagePath":"/dev/termination-log","imagePullPolicy":"IfNotPresent","securityContext":{"capabilities":{"drop":["KILL","MKNOD","SETGID","SETUID","SYS_CHROOT"]},"privileged":false,"seLinuxOptions":{"level":"s0:c7,c4"},"runAsUser":1000050000}}],"restartPolicy":"Never","terminationGracePeriodSeconds":10,"activeDeadlineSeconds":21600,"dnsPolicy":"ClusterFirst","host":"192.168.65.2","serviceAccountName":"default","serviceAccount":"default","nodeName":"192.168.65.2","securityContext":{"seLinuxOptions":{"level":"s0:c7,c4"},"fsGroup":1000050000},"imagePullSecrets":[{"name":"default-dockercfg-1w5gg"}]},"status":{"phase":"Pending","conditions":[{"type":"PodScheduled","status":"True","lastProbeTime":null,"lastTransitionTime":"2016-09-23T17:20:12Z"}]}}]}
I0923 17:20:12.812889       1 round_trippers.go:299] curl -k -v -XGET  -H "User-Agent: openshift-deploy/v1.3.0+52492b4 (linux/amd64) kubernetes/52492b4" -H "Accept: application/json, */*" -H "Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJuYXRpb25hbHBhcmtzIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZWNyZXQubmFtZSI6ImRlcGxveWVyLXRva2VuLTlyY3dzIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6ImRlcGxveWVyIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQudWlkIjoiZDNhNjQ4YzgtODE3ZC0xMWU2LThmYTctMWUxZTVhYjgyNTk0Iiwic3ViIjoic3lzdGVtOnNlcnZpY2VhY2NvdW50Om5hdGlvbmFscGFya3M6ZGVwbG95ZXIifQ.dWIkY4bJ6C5oE9-BWZLb2AMNaR9PiAUobl93TQu0MUF6y3C8w9kXGZoCB1D2KYTAgQHyxboDVfd7-Rjl_lrgk4dWx02sZXcnKe2Ita0eVh1RTBOjkhHNFcMd9EcApKXMcMdmnCvxqA-adUVzpiCTRIUvFv1bNnCk8qCpKl21K8l1hbmbK71YjU5IRYGx8gN1ebGtLp8j5BNwGkgVcuKMtRVESIQAphx9Y4_y716aRK5tM5aFuHii99haKwAQujxLc28wyVF7gwa4cWG5kOB_AvJmAYdHXd2D3yezdINCX9luQ9j6M06UX3wJP7jDmrhdrcrn579gKDrn9Luqv-s9RQ" https://172.30.0.1:443/api/v1/watch/namespaces/nationalparks/pods?fieldSelector=metadata.name%3Dmlbparks-4-hook-post&resourceVersion=9853
I0923 17:20:12.815213       1 round_trippers.go:318] GET https://172.30.0.1:443/api/v1/watch/namespaces/nationalparks/pods?fieldSelector=metadata.name%3Dmlbparks-4-hook-post&resourceVersion=9853 200 OK in 2 milliseconds
I0923 17:20:12.815275       1 round_trippers.go:324] Response Headers:
I0923 17:20:12.815304       1 round_trippers.go:327]     Date: Fri, 23 Sep 2016 17:20:12 GMT
I0923 17:20:12.815324       1 round_trippers.go:327]     Cache-Control: no-store
I0923 17:20:12.815332       1 round_trippers.go:327]     Content-Type: application/json
I0923 17:20:15.048216       1 round_trippers.go:299] curl -k -v -XGET  -H "User-Agent: openshift-deploy/v1.3.0+52492b4 (linux/amd64) kubernetes/52492b4" -H "Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJuYXRpb25hbHBhcmtzIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZWNyZXQubmFtZSI6ImRlcGxveWVyLXRva2VuLTlyY3dzIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6ImRlcGxveWVyIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQudWlkIjoiZDNhNjQ4YzgtODE3ZC0xMWU2LThmYTctMWUxZTVhYjgyNTk0Iiwic3ViIjoic3lzdGVtOnNlcnZpY2VhY2NvdW50Om5hdGlvbmFscGFya3M6ZGVwbG95ZXIifQ.dWIkY4bJ6C5oE9-BWZLb2AMNaR9PiAUobl93TQu0MUF6y3C8w9kXGZoCB1D2KYTAgQHyxboDVfd7-Rjl_lrgk4dWx02sZXcnKe2Ita0eVh1RTBOjkhHNFcMd9EcApKXMcMdmnCvxqA-adUVzpiCTRIUvFv1bNnCk8qCpKl21K8l1hbmbK71YjU5IRYGx8gN1ebGtLp8j5BNwGkgVcuKMtRVESIQAphx9Y4_y716aRK5tM5aFuHii99haKwAQujxLc28wyVF7gwa4cWG5kOB_AvJmAYdHXd2D3yezdINCX9luQ9j6M06UX3wJP7jDmrhdrcrn579gKDrn9Luqv-s9RQ" -H "Accept: application/json, */*" https://172.30.0.1:443/api/v1/namespaces/nationalparks/pods/mlbparks-4-hook-post/log?container=lifecycle&follow=true
I0923 17:21:12.809227       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:22:12.810140       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:23:12.810565       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:24:12.812042       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:25:12.812975       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:26:12.813776       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:27:12.816624       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:28:12.817570       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:29:12.818069       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:30:12.819105       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:31:12.819889       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:32:12.820908       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:33:12.821496       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:34:12.822348       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:35:12.823099       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:36:12.825042       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:37:12.825812       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:38:12.826409       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync
I0923 17:39:12.826911       1 reflector.go:284] github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483: forcing resync

Full log in a gist: https://gist.github.com/jorgemoralespou/84cce7aac15f02e2d2f015e48e7cf13f

@jorgemoralespou
Copy link
Author

The one thing i noticed is the time in the response. Current time is:
$ date
Sat Sep 24 08:08:38 CEST 2016

Which by no means relate to the date I get in the answers from the server.

@csrwng Can this be related to "oc cluster up", and having the container paused during the night?

@jorgemoralespou
Copy link
Author

It might be related to the time problem.
I have opened an issue (#11083) and I have restarted my "Docker for mac" to sync time, and then do a new deployment and everything went fine.

I0924 09:04:40.556183       1 round_trippers.go:318] POST https://172.30.0.1:443/api/v1/namespaces/roadshow/pods 201 Created in 7 milliseconds
I0924 09:04:40.556203       1 round_trippers.go:324] Response Headers:
I0924 09:04:40.556206       1 round_trippers.go:327]     Cache-Control: no-store
I0924 09:04:40.556208       1 round_trippers.go:327]     Content-Type: application/json
I0924 09:04:40.556211       1 round_trippers.go:327]     Date: Sat, 24 Sep 2016 09:04:40 GMT
I0924 09:04:40.556213       1 round_trippers.go:327]     Content-Length: 1712
I0924 09:04:40.556243       1 request.go:901] Response Body: {"kind":"Pod","apiVersion":"v1","metadata":{"name":"nationalparks-2-hook-post","namespace":"roadshow","selfLink":"/api/v1/namespaces/roadshow/pods/nationalparks-2-hook-post","uid":"ed2f2893-8235-11e6-9ae6-d2f2bc615fc6","resourceVersion":"1483","creationTimestamp":"2016-09-24T09:04:40Z","labels":{"openshift.io/deployer-pod-for.name":"nationalparks-2","openshift.io/deployer-pod.type":"hook-post"},"annotations":{"openshift.io/deployment.name":"nationalparks-2","openshift.io/scc":"restricted"}},"spec":{"volumes":[{"name":"default-token-6pa3a","secret":{"secretName":"default-token-6pa3a"}}],"containers":[{"name":"lifecycle","image":"172.30.8.37:5000/roadshow/nationalparks@sha256:2855eccb2707227213574798e72b1402c2700f1584f1919a059e647946f0619e","command":["curl","-s","http://nationalparks:8080/ws/data/load"],"env":[{"name":"KUBERNETES_NAMESPACE"},{"name":"OPENSHIFT_DEPLOYMENT_NAME","value":"nationalparks-2"},{"name":"OPENSHIFT_DEPLOYMENT_NAMESPACE","value":"roadshow"}],"resources":{},"volumeMounts":[{"name":"default-token-6pa3a","readOnly":true,"mountPath":"/var/run/secrets/kubernetes.io/serviceaccount"}],"terminationMessagePath":"/dev/termination-log","imagePullPolicy":"IfNotPresent","securityContext":{"capabilities":{"drop":["KILL","MKNOD","SETGID","SETUID","SYS_CHROOT"]},"privileged":false,"seLinuxOptions":{"level":"s0:c7,c4"},"runAsUser":1000050000}}],"restartPolicy":"Never","terminationGracePeriodSeconds":10,"activeDeadlineSeconds":21600,"dnsPolicy":"ClusterFirst","serviceAccountName":"default","serviceAccount":"default","securityContext":{"seLinuxOptions":{"level":"s0:c7,c4"},"fsGroup":1000050000},"imagePullSecrets":[{"name":"default-dockercfg-p0ec3"}]},"status":{"phase":"Pending"}}
--> post: Running hook pod ...
I0924 09:04:40.556626       1 reflector.go:211] Starting reflector *api.Pod (1m0s) from github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483
I0924 09:04:40.556682       1 reflector.go:249] Listing and watching *api.Pod from github.com/openshift/origin/pkg/deploy/strategy/support/lifecycle.go:483
I0924 09:04:40.556897       1 round_trippers.go:299] curl -k -v -XGET  -H "Accept: application/json, */*" -H "User-Agent: openshift-deploy/v1.3.0+52492b4 (linux/amd64) kubernetes/52492b4" -H "Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJyb2Fkc2hvdyIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJkZXBsb3llci10b2tlbi04N2I0NCIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VydmljZS1hY2NvdW50Lm5hbWUiOiJkZXBsb3llciIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VydmljZS1hY2NvdW50LnVpZCI6ImQ1Mzg5NDdlLTgyMjMtMTFlNi04NDVmLTc2N2U1YjU4YTI3MiIsInN1YiI6InN5c3RlbTpzZXJ2aWNlYWNjb3VudDpyb2Fkc2hvdzpkZXBsb3llciJ9.Wtga7P02Way77cFITzRWM9pz6qlJIj0t3EM2QgXMfM29Z_b12B4jTRi0i0OBUYYpbMntj7loP2N29U1VRGCKJkGH-NJsxSrNehRx-bOdJ6sdmziYvGox-o1EGzf_83lKL3AWRPBFY_PsQeAsFDoE_cTU29SJeyBgQlzmflOYusLKaKvNp1nXKQnvnI4GLnJyTJhMGB9KvhnKsWrvbBt0HIHIDdMxn9qsgEQ8pCOj0BNPFigp0OdgR_5Q0dx2AZ1APVowQpExeZD3T9T-hWqFepFnA4of3n1gZVI8bjW6T2d3iVUviMGvr82YGImt9ie1n3BjbsZcJl_OpPU1Q6OjFg" https://172.30.0.1:443/api/v1/namespaces/roadshow/pods?fieldSelector=metadata.name%3Dnationalparks-2-hook-post
I0924 09:04:40.559194       1 round_trippers.go:318] GET https://172.30.0.1:443/api/v1/namespaces/roadshow/pods?fieldSelector=metadata.name%3Dnationalparks-2-hook-post 200 OK in 2 milliseconds
I0924 09:04:40.559213       1 round_trippers.go:324] Response Headers:
I0924 09:04:40.559216       1 round_trippers.go:327]     Cache-Control: no-store
I0924 09:04:40.559219       1 round_trippers.go:327]     Content-Type: application/json
I0924 09:04:40.559221       1 round_trippers.go:327]     Date: Sat, 24 Sep 2016 09:04:40 GMT
I0924 09:04:40.559223       1 round_trippers.go:327]     Content-Length: 1812
I0924 09:04:40.559266       1 request.go:901] Response Body: {"kind":"PodList","apiVersion":"v1","metadata":{"selfLink":"/api/v1/namespaces/roadshow/pods","resourceVersion":"1483"},"items":[{"metadata":{"name":"nationalparks-2-hook-post","namespace":"roadshow","selfLink":"/api/v1/namespaces/roadshow/pods/nationalparks-2-hook-post","uid":"ed2f2893-8235-11e6-9ae6-d2f2bc615fc6","resourceVersion":"1483","creationTimestamp":"2016-09-24T09:04:40Z","labels":{"openshift.io/deployer-pod-for.name":"nationalparks-2","openshift.io/deployer-pod.type":"hook-post"},"annotations":{"openshift.io/deployment.name":"nationalparks-2","openshift.io/scc":"restricted"}},"spec":{"volumes":[{"name":"default-token-6pa3a","secret":{"secretName":"default-token-6pa3a"}}],"containers":[{"name":"lifecycle","image":"172.30.8.37:5000/roadshow/nationalparks@sha256:2855eccb2707227213574798e72b1402c2700f1584f1919a059e647946f0619e","command":["curl","-s","http://nationalparks:8080/ws/data/load"],"env":[{"name":"KUBERNETES_NAMESPACE"},{"name":"OPENSHIFT_DEPLOYMENT_NAME","value":"nationalparks-2"},{"name":"OPENSHIFT_DEPLOYMENT_NAMESPACE","value":"roadshow"}],"resources":{},"volumeMounts":[{"name":"default-token-6pa3a","readOnly":true,"mountPath":"/var/run/secrets/kubernetes.io/serviceaccount"}],"terminationMessagePath":"/dev/termination-log","imagePullPolicy":"IfNotPresent","securityContext":{"capabilities":{"drop":["KILL","MKNOD","SETGID","SETUID","SYS_CHROOT"]},"privileged":false,"seLinuxOptions":{"level":"s0:c7,c4"},"runAsUser":1000050000}}],"restartPolicy":"Never","terminationGracePeriodSeconds":10,"activeDeadlineSeconds":21600,"dnsPolicy":"ClusterFirst","serviceAccountName":"default","serviceAccount":"default","securityContext":{"seLinuxOptions":{"level":"s0:c7,c4"},"fsGroup":1000050000},"imagePullSecrets":[{"name":"default-dockercfg-p0ec3"}]},"status":{"phase":"Pending"}}]}
I0924 09:04:40.560306       1 round_trippers.go:299] curl -k -v -XGET  -H "User-Agent: openshift-deploy/v1.3.0+52492b4 (linux/amd64) kubernetes/52492b4" -H "Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJyb2Fkc2hvdyIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJkZXBsb3llci10b2tlbi04N2I0NCIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VydmljZS1hY2NvdW50Lm5hbWUiOiJkZXBsb3llciIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VydmljZS1hY2NvdW50LnVpZCI6ImQ1Mzg5NDdlLTgyMjMtMTFlNi04NDVmLTc2N2U1YjU4YTI3MiIsInN1YiI6InN5c3RlbTpzZXJ2aWNlYWNjb3VudDpyb2Fkc2hvdzpkZXBsb3llciJ9.Wtga7P02Way77cFITzRWM9pz6qlJIj0t3EM2QgXMfM29Z_b12B4jTRi0i0OBUYYpbMntj7loP2N29U1VRGCKJkGH-NJsxSrNehRx-bOdJ6sdmziYvGox-o1EGzf_83lKL3AWRPBFY_PsQeAsFDoE_cTU29SJeyBgQlzmflOYusLKaKvNp1nXKQnvnI4GLnJyTJhMGB9KvhnKsWrvbBt0HIHIDdMxn9qsgEQ8pCOj0BNPFigp0OdgR_5Q0dx2AZ1APVowQpExeZD3T9T-hWqFepFnA4of3n1gZVI8bjW6T2d3iVUviMGvr82YGImt9ie1n3BjbsZcJl_OpPU1Q6OjFg" -H "Accept: application/json, */*" https://172.30.0.1:443/api/v1/watch/namespaces/roadshow/pods?fieldSelector=metadata.name%3Dnationalparks-2-hook-post&resourceVersion=1483
I0924 09:04:40.562480       1 round_trippers.go:318] GET https://172.30.0.1:443/api/v1/watch/namespaces/roadshow/pods?fieldSelector=metadata.name%3Dnationalparks-2-hook-post&resourceVersion=1483 200 OK in 2 milliseconds
I0924 09:04:40.562516       1 round_trippers.go:324] Response Headers:
I0924 09:04:40.562525       1 round_trippers.go:327]     Date: Sat, 24 Sep 2016 09:04:40 GMT
I0924 09:04:40.562638       1 round_trippers.go:327]     Cache-Control: no-store
I0924 09:04:40.563127       1 round_trippers.go:327]     Content-Type: application/json
I0924 09:04:42.739149       1 round_trippers.go:299] curl -k -v -XGET  -H "Accept: application/json, */*" -H "User-Agent: openshift-deploy/v1.3.0+52492b4 (linux/amd64) kubernetes/52492b4" -H "Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJyb2Fkc2hvdyIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJkZXBsb3llci10b2tlbi04N2I0NCIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VydmljZS1hY2NvdW50Lm5hbWUiOiJkZXBsb3llciIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VydmljZS1hY2NvdW50LnVpZCI6ImQ1Mzg5NDdlLTgyMjMtMTFlNi04NDVmLTc2N2U1YjU4YTI3MiIsInN1YiI6InN5c3RlbTpzZXJ2aWNlYWNjb3VudDpyb2Fkc2hvdzpkZXBsb3llciJ9.Wtga7P02Way77cFITzRWM9pz6qlJIj0t3EM2QgXMfM29Z_b12B4jTRi0i0OBUYYpbMntj7loP2N29U1VRGCKJkGH-NJsxSrNehRx-bOdJ6sdmziYvGox-o1EGzf_83lKL3AWRPBFY_PsQeAsFDoE_cTU29SJeyBgQlzmflOYusLKaKvNp1nXKQnvnI4GLnJyTJhMGB9KvhnKsWrvbBt0HIHIDdMxn9qsgEQ8pCOj0BNPFigp0OdgR_5Q0dx2AZ1APVowQpExeZD3T9T-hWqFepFnA4of3n1gZVI8bjW6T2d3iVUviMGvr82YGImt9ie1n3BjbsZcJl_OpPU1Q6OjFg" https://172.30.0.1:443/api/v1/namespaces/roadshow/pods/nationalparks-2-hook-post/log?container=lifecycle&follow=true
I0924 09:04:42.880912       1 round_trippers.go:318] GET https://172.30.0.1:443/api/v1/namespaces/roadshow/pods/nationalparks-2-hook-post/log?container=lifecycle&follow=true 200 OK in 141 milliseconds
I0924 09:04:42.880958       1 round_trippers.go:324] Response Headers:
I0924 09:04:42.880962       1 round_trippers.go:327]     Cache-Control: no-store
I0924 09:04:42.880965       1 round_trippers.go:327]     Content-Type: text/plain
Items inserted in database: 2740
I0924 09:04:42.880970       1 round_trippers.go:327]     Date: Sat, 24 Sep 2016 09:04:42 GMT
--> post: Success
I0924 09:04:43.744774       1 request.go:562] Request Body: {"kind":"Event","apiVersion":"v1","metadata":{"name":"nationalparks.14773701c60a58ed","namespace":"roadshow","creationTimestamp":null},"involvedObject":{"kind":"DeploymentConfig","namespace":"roadshow","name":"nationalparks","uid":"da8b1ae3-8223-11e6-845f-767e5b58a272","apiVersion":"v1","resourceVersion":"1426"},"reason":"Completed","message":"The post-hook for deployment roadshow/nationalparks-2 completed successfully","source":{"component":"nationalparks-2-deploy"},"firstTimestamp":"2016-09-24T09:04:43Z","lastTimestamp":"2016-09-24T09:04:43Z","count":1,"type":"Normal"}
I0924 09:04:43.744963       1 round_trippers.go:299] curl -k -v -XPOST  -H "Content-Type: application/json" -H "Accept: application/json, */*" -H "User-Agent: openshift-deploy/v1.3.0+52492b4 (linux/amd64) kubernetes/52492b4" -H "Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJyb2Fkc2hvdyIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJkZXBsb3llci10b2tlbi04N2I0NCIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VydmljZS1hY2NvdW50Lm5hbWUiOiJkZXBsb3llciIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VydmljZS1hY2NvdW50LnVpZCI6ImQ1Mzg5NDdlLTgyMjMtMTFlNi04NDVmLTc2N2U1YjU4YTI3MiIsInN1YiI6InN5c3RlbTpzZXJ2aWNlYWNjb3VudDpyb2Fkc2hvdzpkZXBsb3llciJ9.Wtga7P02Way77cFITzRWM9pz6qlJIj0t3EM2QgXMfM29Z_b12B4jTRi0i0OBUYYpbMntj7loP2N29U1VRGCKJkGH-NJsxSrNehRx-bOdJ6sdmziYvGox-o1EGzf_83lKL3AWRPBFY_PsQeAsFDoE_cTU29SJeyBgQlzmflOYusLKaKvNp1nXKQnvnI4GLnJyTJhMGB9KvhnKsWrvbBt0HIHIDdMxn9qsgEQ8pCOj0BNPFigp0OdgR_5Q0dx2AZ1APVowQpExeZD3T9T-hWqFepFnA4of3n1gZVI8bjW6T2d3iVUviMGvr82YGImt9ie1n3BjbsZcJl_OpPU1Q6OjFg" https://172.30.0.1:443/api/v1/namespaces/roadshow/events
I0924 09:04:43.748506       1 round_trippers.go:318] POST https://172.30.0.1:443/api/v1/namespaces/roadshow/events 201 Created in 3 milliseconds
I0924 09:04:43.748526       1 round_trippers.go:324] Response Headers:
I0924 09:04:43.748530       1 round_trippers.go:327]     Content-Type: application/json
I0924 09:04:43.748532       1 round_trippers.go:327]     Date: Sat, 24 Sep 2016 09:04:43 GMT
I0924 09:04:43.748535       1 round_trippers.go:327]     Content-Length: 745
I0924 09:04:43.748537       1 round_trippers.go:327]     Cache-Control: no-store
I0924 09:04:43.748603       1 request.go:901] Response Body: {"kind":"Event","apiVersion":"v1","metadata":{"name":"nationalparks.14773701c60a58ed","namespace":"roadshow","selfLink":"/api/v1/namespaces/roadshow/events/nationalparks.14773701c60a58ed","uid":"ef164cb3-8235-11e6-9ae6-d2f2bc615fc6","resourceVersion":"1493","creationTimestamp":"2016-09-24T09:04:43Z"},"involvedObject":{"kind":"DeploymentConfig","namespace":"roadshow","name":"nationalparks","uid":"da8b1ae3-8223-11e6-845f-767e5b58a272","apiVersion":"v1","resourceVersion":"1426"},"reason":"Completed","message":"The post-hook for deployment roadshow/nationalparks-2 completed successfully","source":{"component":"nationalparks-2-deploy"},"firstTimestamp":"2016-09-24T09:04:43Z","lastTimestamp":"2016-09-24T09:04:43Z","count":1,"type":"Normal"}
--> Success

@mfojtik
Copy link
Contributor

mfojtik commented Oct 10, 2016

@jorgemoralespou ok to close this?

@jorgemoralespou
Copy link
Author

@mfojtik Not sure. My problem is due to click synch, but I guess there's an underlying problem that don't finish deployments in 10 minutes if post deployment don't work. I would rather look into why this happens although for me I have no longer problem since I can workaround.
Your take.

@0xmichalis
Copy link
Contributor

My problem is due to click synch, but I guess there's an underlying problem that don't finish deployments in 10 minutes if post deployment don't work.

If the post hook never exits then the deployer pod can never exit because it needs to know the outcome of the hook and act accordingly (based on the failure policy of the hook).

@jorgemoralespou
Copy link
Author

@mfojtik @Kargakis well, for one I think a timeout should be able to set on hooks (and maybe that's another issue that needs to be opened) but then the second is why a curl does not finish? I don't really think it's a problem with the hook itself, but the mechanisms behind the hook.
You can do as you wish with the issue, but I honestly think there's an underlying issue with how hooks are managed as if you look closely to the traces I've pasted, when the hook code hangs (when incorrect times) is executing a curl to tailing the logs and not really executing my hook's code.

@rhcarvalho
Copy link
Contributor

@jorgemoralespou I think this is related to this upstream issue kubernetes/kubernetes#26895

Not documented, but all the timeouts you set for your hooks are being ignored.
Timeouts only work for HTTP and TCP probes. They never worked for "exec"-type hooks.

@0xmichalis
Copy link
Contributor

@jorgemoralespou I think this is related to this upstream issue kubernetes/kubernetes#26895

No, that issue is about container hooks, this issue is about deployment hooks.

@jorgemoralespou the log query being executed by curl is definitely strange. This query is executed by the deployer pod to get the logs from the hook and inline them into the deployer logs but it uses an openshift client and not curl. It seems that time skew affects this but I am not sure how or why.

Regarding a hook timeout, we had discussed this previously with @mfojtik. My reaction back then was negative but eventually we may want to consider setting one as ActiveDeadlineSeconds for the hook.

@smarterclayton
Copy link
Contributor

The overall deadline for deployment should bound hooks. They have to fit
within that time (remaining).

On Oct 11, 2016, at 5:23 AM, Michail Kargakis notifications@github.com
wrote:

@jorgemoralespou https://github.com/jorgemoralespou I think this is
related to this upstream issue kubernetes/kubernetes#26895
kubernetes/kubernetes#26895

No, that issue is about container hooks, this issue is about deployment
hooks.

@jorgemoralespou https://github.com/jorgemoralespou the log query being
executed by curl is definitely strange. This query is executed by the
deployer pod to get the logs from the hook and inline them into the
deployer logs but it uses an openshift client and not curl. It seems that
time skew affects this but I am not sure how or why.

Regarding a hook timeout, we had discussed this previously with @mfojtik
https://github.com/mfojtik. My reaction back then was negative but
eventually we may want to consider setting one as ActiveDeadlineSeconds for
the hook.


You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
#11069 (comment),
or mute the thread
https://github.com/notifications/unsubscribe-auth/ABG_p4_AR7LcL0CQtkZMbP_6aaLF2CtFks5qy1WEgaJpZM4KE4ny
.

@0xmichalis
Copy link
Contributor

That was my initial thought on this. I don't feel strong for either: a
timeout on the hooks would be one more thing new users would need to know
but on the other hand it's more fine grained lifecycle management.

On Wed, Oct 12, 2016 at 5:57 AM, Clayton Coleman notifications@github.com
wrote:

The overall deadline for deployment should bound hooks. They have to fit
within that time (remaining).

On Oct 11, 2016, at 5:23 AM, Michail Kargakis notifications@github.com
wrote:

@jorgemoralespou https://github.com/jorgemoralespou I think this is
related to this upstream issue kubernetes/kubernetes#26895
kubernetes/kubernetes#26895

No, that issue is about container hooks, this issue is about deployment
hooks.

@jorgemoralespou https://github.com/jorgemoralespou the log query being
executed by curl is definitely strange. This query is executed by the
deployer pod to get the logs from the hook and inline them into the
deployer logs but it uses an openshift client and not curl. It seems that
time skew affects this but I am not sure how or why.

Regarding a hook timeout, we had discussed this previously with @mfojtik
https://github.com/mfojtik. My reaction back then was negative but
eventually we may want to consider setting one as ActiveDeadlineSeconds for
the hook.


You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
#11069 (comment),
or mute the thread
<https://github.com/notifications/unsubscribe-auth/ABG_p4_AR7LcL0CQtkZMbP_
6aaLF2CtFks5qy1WEgaJpZM4KE4ny>
.


You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
#11069 (comment),
or mute the thread
https://github.com/notifications/unsubscribe-auth/ADuFf9frqGMUsyC_Lm3GMV419FdoEa0dks5qzFqegaJpZM4KE4ny
.

@mfojtik
Copy link
Contributor

mfojtik commented Oct 12, 2016

agree with @Kargakis, timeouts are bad and might get complex (you have timeout for hooks, deadline for deployment, etc..). I would rather stick to one global timeout, which is a deadline seconds currently.

@jorgemoralespou
Copy link
Author

jorgemoralespou commented Oct 12, 2016

While I'm not going to discuss if implementation wise this is difficult or complex, I think that trying to stick hooks execution in global deployment deadline is good but being able to have fine grained timeouts for hooks can also be good. I would maybe just apply a particular timeout in case it's specified and otherwise just use the deployment deadline to include hook execution. In any case, whether a specific timeout is set, deployment with hooks should never exceed the deadline.

@0xmichalis
Copy link
Contributor

It's not hard implementation-wise, it will just make DeploymentConfigs a
bit harder to understand with all the different timeouts floating around.
Also the other argument is that we should stop adding more features in our
DCs and move any discussion upstream, since there is ongoing work to bring
upstream Deployments into feature parity (or at least close enough so we
can enable running our flows in Kube). That being said I am not against
having timeouts specific to hooks, it's just not something we are desperate
about atm.

On Wed, Oct 12, 2016 at 11:08 AM, Jorge Morales Pou <
notifications@github.com> wrote:

While I'm not going to discuss if implementation wise this is difficult or
complex, I think that trying to stick hooks execution in global deployment
deadline is good but being able to have fine grained timeouts for hooks can
also be good. I would maybe just apply a particular timeout in case it's
specified and otherwise just use the deployment deadline to inClyde hook
execution. In any case, whether a specific timeout is set, deployment with
hooks should never exceed the deadline.


You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
#11069 (comment),
or mute the thread
https://github.com/notifications/unsubscribe-auth/ADuFf4yVdEl6DZzZT_wR5gsfTgVm8g-Cks5qzKODgaJpZM4KE4ny
.

@jorgemoralespou
Copy link
Author

@Kargakis I'm up to see how things go upstream, but there I would make sure this is at least discussed.

@smarterclayton
Copy link
Contributor

I'm not sure we need two timeouts. I'm just saying, when we start a hook
pod, are we correctly setting active deadlines on the hook pod to
deployment active deadline - current time?

On Wed, Oct 12, 2016 at 10:00 AM, Jorge Morales Pou <
notifications@github.com> wrote:

@Kargakis https://github.com/kargakis I'm up to see how things go
upstream, but there I would make sure this is at least discussed.


You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
#11069 (comment),
or mute the thread
https://github.com/notifications/unsubscribe-auth/ABG_p4XDcZzmyl-QATlPjfXAPp1CpqLVks5qzOf1gaJpZM4KE4ny
.

@0xmichalis
Copy link
Contributor

It seems that we are just setting the default activeDeadline currently:

ActiveDeadlineSeconds: &maxDeploymentDurationSeconds,

We need the creation time of the deployer pod inside it if we want to set
activeDeadline - (currentTime - creationTime). Can we get it via the
downward API?

On Thu, Oct 13, 2016 at 12:53 AM, Clayton Coleman notifications@github.com
wrote:

I'm not sure we need two timeouts. I'm just saying, when we start a hook
pod, are we correctly setting active deadlines on the hook pod to
deployment active deadline - current time?

On Wed, Oct 12, 2016 at 10:00 AM, Jorge Morales Pou <
notifications@github.com> wrote:

@Kargakis https://github.com/kargakis I'm up to see how things go
upstream, but there I would make sure this is at least discussed.


You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
<#11069 (comment)
,
or mute the thread
<https://github.com/notifications/unsubscribe-auth/ABG_p4XDcZzmyl-
QATlPjfXAPp1CpqLVks5qzOf1gaJpZM4KE4ny>
.


You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
#11069 (comment),
or mute the thread
https://github.com/notifications/unsubscribe-auth/ADuFf9evXM5A4xfRAFXdbyRF3T3l0yv4ks5qzWTfgaJpZM4KE4ny
.

@0xmichalis
Copy link
Contributor

Opened #11352

@0xmichalis
Copy link
Contributor

Opened #11352

Fixed. Also linking the upstream hooks rfe (kubernetes/kubernetes#14512) and the initial proposal (kubernetes/kubernetes#33545). Closing this.

@smarterclayton
Copy link
Contributor

Start a timer at the beginning of the deployer process and calculate from
there. Doesn't have to be perfect, just close.

On Oct 13, 2016, at 4:42 AM, Michail Kargakis notifications@github.com
wrote:

It seems that we are just setting the default activeDeadline currently:

ActiveDeadlineSeconds: &maxDeploymentDurationSeconds,

We need the creation time of the deployer pod inside it if we want to set
activeDeadline - (currentTime - creationTime). Can we get it via the
downward API?

On Thu, Oct 13, 2016 at 12:53 AM, Clayton Coleman notifications@github.com
wrote:

I'm not sure we need two timeouts. I'm just saying, when we start a hook
pod, are we correctly setting active deadlines on the hook pod to
deployment active deadline - current time?

On Wed, Oct 12, 2016 at 10:00 AM, Jorge Morales Pou <
notifications@github.com> wrote:

@Kargakis https://github.com/kargakis I'm up to see how things go
upstream, but there I would make sure this is at least discussed.


You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
<#11069 (comment)
,
or mute the thread
<https://github.com/notifications/unsubscribe-auth/ABG_p4XDcZzmyl-
QATlPjfXAPp1CpqLVks5qzOf1gaJpZM4KE4ny>
.


You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
#11069 (comment),
or mute the thread
<
https://github.com/notifications/unsubscribe-auth/ADuFf9evXM5A4xfRAFXdbyRF3T3l0yv4ks5qzWTfgaJpZM4KE4ny

.


You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
#11069 (comment),
or mute the thread
https://github.com/notifications/unsubscribe-auth/ABG_p6_ICyaEfLp-rVSF6kJwIGUdHlNUks5qze7igaJpZM4KE4ny
.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

6 participants