Skip to content

Instantly share code, notes, and snippets.

@errordeveloper

errordeveloper/kubernetes.log Secret

Last active October 23, 2017 09:01
Show Gist options
  • Save errordeveloper/7d976e17c7a25e59d1d6058854a6a67a to your computer and use it in GitHub Desktop.
Save errordeveloper/7d976e17c7a25e59d1d6058854a6a67a to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
> ./dcos task --completed
NAME HOST USER STATE ID MESOS ID
etcd-0-peer 10.0.2.167 root K etcd-0-peer__40a9cb95-3f0c-4dd1-aa49-ae00a33b5ea5 820ed603-26e9-442b-810b-1d9f44ddb2cd-S5
etcd-1-peer 10.0.1.91 root K etcd-1-peer__3063382f-332e-4b7a-a276-ece28058be79 820ed603-26e9-442b-810b-1d9f44ddb2cd-S3
etcd-2-peer 10.0.2.158 root K etcd-2-peer__b2c81f36-b34f-4a01-a6d6-e7ca7ac343ca 820ed603-26e9-442b-810b-1d9f44ddb2cd-S1
kube-apiserver-0-instance 10.0.1.91 root K kube-apiserver-0-instance__89803260-8307-4199-b07f-a33d8f24f2c9 820ed603-26e9-442b-810b-1d9f44ddb2cd-S3
kube-apiserver-1-instance 10.0.2.158 root K kube-apiserver-1-instance__2c30b24a-aa44-43fb-833a-0b7cfbb13c4b 820ed603-26e9-442b-810b-1d9f44ddb2cd-S1
kube-apiserver-2-instance 10.0.3.179 root K kube-apiserver-2-instance__21fc6333-66a8-42d6-83a2-e235020d7b2f 820ed603-26e9-442b-810b-1d9f44ddb2cd-S2
kube-controller-manager-0-instance 10.0.2.158 root K kube-controller-manager-0-instance__f10a0fd2-a826-42b5-9222-72b7cea7b625 820ed603-26e9-442b-810b-1d9f44ddb2cd-S1
kube-controller-manager-1-instance 10.0.2.167 root F kube-controller-manager-1-instance__af32b6fc-6ed3-4a37-80bb-e16edd385cba 820ed603-26e9-442b-810b-1d9f44ddb2cd-S5
kube-controller-manager-1-instance 10.0.3.179 root K kube-controller-manager-1-instance__342f6c65-7375-4e2f-a741-426fff97aadc 820ed603-26e9-442b-810b-1d9f44ddb2cd-S2
kube-controller-manager-2-instance 10.0.1.91 root F kube-controller-manager-2-instance__8fc2618d-600e-4abb-9f58-0cbe9988e890 820ed603-26e9-442b-810b-1d9f44ddb2cd-S3
kube-controller-manager-2-instance 10.0.1.91 root K kube-controller-manager-2-instance__c90f2656-e833-450e-83e7-16f90a6bb7a6 820ed603-26e9-442b-810b-1d9f44ddb2cd-S3
kube-node-0-kube-proxy 10.0.3.80 root K kube-node-0-kube-proxy__06552b5b-532c-434e-932c-9b89fc802df8 820ed603-26e9-442b-810b-1d9f44ddb2cd-S4
kube-node-0-kubelet 10.0.3.80 root K kube-node-0-kubelet__25873ddf-534f-4dbc-9be0-e63c2fb92b0a 820ed603-26e9-442b-810b-1d9f44ddb2cd-S4
kube-scheduler-0-instance 10.0.3.179 root K kube-scheduler-0-instance__d552c3ff-05a3-48ce-838a-00a3aeb45ee4 820ed603-26e9-442b-810b-1d9f44ddb2cd-S2
kube-scheduler-1-instance 10.0.2.158 root K kube-scheduler-1-instance__fac51342-12d9-48f4-a499-3d9e201b5c4d 820ed603-26e9-442b-810b-1d9f44ddb2cd-S1
kube-scheduler-2-instance 10.0.2.167 root K kube-scheduler-2-instance__74e4ad5e-4d23-4d75-8bbf-1e020eb71948 820ed603-26e9-442b-810b-1d9f44ddb2cd-S5
kubernetes 10.0.1.91 root K kubernetes.415b179f-b7b2-11e7-b31e-ba3d11f657f7 820ed603-26e9-442b-810b-1d9f44ddb2cd-S3
kubernetes 10.0.2.167 root K kubernetes.70c2776e-b732-11e7-b31e-ba3d11f657f7 820ed603-26e9-442b-810b-1d9f44ddb2cd-S5
kubernetes 10.0.2.167 root K kubernetes.771ab491-b7b2-11e7-b31e-ba3d11f657f7 820ed603-26e9-442b-810b-1d9f44ddb2cd-S5
kubernetes 10.0.3.179 root K kubernetes.6f305690-b7b2-11e7-b31e-ba3d11f657f7 820ed603-26e9-442b-810b-1d9f44ddb2cd-S2
> kubectl -s localhost:9000 get nodes
NAME STATUS AGE VERSION
kube-node-0-kubelet.kubernetes.mesos Ready 3h v1.7.7
kube-node-2-kubelet.kubernetes.mesos Ready 3h v1.7.7
Raw
kubernetes.log
INFO 2017-10-23 08:59:38,435 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(103): 1: id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-O43027" } framework_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-0003" } slave_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-S4" } hostname: "10.0.3.80" resources { name: "ports" type: RANGES ranges { range { begin: 1025 end: 1570 } range { begin: 1572 end: 2180 } range { begin: 2182 end: 2378 } range { begin: 2381 end: 3887 } range { begin: 3889 end: 5049 } range { begin: 5052 end: 8079 } range { begin: 8082 end: 8180 } range { begin: 8182 end: 32000 } } allocation_info { role: "kubernetes-role" } } resources { name: "disk" type: SCALAR scalar { value: 33774.0 } allocation_info { role: "kubernetes-role" } } resources { name: "cpus" type: SCALAR scalar { value: 2.15 } allocation_info { role: "kubernetes-role" } } resources { name: "mem" type: SCALAR scalar { value: 12645.0 } allocation_info { role: "kubernetes-role" } } executor_ids { value: "kube-scheduler__6f247262-0dd7-4633-b4fc-02e15a11975d" } executor_ids { value: "kube-controller-manager__a76b2e0d-2f2b-450c-89f2-5da17535e6ee" } executor_ids { value: "etcd__07256781-3267-4222-9037-b50f170f335f" } url { scheme: "http" address { hostname: "10.0.3.80" ip: "10.0.3.80" port: 5051 } path: "/slave(1)" } allocation_info { role: "kubernetes-role" }
INFO 2017-10-23 08:59:38,435 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(55): Initial dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:38,435 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(119): Input dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:38,435 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(120): Plan's dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:38,435 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(66): Processing offers for plan: 'deploy' with relevant dirtied assets: [].
INFO 2017-10-23 08:59:38,435 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(71): Attempting to process candidates: [kube-node-1:[kube-proxy, kubelet]], from plan: deploy
INFO 2017-10-23 08:59:38,435 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:resourceOffers(88): Processing resource offers for step: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:38,436 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(138): Killing tasks for pod instance requirement: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:38,436 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(145): TaskSpecs to launch: [kube-proxy, kubelet]
INFO 2017-10-23 08:59:38,436 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(152): Resource sets to consume: kube-node-1
INFO 2017-10-23 08:59:38,436 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(160): Tasks to kill: [kube-node-1-kube-proxy, kube-node-1-kubelet, kube-node-1-recover]
INFO 2017-10-23 08:59:38,437 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:evaluate(67): Pod: kube-node-1, taskInfos for evaluation.
WARN 2017-10-23 08:59:38,437 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kube-proxy at: Tasks/kube-node-1-kube-proxy/TaskInfo
WARN 2017-10-23 08:59:38,437 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kubelet at: Tasks/kube-node-1-kubelet/TaskInfo
WARN 2017-10-23 08:59:38,437 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-recover at: Tasks/kube-node-1-recover/TaskInfo
INFO 2017-10-23 08:59:38,437 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:getEvaluationPipeline(176): Generating requirement for new pod 'kube-node-1' containing tasks: [kube-proxy, kubelet].
INFO 2017-10-23 08:59:38,447 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 1024.0 }
INFO 2017-10-23 08:59:38,447 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:38,447 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(129): Resource 'disk' requires a CREATE operation
INFO 2017-10-23 08:59:38,447 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(133): Generated 'disk' resource for task: [name: "disk" type: SCALAR scalar { value: 1024.0 } disk { persistence { id: "518ceb34-f27d-406e-b641-10c7150915e6" principal: "kubernetes-principal" } volume { container_path: "var" mode: RW } } reservations { principal: "kubernetes-principal" labels { labels { key: "resource_id" value: "1ab7ac1c-5f7f-4914-b9e0-9cafcaf24e9f" } } role: "kubernetes-role" type: DYNAMIC }]
INFO 2017-10-23 08:59:38,447 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'cpus' matches required value: type: SCALAR scalar { value: 0.1 }
INFO 2017-10-23 08:59:38,447 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'cpus' requires a RESERVE operation
INFO 2017-10-23 08:59:38,448 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 128.0 }
INFO 2017-10-23 08:59:38,448 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:38,448 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'cpus' matches required value: type: SCALAR scalar { value: 0.1 }
INFO 2017-10-23 08:59:38,448 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'cpus' requires a RESERVE operation
INFO 2017-10-23 08:59:38,448 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 32.0 }
INFO 2017-10-23 08:59:38,448 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:38,448 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 256.0 }
INFO 2017-10-23 08:59:38,448 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:38,449 [pool-6-thread-1] com.mesosphere.sdk.offer.MesosResourcePool:consumeReservableMerged(194): Offered quantity of cpus is insufficient: desired type: SCALAR scalar { value: 2.0 }, offered type: SCALAR scalar { value: 1.9499999999999997 }
INFO 2017-10-23 08:59:38,449 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 2048.0 }
INFO 2017-10-23 08:59:38,449 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:38,449 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:evaluate(131): Offer 1, 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43027: failed 1 of 12 evaluation stages:
PASS(ExecutorEvaluationStage): No Executor ID required, generated: 'kube-node__9e14851a-758d-4c4e-8e2b-31d68e36bb65'
PASS(MaxPerHostnameRule): 0/1 tasks matching filter 'RegexMatcher{pattern='kube-node-.*'}' are present on this host
PASS(VolumeEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 1024.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal, type: 'ROOT', container-path: 'var'' with resourceId: 'Optional.empty' and persistenceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'cpus': for resource: 'name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 128.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'cpus': for resource: 'name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 32.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 256.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
FAIL(ResourceEvaluationStage): Offer failed to satisfy: name: cpus, value: type: SCALAR scalar { value: 2.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal with resourceId: Optional.empty
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 2048.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
WARN 2017-10-23 08:59:38,449 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:resourceOffers(114): Unable to find any offers which fulfill requirement provided by step kube-node-1:[kube-proxy, kubelet]: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:38,449 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:updateOfferStatus(92): Updated step 'kube-node-1:[kube-proxy, kubelet] [d5458796-061b-4d1b-b03d-d28b57305ca6]' with 0 recommendations:
INFO 2017-10-23 08:59:38,449 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:setTaskIds(59): Step 'kube-node-1:[kube-proxy, kubelet] [d5458796-061b-4d1b-b03d-d28b57305ca6]' is now waiting for updates for task IDs: {}
INFO 2017-10-23 08:59:38,449 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:setStatus(67): kube-node-1:[kube-proxy, kubelet]: changed status from: PREPARED to: PREPARED (interrupted=false)
INFO 2017-10-23 08:59:38,450 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(80): Updated dirtied offers: []
INFO 2017-10-23 08:59:38,450 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(84): Updated dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:38,450 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(119): Input dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:38,450 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(120): Plan's dirty assets: []
INFO 2017-10-23 08:59:38,450 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(66): Processing offers for plan: 'recovery' with relevant dirtied assets: [kube-node-1:[kube-proxy, kubelet]].
INFO 2017-10-23 08:59:38,450 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:updatePlan(115): Dirty assets for recovery plan consideration: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:41,130 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(186): Found tasks needing recovery: []
INFO 2017-10-23 08:59:41,131 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(202): Found pods needing recovery: []
INFO 2017-10-23 08:59:41,131 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(211): Found recoveries already in progress: []
INFO 2017-10-23 08:59:41,131 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(216): New pods needing recovery: []
INFO 2017-10-23 08:59:41,131 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:setPlan(82): Recovery plan set to: [kube-controller-manager-1:[instance]]
INFO 2017-10-23 08:59:41,131 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(71): Attempting to process candidates: [], from plan: recovery
INFO 2017-10-23 08:59:41,132 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(80): Updated dirtied offers: []
INFO 2017-10-23 08:59:41,132 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(84): Updated dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:41,132 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(59): Skipping interrupted plan: replace
INFO 2017-10-23 08:59:41,132 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(95): Total dirtied offers: []
INFO 2017-10-23 08:59:41,132 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(736): Offers accepted by plan coordinator: []
INFO 2017-10-23 08:59:41,132 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(753): Offers accepted by resource cleaner: []
INFO 2017-10-23 08:59:41,132 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(757): Total accepted offers: []
INFO 2017-10-23 08:59:41,133 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(762): Unused offers to be declined: [820ed603-26e9-442b-810b-1d9f44ddb2cd-O43027]
INFO 2017-10-23 08:59:41,133 [pool-6-thread-1] com.mesosphere.sdk.offer.OfferUtils:declineOffers(55): Declining 1 unused offers:
INFO 2017-10-23 08:59:41,133 [pool-6-thread-1] com.mesosphere.sdk.offer.OfferUtils:lambda$declineOffers$2(58): 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43027
INFO 2017-10-23 08:59:41,133 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(115): Offers in progress: []
INFO 2017-10-23 08:59:41,255 [Thread-5327] com.mesosphere.mesos.HTTPAdapter.MesosToSchedulerDriverAdapter:received(181): Received event of type: OFFERS
INFO 2017-10-23 08:59:41,255 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(101): Processing 1 offer:
INFO 2017-10-23 08:59:41,256 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(103): 1: id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-O43028" } framework_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-0003" } slave_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-S2" } hostname: "10.0.3.179" resources { name: "ports" type: RANGES ranges { range { begin: 1025 end: 2180 } range { begin: 2182 end: 2378 } range { begin: 2381 end: 3887 } range { begin: 3889 end: 5049 } range { begin: 5052 end: 6442 } range { begin: 6444 end: 8079 } range { begin: 8082 end: 8180 } range { begin: 8182 end: 8999 } range { begin: 9001 end: 10586 } range { begin: 10588 end: 32000 } } allocation_info { role: "kubernetes-role" } } resources { name: "disk" type: SCALAR scalar { value: 32750.0 } allocation_info { role: "kubernetes-role" } } resources { name: "cpus" type: SCALAR scalar { value: 0.55 } allocation_info { role: "kubernetes-role" } } resources { name: "mem" type: SCALAR scalar { value: 10469.0 } allocation_info { role: "kubernetes-role" } } executor_ids { value: "kube-node__d9487f51-1b2e-440b-b7ef-d2c241069d36" } executor_ids { value: "kube-apiserver__af6e9289-c6a6-42bc-88cf-ef4db36eae36" } executor_ids { value: "etcd__569ef441-4a92-453c-a3fe-ee24f2324583" } url { scheme: "http" address { hostname: "10.0.3.179" ip: "10.0.3.179" port: 5051 } path: "/slave(1)" } allocation_info { role: "kubernetes-role" }
INFO 2017-10-23 08:59:41,256 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(55): Initial dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:41,256 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(119): Input dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:41,256 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(120): Plan's dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:41,256 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(66): Processing offers for plan: 'deploy' with relevant dirtied assets: [].
INFO 2017-10-23 08:59:41,256 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(71): Attempting to process candidates: [kube-node-1:[kube-proxy, kubelet]], from plan: deploy
INFO 2017-10-23 08:59:41,256 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:resourceOffers(88): Processing resource offers for step: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:41,257 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(138): Killing tasks for pod instance requirement: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:41,257 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(145): TaskSpecs to launch: [kube-proxy, kubelet]
INFO 2017-10-23 08:59:41,257 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(152): Resource sets to consume: kube-node-1
INFO 2017-10-23 08:59:41,257 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(160): Tasks to kill: [kube-node-1-kube-proxy, kube-node-1-kubelet, kube-node-1-recover]
INFO 2017-10-23 08:59:41,257 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:evaluate(67): Pod: kube-node-1, taskInfos for evaluation.
WARN 2017-10-23 08:59:41,257 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kube-proxy at: Tasks/kube-node-1-kube-proxy/TaskInfo
WARN 2017-10-23 08:59:41,258 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kubelet at: Tasks/kube-node-1-kubelet/TaskInfo
WARN 2017-10-23 08:59:41,258 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-recover at: Tasks/kube-node-1-recover/TaskInfo
INFO 2017-10-23 08:59:41,258 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:getEvaluationPipeline(176): Generating requirement for new pod 'kube-node-1' containing tasks: [kube-proxy, kubelet].
INFO 2017-10-23 08:59:41,266 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 1024.0 }
INFO 2017-10-23 08:59:41,266 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:41,266 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(129): Resource 'disk' requires a CREATE operation
INFO 2017-10-23 08:59:41,266 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(133): Generated 'disk' resource for task: [name: "disk" type: SCALAR scalar { value: 1024.0 } disk { persistence { id: "765925a0-ef61-4eb6-b03e-d656ffe5198c" principal: "kubernetes-principal" } volume { container_path: "var" mode: RW } } reservations { principal: "kubernetes-principal" labels { labels { key: "resource_id" value: "ca3a0318-9802-46d2-8d8c-f7e35e1a0949" } } role: "kubernetes-role" type: DYNAMIC }]
INFO 2017-10-23 08:59:41,266 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'cpus' matches required value: type: SCALAR scalar { value: 0.1 }
INFO 2017-10-23 08:59:41,266 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'cpus' requires a RESERVE operation
INFO 2017-10-23 08:59:41,266 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 128.0 }
INFO 2017-10-23 08:59:41,267 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:41,267 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'cpus' matches required value: type: SCALAR scalar { value: 0.1 }
INFO 2017-10-23 08:59:41,267 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'cpus' requires a RESERVE operation
INFO 2017-10-23 08:59:41,267 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 32.0 }
INFO 2017-10-23 08:59:41,267 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:41,267 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 256.0 }
INFO 2017-10-23 08:59:41,267 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:41,267 [pool-6-thread-1] com.mesosphere.sdk.offer.MesosResourcePool:consumeReservableMerged(194): Offered quantity of cpus is insufficient: desired type: SCALAR scalar { value: 2.0 }, offered type: SCALAR scalar { value: 0.3500000000000001 }
INFO 2017-10-23 08:59:41,268 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 2048.0 }
INFO 2017-10-23 08:59:41,268 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:41,268 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:evaluate(131): Offer 1, 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43028: failed 2 of 12 evaluation stages:
PASS(ExecutorEvaluationStage): No Executor ID required, generated: 'kube-node__e97a68ba-d028-4452-9c35-771701f6bab5'
FAIL(MaxPerHostnameRule): 1/1 tasks matching filter 'RegexMatcher{pattern='kube-node-.*'}' are already present on this host
PASS(VolumeEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 1024.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal, type: 'ROOT', container-path: 'var'' with resourceId: 'Optional.empty' and persistenceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'cpus': for resource: 'name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 128.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'cpus': for resource: 'name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 32.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 256.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
FAIL(ResourceEvaluationStage): Offer failed to satisfy: name: cpus, value: type: SCALAR scalar { value: 2.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal with resourceId: Optional.empty
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 2048.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
WARN 2017-10-23 08:59:41,268 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:resourceOffers(114): Unable to find any offers which fulfill requirement provided by step kube-node-1:[kube-proxy, kubelet]: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:41,268 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:updateOfferStatus(92): Updated step 'kube-node-1:[kube-proxy, kubelet] [d5458796-061b-4d1b-b03d-d28b57305ca6]' with 0 recommendations:
INFO 2017-10-23 08:59:41,268 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:setTaskIds(59): Step 'kube-node-1:[kube-proxy, kubelet] [d5458796-061b-4d1b-b03d-d28b57305ca6]' is now waiting for updates for task IDs: {}
INFO 2017-10-23 08:59:41,268 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:setStatus(67): kube-node-1:[kube-proxy, kubelet]: changed status from: PREPARED to: PREPARED (interrupted=false)
INFO 2017-10-23 08:59:41,268 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(80): Updated dirtied offers: []
INFO 2017-10-23 08:59:41,268 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(84): Updated dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:41,269 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(119): Input dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:41,269 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(120): Plan's dirty assets: []
INFO 2017-10-23 08:59:41,269 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(66): Processing offers for plan: 'recovery' with relevant dirtied assets: [kube-node-1:[kube-proxy, kubelet]].
INFO 2017-10-23 08:59:41,269 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:updatePlan(115): Dirty assets for recovery plan consideration: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:44,066 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(186): Found tasks needing recovery: []
INFO 2017-10-23 08:59:44,066 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(202): Found pods needing recovery: []
INFO 2017-10-23 08:59:44,066 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(211): Found recoveries already in progress: []
INFO 2017-10-23 08:59:44,067 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(216): New pods needing recovery: []
INFO 2017-10-23 08:59:44,067 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:setPlan(82): Recovery plan set to: [kube-controller-manager-1:[instance]]
INFO 2017-10-23 08:59:44,067 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(71): Attempting to process candidates: [], from plan: recovery
INFO 2017-10-23 08:59:44,067 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(80): Updated dirtied offers: []
INFO 2017-10-23 08:59:44,067 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(84): Updated dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:44,067 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(59): Skipping interrupted plan: replace
INFO 2017-10-23 08:59:44,067 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(95): Total dirtied offers: []
INFO 2017-10-23 08:59:44,067 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(736): Offers accepted by plan coordinator: []
INFO 2017-10-23 08:59:44,068 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(753): Offers accepted by resource cleaner: []
INFO 2017-10-23 08:59:44,068 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(757): Total accepted offers: []
INFO 2017-10-23 08:59:44,068 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(762): Unused offers to be declined: [820ed603-26e9-442b-810b-1d9f44ddb2cd-O43028]
INFO 2017-10-23 08:59:44,068 [pool-6-thread-1] com.mesosphere.sdk.offer.OfferUtils:declineOffers(55): Declining 1 unused offers:
INFO 2017-10-23 08:59:44,068 [pool-6-thread-1] com.mesosphere.sdk.offer.OfferUtils:lambda$declineOffers$2(58): 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43028
INFO 2017-10-23 08:59:44,068 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(115): Offers in progress: []
INFO 2017-10-23 08:59:44,262 [Thread-5328] com.mesosphere.mesos.HTTPAdapter.MesosToSchedulerDriverAdapter:received(181): Received event of type: OFFERS
INFO 2017-10-23 08:59:44,262 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(101): Processing 3 offers:
INFO 2017-10-23 08:59:44,262 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(103): 1: id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-O43029" } framework_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-0003" } slave_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-S5" } hostname: "10.0.2.167" resources { name: "ports" type: RANGES ranges { range { begin: 1025 end: 2180 } range { begin: 2182 end: 3887 } range { begin: 3889 end: 4039 } range { begin: 4041 end: 5049 } range { begin: 5052 end: 6442 } range { begin: 6444 end: 8079 } range { begin: 8082 end: 8180 } range { begin: 8182 end: 8999 } range { begin: 9001 end: 11190 } range { begin: 11192 end: 32000 } } allocation_info { role: "kubernetes-role" } } resources { name: "disk" type: SCALAR scalar { value: 34798.0 } allocation_info { role: "kubernetes-role" } } resources { name: "cpus" type: SCALAR scalar { value: 1.65 } allocation_info { role: "kubernetes-role" } } resources { name: "mem" type: SCALAR scalar { value: 11621.0 } allocation_info { role: "kubernetes-role" } } executor_ids { value: "kube-controller-manager__bb906f60-9d57-40db-9756-3c4033039b51" } executor_ids { value: "kube-scheduler__ecdb27dd-e087-420d-9061-d47c269131de" } executor_ids { value: "kube-apiserver__12c27d6a-792e-4860-ac7a-15c860ca7273" } url { scheme: "http" address { hostname: "10.0.2.167" ip: "10.0.2.167" port: 5051 } path: "/slave(1)" } allocation_info { role: "kubernetes-role" }
INFO 2017-10-23 08:59:44,263 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(103): 2: id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-O43030" } framework_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-0003" } slave_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-S1" } hostname: "10.0.2.158" resources { name: "ports" type: RANGES ranges { range { begin: 1025 end: 2180 } range { begin: 2182 end: 2378 } range { begin: 2381 end: 3887 } range { begin: 3889 end: 5049 } range { begin: 5052 end: 6442 } range { begin: 6444 end: 8079 } range { begin: 8082 end: 8180 } range { begin: 8182 end: 8999 } range { begin: 9001 end: 31219 } range { begin: 31221 end: 32000 } } allocation_info { role: "kubernetes-role" } } resources { name: "disk" type: SCALAR scalar { value: 33774.0 } allocation_info { role: "kubernetes-role" } } resources { name: "cpus" type: SCALAR scalar { value: 2.15 } allocation_info { role: "kubernetes-role" } } resources { name: "mem" type: SCALAR scalar { value: 12389.0 } allocation_info { role: "kubernetes-role" } } executor_ids { value: "kube-scheduler__8c9e0133-5bee-47ee-a0a4-1ff1f28a6178" } executor_ids { value: "kube-apiserver__6cf0f11a-a049-4670-81d9-0b93f3ab2526" } executor_ids { value: "etcd__9e787f82-fac8-41c6-9084-f46d66f4fd87" } url { scheme: "http" address { hostname: "10.0.2.158" ip: "10.0.2.158" port: 5051 } path: "/slave(1)" } allocation_info { role: "kubernetes-role" }
INFO 2017-10-23 08:59:44,263 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(103): 3: id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-O43031" } framework_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-0003" } slave_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-S3" } hostname: "10.0.1.91" resources { name: "ports" type: RANGES ranges { range { begin: 1025 end: 2180 } range { begin: 2182 end: 3887 } range { begin: 3889 end: 5049 } range { begin: 5052 end: 8079 } range { begin: 8082 end: 8180 } range { begin: 8182 end: 16165 } range { begin: 16167 end: 23379 } range { begin: 23381 end: 32000 } } allocation_info { role: "kubernetes-role" } } resources { name: "disk" type: SCALAR scalar { value: 34030.0 } allocation_info { role: "kubernetes-role" } } resources { name: "cpus" type: SCALAR scalar { value: 0.15 } allocation_info { role: "kubernetes-role" } } resources { name: "mem" type: SCALAR scalar { value: 10245.0 } allocation_info { role: "kubernetes-role" } } executor_ids { value: "kube-node__55d3d915-aaa1-4515-bffb-20141db20440" } executor_ids { value: "kube-controller-manager__080fe78b-2a0f-463f-9b75-fe670657f559" } url { scheme: "http" address { hostname: "10.0.1.91" ip: "10.0.1.91" port: 5051 } path: "/slave(1)" } allocation_info { role: "kubernetes-role" }
INFO 2017-10-23 08:59:44,263 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(55): Initial dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:44,263 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(119): Input dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:44,263 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(120): Plan's dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:44,263 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(66): Processing offers for plan: 'deploy' with relevant dirtied assets: [].
INFO 2017-10-23 08:59:44,263 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(71): Attempting to process candidates: [kube-node-1:[kube-proxy, kubelet]], from plan: deploy
INFO 2017-10-23 08:59:44,264 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:resourceOffers(88): Processing resource offers for step: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:44,264 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(138): Killing tasks for pod instance requirement: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:44,264 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(145): TaskSpecs to launch: [kube-proxy, kubelet]
INFO 2017-10-23 08:59:44,264 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(152): Resource sets to consume: kube-node-1
INFO 2017-10-23 08:59:44,264 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(160): Tasks to kill: [kube-node-1-kube-proxy, kube-node-1-kubelet, kube-node-1-recover]
INFO 2017-10-23 08:59:44,265 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:evaluate(67): Pod: kube-node-1, taskInfos for evaluation.
WARN 2017-10-23 08:59:44,265 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kube-proxy at: Tasks/kube-node-1-kube-proxy/TaskInfo
WARN 2017-10-23 08:59:44,265 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kubelet at: Tasks/kube-node-1-kubelet/TaskInfo
WARN 2017-10-23 08:59:44,265 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-recover at: Tasks/kube-node-1-recover/TaskInfo
INFO 2017-10-23 08:59:44,265 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:getEvaluationPipeline(176): Generating requirement for new pod 'kube-node-1' containing tasks: [kube-proxy, kubelet].
INFO 2017-10-23 08:59:44,275 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 1024.0 }
INFO 2017-10-23 08:59:44,275 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:44,275 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(129): Resource 'disk' requires a CREATE operation
INFO 2017-10-23 08:59:44,276 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(133): Generated 'disk' resource for task: [name: "disk" type: SCALAR scalar { value: 1024.0 } disk { persistence { id: "fb989b99-db4c-4dfe-92a4-898e36aa939e" principal: "kubernetes-principal" } volume { container_path: "var" mode: RW } } reservations { principal: "kubernetes-principal" labels { labels { key: "resource_id" value: "6ff4f63c-4315-4b88-857c-44d7e0abe910" } } role: "kubernetes-role" type: DYNAMIC }]
INFO 2017-10-23 08:59:44,276 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'cpus' matches required value: type: SCALAR scalar { value: 0.1 }
INFO 2017-10-23 08:59:44,276 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'cpus' requires a RESERVE operation
INFO 2017-10-23 08:59:44,276 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 128.0 }
INFO 2017-10-23 08:59:44,276 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:44,276 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'cpus' matches required value: type: SCALAR scalar { value: 0.1 }
INFO 2017-10-23 08:59:44,277 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'cpus' requires a RESERVE operation
INFO 2017-10-23 08:59:44,277 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 32.0 }
INFO 2017-10-23 08:59:44,277 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:44,277 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 256.0 }
INFO 2017-10-23 08:59:44,277 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:44,277 [pool-6-thread-1] com.mesosphere.sdk.offer.MesosResourcePool:consumeReservableMerged(194): Offered quantity of cpus is insufficient: desired type: SCALAR scalar { value: 2.0 }, offered type: SCALAR scalar { value: 1.4499999999999997 }
INFO 2017-10-23 08:59:44,277 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 2048.0 }
INFO 2017-10-23 08:59:44,278 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:44,278 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:evaluate(131): Offer 1, 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43029: failed 1 of 12 evaluation stages:
PASS(ExecutorEvaluationStage): No Executor ID required, generated: 'kube-node__6ac3ed4c-86f8-4bf1-88e0-2a07b175b374'
PASS(MaxPerHostnameRule): 0/1 tasks matching filter 'RegexMatcher{pattern='kube-node-.*'}' are present on this host
PASS(VolumeEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 1024.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal, type: 'ROOT', container-path: 'var'' with resourceId: 'Optional.empty' and persistenceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'cpus': for resource: 'name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 128.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'cpus': for resource: 'name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 32.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 256.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
FAIL(ResourceEvaluationStage): Offer failed to satisfy: name: cpus, value: type: SCALAR scalar { value: 2.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal with resourceId: Optional.empty
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 2048.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
WARN 2017-10-23 08:59:44,278 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kube-proxy at: Tasks/kube-node-1-kube-proxy/TaskInfo
WARN 2017-10-23 08:59:44,278 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kubelet at: Tasks/kube-node-1-kubelet/TaskInfo
WARN 2017-10-23 08:59:44,278 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-recover at: Tasks/kube-node-1-recover/TaskInfo
INFO 2017-10-23 08:59:44,278 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:getEvaluationPipeline(176): Generating requirement for new pod 'kube-node-1' containing tasks: [kube-proxy, kubelet].
INFO 2017-10-23 08:59:44,288 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 1024.0 }
INFO 2017-10-23 08:59:44,288 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:44,288 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(129): Resource 'disk' requires a CREATE operation
INFO 2017-10-23 08:59:44,289 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(133): Generated 'disk' resource for task: [name: "disk" type: SCALAR scalar { value: 1024.0 } disk { persistence { id: "3b24acf1-5321-4f73-9c6b-e2904a5a903f" principal: "kubernetes-principal" } volume { container_path: "var" mode: RW } } reservations { principal: "kubernetes-principal" labels { labels { key: "resource_id" value: "70b41eed-1ef6-4b07-9600-cf450d70c341" } } role: "kubernetes-role" type: DYNAMIC }]
INFO 2017-10-23 08:59:44,289 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'cpus' matches required value: type: SCALAR scalar { value: 0.1 }
INFO 2017-10-23 08:59:44,289 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'cpus' requires a RESERVE operation
INFO 2017-10-23 08:59:44,289 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 128.0 }
INFO 2017-10-23 08:59:44,289 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:44,289 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'cpus' matches required value: type: SCALAR scalar { value: 0.1 }
INFO 2017-10-23 08:59:44,289 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'cpus' requires a RESERVE operation
INFO 2017-10-23 08:59:44,289 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 32.0 }
INFO 2017-10-23 08:59:44,290 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:44,290 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 256.0 }
INFO 2017-10-23 08:59:44,290 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:44,290 [pool-6-thread-1] com.mesosphere.sdk.offer.MesosResourcePool:consumeReservableMerged(194): Offered quantity of cpus is insufficient: desired type: SCALAR scalar { value: 2.0 }, offered type: SCALAR scalar { value: 1.9499999999999997 }
INFO 2017-10-23 08:59:44,290 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 2048.0 }
INFO 2017-10-23 08:59:44,290 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:44,290 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:evaluate(131): Offer 2, 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43030: failed 1 of 12 evaluation stages:
PASS(ExecutorEvaluationStage): No Executor ID required, generated: 'kube-node__26879f64-9e35-4539-ae36-a4adaaa96afa'
PASS(MaxPerHostnameRule): 0/1 tasks matching filter 'RegexMatcher{pattern='kube-node-.*'}' are present on this host
PASS(VolumeEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 1024.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal, type: 'ROOT', container-path: 'var'' with resourceId: 'Optional.empty' and persistenceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'cpus': for resource: 'name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 128.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'cpus': for resource: 'name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 32.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 256.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
FAIL(ResourceEvaluationStage): Offer failed to satisfy: name: cpus, value: type: SCALAR scalar { value: 2.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal with resourceId: Optional.empty
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 2048.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
WARN 2017-10-23 08:59:44,290 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kube-proxy at: Tasks/kube-node-1-kube-proxy/TaskInfo
WARN 2017-10-23 08:59:44,291 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kubelet at: Tasks/kube-node-1-kubelet/TaskInfo
WARN 2017-10-23 08:59:44,291 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-recover at: Tasks/kube-node-1-recover/TaskInfo
INFO 2017-10-23 08:59:44,291 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:getEvaluationPipeline(176): Generating requirement for new pod 'kube-node-1' containing tasks: [kube-proxy, kubelet].
INFO 2017-10-23 08:59:44,298 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 1024.0 }
INFO 2017-10-23 08:59:44,298 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:44,298 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(129): Resource 'disk' requires a CREATE operation
INFO 2017-10-23 08:59:44,298 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(133): Generated 'disk' resource for task: [name: "disk" type: SCALAR scalar { value: 1024.0 } disk { persistence { id: "54defee8-1005-469c-8d60-28e1781b03ee" principal: "kubernetes-principal" } volume { container_path: "var" mode: RW } } reservations { principal: "kubernetes-principal" labels { labels { key: "resource_id" value: "c063b1e6-cc4c-45b7-9ebb-988e0b537538" } } role: "kubernetes-role" type: DYNAMIC }]
INFO 2017-10-23 08:59:44,299 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'cpus' matches required value: type: SCALAR scalar { value: 0.1 }
INFO 2017-10-23 08:59:44,299 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'cpus' requires a RESERVE operation
INFO 2017-10-23 08:59:44,299 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 128.0 }
INFO 2017-10-23 08:59:44,299 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:44,299 [pool-6-thread-1] com.mesosphere.sdk.offer.MesosResourcePool:consumeReservableMerged(194): Offered quantity of cpus is insufficient: desired type: SCALAR scalar { value: 0.1 }, offered type: SCALAR scalar { value: 0.04999999999999999 }
INFO 2017-10-23 08:59:44,299 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 32.0 }
INFO 2017-10-23 08:59:44,299 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:44,299 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 256.0 }
INFO 2017-10-23 08:59:44,300 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:44,300 [pool-6-thread-1] com.mesosphere.sdk.offer.MesosResourcePool:consumeReservableMerged(194): Offered quantity of cpus is insufficient: desired type: SCALAR scalar { value: 2.0 }, offered type: SCALAR scalar { value: 0.04999999999999999 }
INFO 2017-10-23 08:59:44,300 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 2048.0 }
INFO 2017-10-23 08:59:44,300 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:44,300 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:evaluate(131): Offer 3, 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43031: failed 3 of 12 evaluation stages:
PASS(ExecutorEvaluationStage): No Executor ID required, generated: 'kube-node__13554c2f-b574-4b31-9caa-8e2a5dd54407'
FAIL(MaxPerHostnameRule): 1/1 tasks matching filter 'RegexMatcher{pattern='kube-node-.*'}' are already present on this host
PASS(VolumeEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 1024.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal, type: 'ROOT', container-path: 'var'' with resourceId: 'Optional.empty' and persistenceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'cpus': for resource: 'name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 128.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
FAIL(ResourceEvaluationStage): Offer failed to satisfy: name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal with resourceId: Optional.empty
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 32.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 256.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
FAIL(ResourceEvaluationStage): Offer failed to satisfy: name: cpus, value: type: SCALAR scalar { value: 2.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal with resourceId: Optional.empty
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 2048.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
WARN 2017-10-23 08:59:44,300 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:resourceOffers(114): Unable to find any offers which fulfill requirement provided by step kube-node-1:[kube-proxy, kubelet]: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:44,300 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:updateOfferStatus(92): Updated step 'kube-node-1:[kube-proxy, kubelet] [d5458796-061b-4d1b-b03d-d28b57305ca6]' with 0 recommendations:
INFO 2017-10-23 08:59:44,300 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:setTaskIds(59): Step 'kube-node-1:[kube-proxy, kubelet] [d5458796-061b-4d1b-b03d-d28b57305ca6]' is now waiting for updates for task IDs: {}
INFO 2017-10-23 08:59:44,300 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:setStatus(67): kube-node-1:[kube-proxy, kubelet]: changed status from: PREPARED to: PREPARED (interrupted=false)
INFO 2017-10-23 08:59:44,301 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(80): Updated dirtied offers: []
INFO 2017-10-23 08:59:44,301 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(84): Updated dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:44,301 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(119): Input dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:44,301 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(120): Plan's dirty assets: []
INFO 2017-10-23 08:59:44,301 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(66): Processing offers for plan: 'recovery' with relevant dirtied assets: [kube-node-1:[kube-proxy, kubelet]].
INFO 2017-10-23 08:59:44,301 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:updatePlan(115): Dirty assets for recovery plan consideration: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:46,269 [Thread-5329] com.mesosphere.mesos.HTTPAdapter.MesosToSchedulerDriverAdapter:received(181): Received event of type: OFFERS
INFO 2017-10-23 08:59:46,382 [Thread-5330] com.mesosphere.mesos.HTTPAdapter.MesosToSchedulerDriverAdapter:received(181): Received event of type: HEARTBEAT
INFO 2017-10-23 08:59:47,177 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(186): Found tasks needing recovery: []
INFO 2017-10-23 08:59:47,178 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(202): Found pods needing recovery: []
INFO 2017-10-23 08:59:47,178 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(211): Found recoveries already in progress: []
INFO 2017-10-23 08:59:47,178 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(216): New pods needing recovery: []
INFO 2017-10-23 08:59:47,178 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:setPlan(82): Recovery plan set to: [kube-controller-manager-1:[instance]]
INFO 2017-10-23 08:59:47,179 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(71): Attempting to process candidates: [], from plan: recovery
INFO 2017-10-23 08:59:47,179 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(80): Updated dirtied offers: []
INFO 2017-10-23 08:59:47,179 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(84): Updated dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:47,179 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(59): Skipping interrupted plan: replace
INFO 2017-10-23 08:59:47,179 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(95): Total dirtied offers: []
INFO 2017-10-23 08:59:47,179 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(736): Offers accepted by plan coordinator: []
INFO 2017-10-23 08:59:47,180 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(753): Offers accepted by resource cleaner: []
INFO 2017-10-23 08:59:47,180 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(757): Total accepted offers: []
INFO 2017-10-23 08:59:47,180 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(762): Unused offers to be declined: [820ed603-26e9-442b-810b-1d9f44ddb2cd-O43029, 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43030, 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43031]
INFO 2017-10-23 08:59:47,180 [pool-6-thread-1] com.mesosphere.sdk.offer.OfferUtils:declineOffers(55): Declining 3 unused offers:
INFO 2017-10-23 08:59:47,180 [pool-6-thread-1] com.mesosphere.sdk.offer.OfferUtils:lambda$declineOffers$2(58): 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43029
INFO 2017-10-23 08:59:47,180 [pool-6-thread-1] com.mesosphere.sdk.offer.OfferUtils:lambda$declineOffers$2(58): 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43030
INFO 2017-10-23 08:59:47,180 [pool-6-thread-1] com.mesosphere.sdk.offer.OfferUtils:lambda$declineOffers$2(58): 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43031
INFO 2017-10-23 08:59:47,181 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(115): Offers in progress: [value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-O43032"
]
INFO 2017-10-23 08:59:47,181 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(101): Processing 1 offer:
INFO 2017-10-23 08:59:47,181 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(103): 1: id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-O43032" } framework_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-0003" } slave_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-S4" } hostname: "10.0.3.80" resources { name: "ports" type: RANGES ranges { range { begin: 1025 end: 1570 } range { begin: 1572 end: 2180 } range { begin: 2182 end: 2378 } range { begin: 2381 end: 3887 } range { begin: 3889 end: 5049 } range { begin: 5052 end: 8079 } range { begin: 8082 end: 8180 } range { begin: 8182 end: 32000 } } allocation_info { role: "kubernetes-role" } } resources { name: "disk" type: SCALAR scalar { value: 33774.0 } allocation_info { role: "kubernetes-role" } } resources { name: "cpus" type: SCALAR scalar { value: 2.15 } allocation_info { role: "kubernetes-role" } } resources { name: "mem" type: SCALAR scalar { value: 12645.0 } allocation_info { role: "kubernetes-role" } } executor_ids { value: "kube-scheduler__6f247262-0dd7-4633-b4fc-02e15a11975d" } executor_ids { value: "kube-controller-manager__a76b2e0d-2f2b-450c-89f2-5da17535e6ee" } executor_ids { value: "etcd__07256781-3267-4222-9037-b50f170f335f" } url { scheme: "http" address { hostname: "10.0.3.80" ip: "10.0.3.80" port: 5051 } path: "/slave(1)" } allocation_info { role: "kubernetes-role" }
INFO 2017-10-23 08:59:47,181 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(55): Initial dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:47,181 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(119): Input dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:47,182 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(120): Plan's dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:47,182 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(66): Processing offers for plan: 'deploy' with relevant dirtied assets: [].
INFO 2017-10-23 08:59:47,182 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(71): Attempting to process candidates: [kube-node-1:[kube-proxy, kubelet]], from plan: deploy
INFO 2017-10-23 08:59:47,182 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:resourceOffers(88): Processing resource offers for step: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:47,182 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(138): Killing tasks for pod instance requirement: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:47,182 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(145): TaskSpecs to launch: [kube-proxy, kubelet]
INFO 2017-10-23 08:59:47,182 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(152): Resource sets to consume: kube-node-1
INFO 2017-10-23 08:59:47,183 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(160): Tasks to kill: [kube-node-1-kube-proxy, kube-node-1-kubelet, kube-node-1-recover]
INFO 2017-10-23 08:59:47,183 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:evaluate(67): Pod: kube-node-1, taskInfos for evaluation.
WARN 2017-10-23 08:59:47,183 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kube-proxy at: Tasks/kube-node-1-kube-proxy/TaskInfo
WARN 2017-10-23 08:59:47,183 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kubelet at: Tasks/kube-node-1-kubelet/TaskInfo
WARN 2017-10-23 08:59:47,183 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-recover at: Tasks/kube-node-1-recover/TaskInfo
INFO 2017-10-23 08:59:47,183 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:getEvaluationPipeline(176): Generating requirement for new pod 'kube-node-1' containing tasks: [kube-proxy, kubelet].
INFO 2017-10-23 08:59:47,191 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 1024.0 }
INFO 2017-10-23 08:59:47,191 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:47,191 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(129): Resource 'disk' requires a CREATE operation
INFO 2017-10-23 08:59:47,191 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(133): Generated 'disk' resource for task: [name: "disk" type: SCALAR scalar { value: 1024.0 } disk { persistence { id: "cb41bd3f-46b6-4adf-9769-2d4bbd4822c9" principal: "kubernetes-principal" } volume { container_path: "var" mode: RW } } reservations { principal: "kubernetes-principal" labels { labels { key: "resource_id" value: "ad4bb80e-80cd-4f9d-a1f1-f5bf3f7ce251" } } role: "kubernetes-role" type: DYNAMIC }]
INFO 2017-10-23 08:59:47,191 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'cpus' matches required value: type: SCALAR scalar { value: 0.1 }
INFO 2017-10-23 08:59:47,192 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'cpus' requires a RESERVE operation
INFO 2017-10-23 08:59:47,192 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 128.0 }
INFO 2017-10-23 08:59:47,192 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:47,192 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'cpus' matches required value: type: SCALAR scalar { value: 0.1 }
INFO 2017-10-23 08:59:47,192 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'cpus' requires a RESERVE operation
INFO 2017-10-23 08:59:47,192 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 32.0 }
INFO 2017-10-23 08:59:47,192 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:47,192 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 256.0 }
INFO 2017-10-23 08:59:47,193 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:47,193 [pool-6-thread-1] com.mesosphere.sdk.offer.MesosResourcePool:consumeReservableMerged(194): Offered quantity of cpus is insufficient: desired type: SCALAR scalar { value: 2.0 }, offered type: SCALAR scalar { value: 1.9499999999999997 }
INFO 2017-10-23 08:59:47,193 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 2048.0 }
INFO 2017-10-23 08:59:47,193 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:47,193 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:evaluate(131): Offer 1, 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43032: failed 1 of 12 evaluation stages:
PASS(ExecutorEvaluationStage): No Executor ID required, generated: 'kube-node__65acf161-dfe5-480e-a090-79fd679078ba'
PASS(MaxPerHostnameRule): 0/1 tasks matching filter 'RegexMatcher{pattern='kube-node-.*'}' are present on this host
PASS(VolumeEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 1024.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal, type: 'ROOT', container-path: 'var'' with resourceId: 'Optional.empty' and persistenceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'cpus': for resource: 'name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 128.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'cpus': for resource: 'name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 32.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 256.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
FAIL(ResourceEvaluationStage): Offer failed to satisfy: name: cpus, value: type: SCALAR scalar { value: 2.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal with resourceId: Optional.empty
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 2048.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
WARN 2017-10-23 08:59:47,193 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:resourceOffers(114): Unable to find any offers which fulfill requirement provided by step kube-node-1:[kube-proxy, kubelet]: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:47,193 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:updateOfferStatus(92): Updated step 'kube-node-1:[kube-proxy, kubelet] [d5458796-061b-4d1b-b03d-d28b57305ca6]' with 0 recommendations:
INFO 2017-10-23 08:59:47,193 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:setTaskIds(59): Step 'kube-node-1:[kube-proxy, kubelet] [d5458796-061b-4d1b-b03d-d28b57305ca6]' is now waiting for updates for task IDs: {}
INFO 2017-10-23 08:59:47,194 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:setStatus(67): kube-node-1:[kube-proxy, kubelet]: changed status from: PREPARED to: PREPARED (interrupted=false)
INFO 2017-10-23 08:59:47,194 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(80): Updated dirtied offers: []
INFO 2017-10-23 08:59:47,194 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(84): Updated dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:47,194 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(119): Input dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:47,194 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(120): Plan's dirty assets: []
INFO 2017-10-23 08:59:47,194 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(66): Processing offers for plan: 'recovery' with relevant dirtied assets: [kube-node-1:[kube-proxy, kubelet]].
INFO 2017-10-23 08:59:47,194 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:updatePlan(115): Dirty assets for recovery plan consideration: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:49,273 [Thread-5331] com.mesosphere.mesos.HTTPAdapter.MesosToSchedulerDriverAdapter:received(181): Received event of type: OFFERS
INFO 2017-10-23 08:59:49,889 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(186): Found tasks needing recovery: []
INFO 2017-10-23 08:59:49,889 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(202): Found pods needing recovery: []
INFO 2017-10-23 08:59:49,889 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(211): Found recoveries already in progress: []
INFO 2017-10-23 08:59:49,889 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:getRecoveryRequirements(216): New pods needing recovery: []
INFO 2017-10-23 08:59:49,890 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:setPlan(82): Recovery plan set to: [kube-controller-manager-1:[instance]]
INFO 2017-10-23 08:59:49,890 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(71): Attempting to process candidates: [], from plan: recovery
INFO 2017-10-23 08:59:49,890 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(80): Updated dirtied offers: []
INFO 2017-10-23 08:59:49,890 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(84): Updated dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:49,890 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(59): Skipping interrupted plan: replace
INFO 2017-10-23 08:59:49,890 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(95): Total dirtied offers: []
INFO 2017-10-23 08:59:49,890 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(736): Offers accepted by plan coordinator: []
INFO 2017-10-23 08:59:49,890 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(753): Offers accepted by resource cleaner: []
INFO 2017-10-23 08:59:49,890 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(757): Total accepted offers: []
INFO 2017-10-23 08:59:49,891 [pool-6-thread-1] com.mesosphere.sdk.scheduler.DefaultScheduler:processOfferSet(762): Unused offers to be declined: [820ed603-26e9-442b-810b-1d9f44ddb2cd-O43032]
INFO 2017-10-23 08:59:49,891 [pool-6-thread-1] com.mesosphere.sdk.offer.OfferUtils:declineOffers(55): Declining 1 unused offers:
INFO 2017-10-23 08:59:49,891 [pool-6-thread-1] com.mesosphere.sdk.offer.OfferUtils:lambda$declineOffers$2(58): 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43032
INFO 2017-10-23 08:59:49,891 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(115): Offers in progress: [value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-O43033"
]
INFO 2017-10-23 08:59:49,891 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(101): Processing 1 offer:
INFO 2017-10-23 08:59:49,891 [pool-6-thread-1] com.mesosphere.sdk.scheduler.AbstractScheduler:lambda$processOffers$2(103): 1: id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-O43033" } framework_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-0003" } slave_id { value: "820ed603-26e9-442b-810b-1d9f44ddb2cd-S2" } hostname: "10.0.3.179" resources { name: "ports" type: RANGES ranges { range { begin: 1025 end: 2180 } range { begin: 2182 end: 2378 } range { begin: 2381 end: 3887 } range { begin: 3889 end: 5049 } range { begin: 5052 end: 6442 } range { begin: 6444 end: 8079 } range { begin: 8082 end: 8180 } range { begin: 8182 end: 8999 } range { begin: 9001 end: 10586 } range { begin: 10588 end: 32000 } } allocation_info { role: "kubernetes-role" } } resources { name: "disk" type: SCALAR scalar { value: 32750.0 } allocation_info { role: "kubernetes-role" } } resources { name: "cpus" type: SCALAR scalar { value: 0.55 } allocation_info { role: "kubernetes-role" } } resources { name: "mem" type: SCALAR scalar { value: 10469.0 } allocation_info { role: "kubernetes-role" } } executor_ids { value: "kube-node__d9487f51-1b2e-440b-b7ef-d2c241069d36" } executor_ids { value: "kube-apiserver__af6e9289-c6a6-42bc-88cf-ef4db36eae36" } executor_ids { value: "etcd__569ef441-4a92-453c-a3fe-ee24f2324583" } url { scheme: "http" address { hostname: "10.0.3.179" ip: "10.0.3.179" port: 5051 } path: "/slave(1)" } allocation_info { role: "kubernetes-role" }
INFO 2017-10-23 08:59:49,891 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(55): Initial dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:49,891 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(119): Input dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:49,891 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(120): Plan's dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:49,892 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(66): Processing offers for plan: 'deploy' with relevant dirtied assets: [].
INFO 2017-10-23 08:59:49,892 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(71): Attempting to process candidates: [kube-node-1:[kube-proxy, kubelet]], from plan: deploy
INFO 2017-10-23 08:59:49,892 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:resourceOffers(88): Processing resource offers for step: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:49,892 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(138): Killing tasks for pod instance requirement: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:49,892 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(145): TaskSpecs to launch: [kube-proxy, kubelet]
INFO 2017-10-23 08:59:49,892 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(152): Resource sets to consume: kube-node-1
INFO 2017-10-23 08:59:49,892 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:killTasks(160): Tasks to kill: [kube-node-1-kube-proxy, kube-node-1-kubelet, kube-node-1-recover]
INFO 2017-10-23 08:59:49,893 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:evaluate(67): Pod: kube-node-1, taskInfos for evaluation.
WARN 2017-10-23 08:59:49,893 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kube-proxy at: Tasks/kube-node-1-kube-proxy/TaskInfo
WARN 2017-10-23 08:59:49,893 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-kubelet at: Tasks/kube-node-1-kubelet/TaskInfo
WARN 2017-10-23 08:59:49,893 [pool-6-thread-1] com.mesosphere.sdk.state.StateStore:fetchTask(296): No TaskInfo found for the requested name: kube-node-1-recover at: Tasks/kube-node-1-recover/TaskInfo
INFO 2017-10-23 08:59:49,893 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:getEvaluationPipeline(176): Generating requirement for new pod 'kube-node-1' containing tasks: [kube-proxy, kubelet].
INFO 2017-10-23 08:59:49,901 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 1024.0 }
INFO 2017-10-23 08:59:49,901 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:49,901 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(129): Resource 'disk' requires a CREATE operation
INFO 2017-10-23 08:59:49,901 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.VolumeEvaluationStage:evaluate(133): Generated 'disk' resource for task: [name: "disk" type: SCALAR scalar { value: 1024.0 } disk { persistence { id: "eed812a3-0a39-448b-aaa0-23801f008e08" principal: "kubernetes-principal" } volume { container_path: "var" mode: RW } } reservations { principal: "kubernetes-principal" labels { labels { key: "resource_id" value: "3c79b388-8f91-4f06-81e6-a7edb2f615ac" } } role: "kubernetes-role" type: DYNAMIC }]
INFO 2017-10-23 08:59:49,901 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'cpus' matches required value: type: SCALAR scalar { value: 0.1 }
INFO 2017-10-23 08:59:49,901 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'cpus' requires a RESERVE operation
INFO 2017-10-23 08:59:49,902 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 128.0 }
INFO 2017-10-23 08:59:49,902 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:49,902 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'cpus' matches required value: type: SCALAR scalar { value: 0.1 }
INFO 2017-10-23 08:59:49,902 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'cpus' requires a RESERVE operation
INFO 2017-10-23 08:59:49,902 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 32.0 }
INFO 2017-10-23 08:59:49,902 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:49,902 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'disk' matches required value: type: SCALAR scalar { value: 256.0 }
INFO 2017-10-23 08:59:49,902 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'disk' requires a RESERVE operation
INFO 2017-10-23 08:59:49,902 [pool-6-thread-1] com.mesosphere.sdk.offer.MesosResourcePool:consumeReservableMerged(194): Offered quantity of cpus is insufficient: desired type: SCALAR scalar { value: 2.0 }, offered type: SCALAR scalar { value: 0.3500000000000001 }
INFO 2017-10-23 08:59:49,902 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(70): Resource 'mem' matches required value: type: SCALAR scalar { value: 2048.0 }
INFO 2017-10-23 08:59:49,902 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluationUtils:evaluateSimpleResource(77): Resource 'mem' requires a RESERVE operation
INFO 2017-10-23 08:59:49,902 [pool-6-thread-1] com.mesosphere.sdk.offer.evaluate.OfferEvaluator:evaluate(131): Offer 1, 820ed603-26e9-442b-810b-1d9f44ddb2cd-O43033: failed 2 of 12 evaluation stages:
PASS(ExecutorEvaluationStage): No Executor ID required, generated: 'kube-node__8f4ca5f4-525b-4c4c-a3da-014bdd2d3181'
FAIL(MaxPerHostnameRule): 1/1 tasks matching filter 'RegexMatcher{pattern='kube-node-.*'}' are already present on this host
PASS(VolumeEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 1024.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal, type: 'ROOT', container-path: 'var'' with resourceId: 'Optional.empty' and persistenceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'cpus': for resource: 'name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 128.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'cpus': for resource: 'name: cpus, value: type: SCALAR scalar { value: 0.1 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 32.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(ResourceEvaluationStage): Offer contains sufficient 'disk': for resource: 'name: disk, value: type: SCALAR scalar { value: 256.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
FAIL(ResourceEvaluationStage): Offer failed to satisfy: name: cpus, value: type: SCALAR scalar { value: 2.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal with resourceId: Optional.empty
PASS(ResourceEvaluationStage): Offer contains sufficient 'mem': for resource: 'name: mem, value: type: SCALAR scalar { value: 2048.0 }, role: kubernetes-role, pre-reserved-role: *, principal: kubernetes-principal' with resourceId: 'Optional.empty'
PASS(LaunchEvaluationStage): Added launch information to offer requirement
WARN 2017-10-23 08:59:49,903 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanScheduler:resourceOffers(114): Unable to find any offers which fulfill requirement provided by step kube-node-1:[kube-proxy, kubelet]: kube-node-1:[kube-proxy, kubelet]
INFO 2017-10-23 08:59:49,903 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:updateOfferStatus(92): Updated step 'kube-node-1:[kube-proxy, kubelet] [d5458796-061b-4d1b-b03d-d28b57305ca6]' with 0 recommendations:
INFO 2017-10-23 08:59:49,903 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:setTaskIds(59): Step 'kube-node-1:[kube-proxy, kubelet] [d5458796-061b-4d1b-b03d-d28b57305ca6]' is now waiting for updates for task IDs: {}
INFO 2017-10-23 08:59:49,903 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DeploymentStep:setStatus(67): kube-node-1:[kube-proxy, kubelet]: changed status from: PREPARED to: PREPARED (interrupted=false)
INFO 2017-10-23 08:59:49,903 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(80): Updated dirtied offers: []
INFO 2017-10-23 08:59:49,903 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(84): Updated dirtied assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:49,903 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(119): Input dirty assets: [kube-node-1:[kube-proxy, kubelet]]
INFO 2017-10-23 08:59:49,903 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:getRelevantDirtyAssets(120): Plan's dirty assets: []
INFO 2017-10-23 08:59:49,903 [pool-6-thread-1] com.mesosphere.sdk.scheduler.plan.DefaultPlanCoordinator:processOffers(66): Processing offers for plan: 'recovery' with relevant dirtied assets: [kube-node-1:[kube-proxy, kubelet]].
INFO 2017-10-23 08:59:49,903 [pool-6-thread-1] com.mesosphere.sdk.scheduler.recovery.DefaultRecoveryPlanManager:updatePlan(115): Dirty assets for recovery plan consideration: [kube-node-1:[kube-proxy, kubelet]]
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment