cloudbreak_1 | 2017-06-20 08:38:54,451 [containerBootstrapBuilderExecutor-31] call:71 WARN c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Orchestrator component SaltJobIdTracker failed to start, retrying [83/90] Elapsed time: 502 ms, Total elapsed time: 876408 ms, Reason: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:39:04,451 [containerBootstrapBuilderExecutor-31] isExitNeeded:91 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] exitCriteriaModel: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3}, exitCriteria: com.sequenceiq.cloudbreak.core.bootstrap.service.ClusterDeletionBasedExitCriteria@628ed141 cloudbreak_1 | 2017-06-20 08:39:04,451 [containerBootstrapBuilderExecutor-31] isExitNeeded:19 DEBUG c.s.c.c.b.s.ClusterDeletionBasedExitCriteria - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Check isExitNeeded for model: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3} cloudbreak_1 | 2017-06-20 08:39:04,451 [containerBootstrapBuilderExecutor-31] isExitNeeded:94 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] isExitNeeded: false cloudbreak_1 | 2017-06-20 08:39:04,451 [containerBootstrapBuilderExecutor-31] call:60 INFO c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Calling orchestrator bootstrap: SaltJobIdTracker, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:39:04,451 [containerBootstrapBuilderExecutor-31] call:43 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently checking the current state. cloudbreak_1 | 2017-06-20 08:39:05,065 [containerBootstrapBuilderExecutor-31] run:99 INFO c.s.c.o.s.c.SaltConnector - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Salt run response: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[], Target-type=glob, Running=[{ip-10-43-190-155.eu-central-1.compute.internal=8629}, {ip-10-43-190-134.eu-central-1.compute.internal=3920}, {ip-10-43-190-146.eu-central-1.compute.internal=3927}, {ip-10-43-190-136.eu-central-1.compute.internal=3919}, {ip-10-43-190-145.eu-central-1.compute.internal=3922}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:39:05,065 [containerBootstrapBuilderExecutor-31] jobIsRunning:114 INFO c.s.c.o.s.s.SaltStates - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Active salt jobs: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[], Target-type=glob, Running=[{ip-10-43-190-155.eu-central-1.compute.internal=8629}, {ip-10-43-190-134.eu-central-1.compute.internal=3920}, {ip-10-43-190-146.eu-central-1.compute.internal=3927}, {ip-10-43-190-136.eu-central-1.compute.internal=3919}, {ip-10-43-190-145.eu-central-1.compute.internal=3922}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:39:05,065 [containerBootstrapBuilderExecutor-31] checkIsFinished:66 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently, waiting for next polling attempt. cloudbreak_1 | 2017-06-20 08:39:05,066 [containerBootstrapBuilderExecutor-31] call:71 WARN c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Orchestrator component SaltJobIdTracker failed to start, retrying [84/90] Elapsed time: 615 ms, Total elapsed time: 887023 ms, Reason: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:39:15,066 [containerBootstrapBuilderExecutor-31] isExitNeeded:91 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] exitCriteriaModel: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3}, exitCriteria: com.sequenceiq.cloudbreak.core.bootstrap.service.ClusterDeletionBasedExitCriteria@628ed141 cloudbreak_1 | 2017-06-20 08:39:15,066 [containerBootstrapBuilderExecutor-31] isExitNeeded:19 DEBUG c.s.c.c.b.s.ClusterDeletionBasedExitCriteria - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Check isExitNeeded for model: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3} cloudbreak_1 | 2017-06-20 08:39:15,066 [containerBootstrapBuilderExecutor-31] isExitNeeded:94 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] isExitNeeded: false cloudbreak_1 | 2017-06-20 08:39:15,066 [containerBootstrapBuilderExecutor-31] call:60 INFO c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Calling orchestrator bootstrap: SaltJobIdTracker, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:39:15,066 [containerBootstrapBuilderExecutor-31] call:43 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently checking the current state. cloudbreak_1 | 2017-06-20 08:39:15,568 [containerBootstrapBuilderExecutor-31] run:99 INFO c.s.c.o.s.c.SaltConnector - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Salt run response: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[], Target-type=glob, Running=[{ip-10-43-190-146.eu-central-1.compute.internal=3927}, {ip-10-43-190-134.eu-central-1.compute.internal=3920}, {ip-10-43-190-155.eu-central-1.compute.internal=8629}, {ip-10-43-190-136.eu-central-1.compute.internal=3919}, {ip-10-43-190-145.eu-central-1.compute.internal=3922}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:39:15,568 [containerBootstrapBuilderExecutor-31] jobIsRunning:114 INFO c.s.c.o.s.s.SaltStates - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Active salt jobs: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[], Target-type=glob, Running=[{ip-10-43-190-146.eu-central-1.compute.internal=3927}, {ip-10-43-190-134.eu-central-1.compute.internal=3920}, {ip-10-43-190-155.eu-central-1.compute.internal=8629}, {ip-10-43-190-136.eu-central-1.compute.internal=3919}, {ip-10-43-190-145.eu-central-1.compute.internal=3922}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:39:15,569 [containerBootstrapBuilderExecutor-31] checkIsFinished:66 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently, waiting for next polling attempt. cloudbreak_1 | 2017-06-20 08:39:15,569 [containerBootstrapBuilderExecutor-31] call:71 WARN c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Orchestrator component SaltJobIdTracker failed to start, retrying [85/90] Elapsed time: 503 ms, Total elapsed time: 897526 ms, Reason: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:39:25,569 [containerBootstrapBuilderExecutor-31] isExitNeeded:91 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] exitCriteriaModel: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3}, exitCriteria: com.sequenceiq.cloudbreak.core.bootstrap.service.ClusterDeletionBasedExitCriteria@628ed141 cloudbreak_1 | 2017-06-20 08:39:25,569 [containerBootstrapBuilderExecutor-31] isExitNeeded:19 DEBUG c.s.c.c.b.s.ClusterDeletionBasedExitCriteria - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Check isExitNeeded for model: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3} cloudbreak_1 | 2017-06-20 08:39:25,569 [containerBootstrapBuilderExecutor-31] isExitNeeded:94 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] isExitNeeded: false cloudbreak_1 | 2017-06-20 08:39:25,569 [containerBootstrapBuilderExecutor-31] call:60 INFO c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Calling orchestrator bootstrap: SaltJobIdTracker, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:39:25,569 [containerBootstrapBuilderExecutor-31] call:43 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently checking the current state. cloudbreak_1 | 2017-06-20 08:39:26,068 [containerBootstrapBuilderExecutor-31] run:99 INFO c.s.c.o.s.c.SaltConnector - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Salt run response: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[], Target-type=glob, Running=[{ip-10-43-190-155.eu-central-1.compute.internal=8629}, {ip-10-43-190-134.eu-central-1.compute.internal=3920}, {ip-10-43-190-146.eu-central-1.compute.internal=3927}, {ip-10-43-190-136.eu-central-1.compute.internal=3919}, {ip-10-43-190-145.eu-central-1.compute.internal=3922}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:39:26,068 [containerBootstrapBuilderExecutor-31] jobIsRunning:114 INFO c.s.c.o.s.s.SaltStates - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Active salt jobs: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[], Target-type=glob, Running=[{ip-10-43-190-155.eu-central-1.compute.internal=8629}, {ip-10-43-190-134.eu-central-1.compute.internal=3920}, {ip-10-43-190-146.eu-central-1.compute.internal=3927}, {ip-10-43-190-136.eu-central-1.compute.internal=3919}, {ip-10-43-190-145.eu-central-1.compute.internal=3922}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:39:26,068 [containerBootstrapBuilderExecutor-31] checkIsFinished:66 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently, waiting for next polling attempt. cloudbreak_1 | 2017-06-20 08:39:26,068 [containerBootstrapBuilderExecutor-31] call:71 WARN c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Orchestrator component SaltJobIdTracker failed to start, retrying [86/90] Elapsed time: 499 ms, Total elapsed time: 908025 ms, Reason: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:39:36,068 [containerBootstrapBuilderExecutor-31] isExitNeeded:91 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] exitCriteriaModel: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3}, exitCriteria: com.sequenceiq.cloudbreak.core.bootstrap.service.ClusterDeletionBasedExitCriteria@628ed141 cloudbreak_1 | 2017-06-20 08:39:36,068 [containerBootstrapBuilderExecutor-31] isExitNeeded:19 DEBUG c.s.c.c.b.s.ClusterDeletionBasedExitCriteria - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Check isExitNeeded for model: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3} cloudbreak_1 | 2017-06-20 08:39:36,068 [containerBootstrapBuilderExecutor-31] isExitNeeded:94 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] isExitNeeded: false cloudbreak_1 | 2017-06-20 08:39:36,069 [containerBootstrapBuilderExecutor-31] call:60 INFO c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Calling orchestrator bootstrap: SaltJobIdTracker, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:39:36,069 [containerBootstrapBuilderExecutor-31] call:43 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently checking the current state. cloudbreak_1 | 2017-06-20 08:39:36,595 [containerBootstrapBuilderExecutor-31] run:99 INFO c.s.c.o.s.c.SaltConnector - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Salt run response: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[], Target-type=glob, Running=[{ip-10-43-190-155.eu-central-1.compute.internal=8629}, {ip-10-43-190-134.eu-central-1.compute.internal=3920}, {ip-10-43-190-146.eu-central-1.compute.internal=3927}, {ip-10-43-190-136.eu-central-1.compute.internal=3919}, {ip-10-43-190-145.eu-central-1.compute.internal=3922}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:39:36,596 [containerBootstrapBuilderExecutor-31] jobIsRunning:114 INFO c.s.c.o.s.s.SaltStates - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Active salt jobs: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[], Target-type=glob, Running=[{ip-10-43-190-155.eu-central-1.compute.internal=8629}, {ip-10-43-190-134.eu-central-1.compute.internal=3920}, {ip-10-43-190-146.eu-central-1.compute.internal=3927}, {ip-10-43-190-136.eu-central-1.compute.internal=3919}, {ip-10-43-190-145.eu-central-1.compute.internal=3922}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:39:36,596 [containerBootstrapBuilderExecutor-31] checkIsFinished:66 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently, waiting for next polling attempt. cloudbreak_1 | 2017-06-20 08:39:36,596 [containerBootstrapBuilderExecutor-31] call:71 WARN c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Orchestrator component SaltJobIdTracker failed to start, retrying [87/90] Elapsed time: 527 ms, Total elapsed time: 918553 ms, Reason: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:39:46,596 [containerBootstrapBuilderExecutor-31] isExitNeeded:91 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] exitCriteriaModel: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3}, exitCriteria: com.sequenceiq.cloudbreak.core.bootstrap.service.ClusterDeletionBasedExitCriteria@628ed141 cloudbreak_1 | 2017-06-20 08:39:46,596 [containerBootstrapBuilderExecutor-31] isExitNeeded:19 DEBUG c.s.c.c.b.s.ClusterDeletionBasedExitCriteria - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Check isExitNeeded for model: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3} cloudbreak_1 | 2017-06-20 08:39:46,596 [containerBootstrapBuilderExecutor-31] isExitNeeded:94 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] isExitNeeded: false cloudbreak_1 | 2017-06-20 08:39:46,596 [containerBootstrapBuilderExecutor-31] call:60 INFO c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Calling orchestrator bootstrap: SaltJobIdTracker, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:39:46,596 [containerBootstrapBuilderExecutor-31] call:43 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently checking the current state. cloudbreak_1 | 2017-06-20 08:39:47,106 [containerBootstrapBuilderExecutor-31] run:99 INFO c.s.c.o.s.c.SaltConnector - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Salt run response: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[], Target-type=glob, Running=[{ip-10-43-190-155.eu-central-1.compute.internal=8629}, {ip-10-43-190-134.eu-central-1.compute.internal=3920}, {ip-10-43-190-146.eu-central-1.compute.internal=3927}, {ip-10-43-190-136.eu-central-1.compute.internal=3919}, {ip-10-43-190-145.eu-central-1.compute.internal=3922}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:39:47,106 [containerBootstrapBuilderExecutor-31] jobIsRunning:114 INFO c.s.c.o.s.s.SaltStates - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Active salt jobs: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[], Target-type=glob, Running=[{ip-10-43-190-155.eu-central-1.compute.internal=8629}, {ip-10-43-190-134.eu-central-1.compute.internal=3920}, {ip-10-43-190-146.eu-central-1.compute.internal=3927}, {ip-10-43-190-136.eu-central-1.compute.internal=3919}, {ip-10-43-190-145.eu-central-1.compute.internal=3922}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:39:47,106 [containerBootstrapBuilderExecutor-31] checkIsFinished:66 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently, waiting for next polling attempt. cloudbreak_1 | 2017-06-20 08:39:47,106 [containerBootstrapBuilderExecutor-31] call:71 WARN c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Orchestrator component SaltJobIdTracker failed to start, retrying [88/90] Elapsed time: 510 ms, Total elapsed time: 929063 ms, Reason: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:39:57,106 [containerBootstrapBuilderExecutor-31] isExitNeeded:91 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] exitCriteriaModel: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3}, exitCriteria: com.sequenceiq.cloudbreak.core.bootstrap.service.ClusterDeletionBasedExitCriteria@628ed141 cloudbreak_1 | 2017-06-20 08:39:57,106 [containerBootstrapBuilderExecutor-31] isExitNeeded:19 DEBUG c.s.c.c.b.s.ClusterDeletionBasedExitCriteria - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Check isExitNeeded for model: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3} cloudbreak_1 | 2017-06-20 08:39:57,106 [containerBootstrapBuilderExecutor-31] isExitNeeded:94 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] isExitNeeded: false cloudbreak_1 | 2017-06-20 08:39:57,106 [containerBootstrapBuilderExecutor-31] call:60 INFO c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Calling orchestrator bootstrap: SaltJobIdTracker, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:39:57,106 [containerBootstrapBuilderExecutor-31] call:43 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently checking the current state. cloudbreak_1 | 2017-06-20 08:39:57,608 [containerBootstrapBuilderExecutor-31] run:99 INFO c.s.c.o.s.c.SaltConnector - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Salt run response: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[], Target-type=glob, Running=[{ip-10-43-190-155.eu-central-1.compute.internal=8629}, {ip-10-43-190-134.eu-central-1.compute.internal=3920}, {ip-10-43-190-146.eu-central-1.compute.internal=3927}, {ip-10-43-190-136.eu-central-1.compute.internal=3919}, {ip-10-43-190-145.eu-central-1.compute.internal=3922}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:39:57,608 [containerBootstrapBuilderExecutor-31] jobIsRunning:114 INFO c.s.c.o.s.s.SaltStates - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Active salt jobs: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[], Target-type=glob, Running=[{ip-10-43-190-155.eu-central-1.compute.internal=8629}, {ip-10-43-190-134.eu-central-1.compute.internal=3920}, {ip-10-43-190-146.eu-central-1.compute.internal=3927}, {ip-10-43-190-136.eu-central-1.compute.internal=3919}, {ip-10-43-190-145.eu-central-1.compute.internal=3922}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:39:57,609 [containerBootstrapBuilderExecutor-31] checkIsFinished:66 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently, waiting for next polling attempt. cloudbreak_1 | 2017-06-20 08:39:57,609 [containerBootstrapBuilderExecutor-31] call:71 WARN c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Orchestrator component SaltJobIdTracker failed to start, retrying [89/90] Elapsed time: 503 ms, Total elapsed time: 939566 ms, Reason: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:40:07,609 [containerBootstrapBuilderExecutor-31] isExitNeeded:91 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] exitCriteriaModel: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3}, exitCriteria: com.sequenceiq.cloudbreak.core.bootstrap.service.ClusterDeletionBasedExitCriteria@628ed141 cloudbreak_1 | 2017-06-20 08:40:07,609 [containerBootstrapBuilderExecutor-31] isExitNeeded:19 DEBUG c.s.c.c.b.s.ClusterDeletionBasedExitCriteria - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Check isExitNeeded for model: ClusterDeletionBasedExitCriteriaModel{stackId=3, clusterId=3} cloudbreak_1 | 2017-06-20 08:40:07,609 [containerBootstrapBuilderExecutor-31] isExitNeeded:94 DEBUG c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] isExitNeeded: false cloudbreak_1 | 2017-06-20 08:40:07,609 [containerBootstrapBuilderExecutor-31] call:60 INFO c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Calling orchestrator bootstrap: SaltJobIdTracker, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:40:07,609 [containerBootstrapBuilderExecutor-31] call:43 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently checking the current state. cloudbreak_1 | 2017-06-20 08:40:08,105 [containerBootstrapBuilderExecutor-31] run:99 INFO c.s.c.o.s.c.SaltConnector - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Salt run response: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[ip-10-43-190-134.eu-central-1.compute.internal, ip-10-43-190-146.eu-central-1.compute.internal, ip-10-43-190-136.eu-central-1.compute.internal, ip-10-43-190-145.eu-central-1.compute.internal], Target-type=glob, Running=[{ip-10-43-190-155.eu-central-1.compute.internal=8629}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:40:08,105 [containerBootstrapBuilderExecutor-31] jobIsRunning:114 INFO c.s.c.o.s.s.SaltStates - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Active salt jobs: RunningJobsResponse{result=[{20170620083830534774={Function=state.highstate, Returned=[ip-10-43-190-134.eu-central-1.compute.internal, ip-10-43-190-146.eu-central-1.compute.internal, ip-10-43-190-136.eu-central-1.compute.internal, ip-10-43-190-145.eu-central-1.compute.internal], Target-type=glob, Running=[{ip-10-43-190-155.eu-central-1.compute.internal=8629}], User=saltuser, Arguments=[], Target=*}}]} cloudbreak_1 | 2017-06-20 08:40:08,106 [containerBootstrapBuilderExecutor-31] checkIsFinished:66 INFO c.s.c.o.s.p.SaltJobIdTracker - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Job: 20170620083830534774 is running currently, waiting for next polling attempt. cloudbreak_1 | 2017-06-20 08:40:08,106 [containerBootstrapBuilderExecutor-31] call:71 WARN c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Orchestrator component SaltJobIdTracker failed to start, retrying [90/90] Elapsed time: 497 ms, Total elapsed time: 950063 ms, Reason: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt, additional info: SaltJobIdTracker{saltJobRunner=HighStateChecker{BaseSaltJobRunner{target=[10.43.190.136, 10.43.190.145, 10.43.190.134, 10.43.190.146, 10.43.190.155], jid=JobId{jobId='20170620083830534774'}, jobState=IN_PROGRESS}}} cloudbreak_1 | 2017-06-20 08:40:08,106 [containerBootstrapBuilderExecutor-31] call:82 ERROR c.s.c.o.OrchestratorBootstrapRunner - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Orchestrator component failed to start in 90 attempts: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt cloudbreak_1 | 2017-06-20 08:40:08,127 [reactorDispatcher-51] runService:171 ERROR c.s.c.o.s.SaltOrchestrator - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] Error occurred during ambari bootstrap cloudbreak_1 | java.util.concurrent.ExecutionException: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt cloudbreak_1 | at java.util.concurrent.FutureTask.report(FutureTask.java:122) cloudbreak_1 | at java.util.concurrent.FutureTask.get(FutureTask.java:192) cloudbreak_1 | at com.sequenceiq.cloudbreak.orchestrator.salt.SaltOrchestrator.runNewService(SaltOrchestrator.java:287) cloudbreak_1 | at com.sequenceiq.cloudbreak.orchestrator.salt.SaltOrchestrator.runNewService(SaltOrchestrator.java:279) cloudbreak_1 | at com.sequenceiq.cloudbreak.orchestrator.salt.SaltOrchestrator.runService(SaltOrchestrator.java:169) cloudbreak_1 | at com.sequenceiq.cloudbreak.core.bootstrap.service.host.ClusterHostServiceRunner.runAmbariServices(ClusterHostServiceRunner.java:99) cloudbreak_1 | at com.sequenceiq.cloudbreak.core.bootstrap.service.ClusterServiceRunner.runAmbariServices(ClusterServiceRunner.java:78) cloudbreak_1 | at com.sequenceiq.cloudbreak.reactor.handler.orchestration.StartAmbariServicesHandler.accept(StartAmbariServicesHandler.java:35) cloudbreak_1 | at com.sequenceiq.cloudbreak.reactor.handler.orchestration.StartAmbariServicesHandler.accept(StartAmbariServicesHandler.java:18) cloudbreak_1 | at reactor.bus.EventBus$3.accept(EventBus.java:317) cloudbreak_1 | at reactor.bus.EventBus$3.accept(EventBus.java:310) cloudbreak_1 | at reactor.bus.routing.ConsumerFilteringRouter.route(ConsumerFilteringRouter.java:72) cloudbreak_1 | at reactor.bus.routing.TraceableDelegatingRouter.route(TraceableDelegatingRouter.java:51) cloudbreak_1 | at reactor.bus.EventBus.accept(EventBus.java:591) cloudbreak_1 | at reactor.bus.EventBus.accept(EventBus.java:63) cloudbreak_1 | at reactor.core.dispatch.AbstractLifecycleDispatcher.route(AbstractLifecycleDispatcher.java:160) cloudbreak_1 | at reactor.core.dispatch.MultiThreadDispatcher$MultiThreadTask.run(MultiThreadDispatcher.java:74) cloudbreak_1 | at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) cloudbreak_1 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) cloudbreak_1 | at java.lang.Thread.run(Thread.java:745) cloudbreak_1 | Caused by: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt cloudbreak_1 | at com.sequenceiq.cloudbreak.orchestrator.salt.poller.SaltJobIdTracker.call(SaltJobIdTracker.java:54) cloudbreak_1 | at com.sequenceiq.cloudbreak.orchestrator.OrchestratorBootstrapRunner.call(OrchestratorBootstrapRunner.java:61) cloudbreak_1 | at com.sequenceiq.cloudbreak.orchestrator.OrchestratorBootstrapRunner.call(OrchestratorBootstrapRunner.java:14) cloudbreak_1 | at java.util.concurrent.FutureTask.run(FutureTask.java:266) cloudbreak_1 | ... 3 common frames omitted cloudbreak_1 | 2017-06-20 08:40:08,128 [reactorDispatcher-51] accept:70 DEBUG c.s.c.c.f.Flow2Handler - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:CLUSTER] [id:3] [name:chokriholger] flow control event arrived: key: STARTAMBARISERVICESFAILED, flowid: d3d9bad2-0e70-41c7-8360-03b763b8ec57, payload: com.sequenceiq.cloudbreak.reactor.api.event.orchestration.StartAmbariServicesFailed@6a62dcdf cloudbreak_1 | 2017-06-20 08:40:08,157 [reactorDispatcher-51] execute:64 INFO c.s.c.c.f.AbstractAction - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:STACK] [id:3] [name:chokriholger] Stack: 3, flow state: STARTING_AMBARI_SERVICES_STATE, phase: service, execution time 953 sec cloudbreak_1 | 2017-06-20 08:40:08,226 [reactorDispatcher-51] updateClusterStatusByStackId:387 DEBUG c.s.c.s.c.AmbariClusterService - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:STACK] [id:3] [name:chokriholger] Updating cluster status. stackId: 3, status: CREATE_FAILED, statusReason: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: java.util.concurrent.ExecutionException: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt cloudbreak_1 | 2017-06-20 08:40:08,303 [reactorDispatcher-51] fireEventAndLog:24 DEBUG c.s.c.c.f.s.FlowMessageService - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:STACK] [id:3] [name:chokriholger] AMBARI_CLUSTER_CREATE_FAILED [STACK_FLOW_STEP]. cloudbreak_1 | 2017-06-20 08:40:08,303 [reactorDispatcher-51] fireCloudbreakEvent:51 INFO c.s.c.s.e.DefaultCloudbreakEventService - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:STACK] [id:3] [name:chokriholger] Firing Cloudbreak event: CloudbreakEventData{entityId=3, eventType='CREATE_FAILED', eventMessage='Ambari cluster could not be created. Reason: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: java.util.concurrent.ExecutionException: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt'} cloudbreak_1 | 2017-06-20 08:40:08,303 [reactorDispatcher-65] accept:33 INFO c.s.c.s.e.CloudbreakEventHandler - [owner:spring] [type:springLog] [id:] [name:] Handling cloudbreak event: Event{id=null, headers=null, replyTo=null, key=CLOUDBREAK_EVENT, data=CloudbreakEventData{entityId=3, eventType='CREATE_FAILED', eventMessage='Ambari cluster could not be created. Reason: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: java.util.concurrent.ExecutionException: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt'}} cloudbreak_1 | 2017-06-20 08:40:08,304 [reactorDispatcher-51] sendEvent:103 INFO c.s.c.c.f.AbstractAction - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:STACK] [id:3] [name:chokriholger] Triggering event: com.sequenceiq.cloudbreak.reactor.api.event.StackEvent@1620720c cloudbreak_1 | 2017-06-20 08:40:08,304 [reactorDispatcher-65] accept:36 INFO c.s.c.s.e.CloudbreakEventHandler - [owner:undefined] [type:CLOUDBREAKEVENTDATA] [id:undefined] [name:undefined] Persisting data: CloudbreakEventData{entityId=3, eventType='CREATE_FAILED', eventMessage='Ambari cluster could not be created. Reason: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: java.util.concurrent.ExecutionException: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt'} cloudbreak_1 | 2017-06-20 08:40:08,304 [reactorDispatcher-51] stateChanged:125 INFO c.s.c.c.f.c.AbstractFlowConfiguration - [owner:c92284b2-68a2-434b-b698-37090b1e6018] [type:STACK] [id:3] [name:chokriholger] changed from ObjectState [getIds()=[STARTING_AMBARI_SERVICES_STATE], getClass()=class org.springframework.statemachine.state.ObjectState, hashCode()=1893412254, toString()=AbstractState [id=STARTING_AMBARI_SERVICES_STATE, pseudoState=null, deferred=null, entryActions=[com.sequenceiq.cloudbreak.core.flow2.cluster.provision.ClusterCreationActions$3@6a30433f], exitActions=null, regions=[], submachine=null]] to ObjectState [getIds()=[CLUSTER_CREATION_FAILED_STATE], getClass()=class org.springframework.statemachine.state.ObjectState, hashCode()=2044307477, toString()=AbstractState [id=CLUSTER_CREATION_FAILED_STATE, pseudoState=null, deferred=null, entryActions=[com.sequenceiq.cloudbreak.core.flow2.cluster.provision.ClusterCreationActions$7@34927359], exitActions=null, regions=[], submachine=null]] cloudbreak_1 | 2017-06-20 08:40:08,304 [reactorDispatcher-66] accept:70 DEBUG c.s.c.c.f.Flow2Handler - [owner:spring] [type:springLog] [id:] [name:] flow control event arrived: key: CLUSTER_CREATION_FAILHANDLED, flowid: d3d9bad2-0e70-41c7-8360-03b763b8ec57, payload: com.sequenceiq.cloudbreak.reactor.api.event.StackEvent@1620720c cloudbreak_1 | 2017-06-20 08:40:08,305 [reactorDispatcher-65] createStackEvent:66 DEBUG c.s.c.s.e.DefaultCloudbreakEventService - [owner:undefined] [type:CLOUDBREAKEVENTDATA] [id:undefined] [name:undefined] Creating stack event from: CloudbreakEventData{entityId=3, eventType='CREATE_FAILED', eventMessage='Ambari cluster could not be created. Reason: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: java.util.concurrent.ExecutionException: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt'} cloudbreak_1 | 2017-06-20 08:40:08,322 [reactorDispatcher-66] execute:64 INFO c.s.c.c.f.AbstractAction - [owner:spring] [type:springLog] [id:] [name:] Stack: 3, flow state: CLUSTER_CREATION_FAILED_STATE, phase: service, execution time 0 sec cloudbreak_1 | 2017-06-20 08:40:08,322 [reactorDispatcher-66] sendEvent:103 INFO c.s.c.c.f.AbstractAction - [owner:spring] [type:springLog] [id:] [name:] Triggering event: com.sequenceiq.cloudbreak.reactor.api.event.StackEvent@1620720c cloudbreak_1 | 2017-06-20 08:40:08,322 [reactorDispatcher-66] stateChanged:125 INFO c.s.c.c.f.c.AbstractFlowConfiguration - [owner:spring] [type:springLog] [id:] [name:] changed from ObjectState [getIds()=[CLUSTER_CREATION_FAILED_STATE], getClass()=class org.springframework.statemachine.state.ObjectState, hashCode()=2044307477, toString()=AbstractState [id=CLUSTER_CREATION_FAILED_STATE, pseudoState=null, deferred=null, entryActions=[com.sequenceiq.cloudbreak.core.flow2.cluster.provision.ClusterCreationActions$7@34927359], exitActions=null, regions=[], submachine=null]] to ObjectState [getIds()=[FINAL_STATE], getClass()=class org.springframework.statemachine.state.ObjectState, hashCode()=1978474750, toString()=AbstractState [id=FINAL_STATE, pseudoState=org.springframework.statemachine.state.DefaultPseudoState@7f73a4e1, deferred=null, entryActions=[com.sequenceiq.cloudbreak.core.flow2.FlowFinalizeAction@71819fc6], exitActions=null, regions=[], submachine=null]] cloudbreak_1 | 2017-06-20 08:40:08,322 [reactorDispatcher-68] finalizeFlow:95 DEBUG c.s.c.c.f.Flow2Handler - [owner:spring] [type:springLog] [id:] [name:] flow finalizing arrived: id: d3d9bad2-0e70-41c7-8360-03b763b8ec57 cloudbreak_1 | 2017-06-20 08:40:08,322 [reactorDispatcher-66] stop:127 INFO o.s.s.s.LifecycleObjectSupport - [owner:spring] [type:springLog] [id:] [name:] stopped org.springframework.statemachine.support.DefaultStateMachineExecutor@27726922 cloudbreak_1 | 2017-06-20 08:40:08,323 [reactorDispatcher-66] stop:127 INFO o.s.s.s.LifecycleObjectSupport - [owner:spring] [type:springLog] [id:] [name:] stopped STARTING_AMBARI_SERVICES_STATE CLUSTER_CREATION_FAILED_STATE FINAL_STATE BOOTSTRAPPING_MACHINES_STATE COLLECTING_HOST_METADATA_STATE STARTING_AMBARI_STATE INIT_STATE CLUSTER_CREATION_FINISHED_STATE INSTALLING_CLUSTER_STATE / / id=01281fe5-d13d-4790-9ff8-07a43df77de0 cloudbreak_1 | 2017-06-20 08:40:08,336 [reactorDispatcher-65] createStackEvent:71 INFO c.s.c.s.e.DefaultCloudbreakEventService - [owner:undefined] [type:CLOUDBREAKEVENTDATA] [id:undefined] [name:undefined] Created stack event: CloudbreakEvent{id=34, eventType='CREATE_FAILED', eventTimestamp=Tue Jun 20 08:40:08 UTC 2017, eventMessage='Ambari cluster could not be created. Reason: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: java.util.concurrent.ExecutionException: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt', owner='c92284b2-68a2-434b-b698-37090b1e6018', account='seq1234567', cloud='AWS', region='eu-central-1', blueprintName='hdp-small-default', instanceGroup='null', blueprintId=1', stackId=3', clusterId=3', clusterName=chokriholger} cloudbreak_1 | 2017-06-20 08:40:08,336 [reactorDispatcher-65] accept:38 INFO c.s.c.s.e.CloudbreakEventHandler - [owner:undefined] [type:CLOUDBREAKEVENTDATA] [id:undefined] [name:undefined] Sending notification with data: CloudbreakEvent{id=34, eventType='CREATE_FAILED', eventTimestamp=Tue Jun 20 08:40:08 UTC 2017, eventMessage='Ambari cluster could not be created. Reason: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: java.util.concurrent.ExecutionException: com.sequenceiq.cloudbreak.orchestrator.exception.CloudbreakOrchestratorFailedException: Job: JobId{jobId='20170620083830534774'} is running currently, waiting for next polling attempt', owner='c92284b2-68a2-434b-b698-37090b1e6018', account='seq1234567', cloud='AWS', region='eu-central-1', blueprintName='hdp-small-default', instanceGroup='null', blueprintId=1', stackId=3', clusterId=3', clusterName=chokriholger} cloudbreak_1 | 2017-06-20 08:44:49,533 [MessageBroker-1] run:113 INFO o.s.w.s.c.WebSocketMessageBrokerStats - [owner:spring] [type:springLog] [id:] [name:] WebSocketSession[0 current WS(0)-HttpStream(0)-HttpPoll(0), 0 total, 0 closed abnormally (0 connect failure, 0 send limit, 0 transport error)], stompSubProtocol[processed CONNECT(0)-CONNECTED(0)-DISCONNECT(0)], stompBrokerRelay[null], inboundChannel[pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0], outboundChannelpool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0], sockJsScheduler[pool size