Support Questions

Find answers, ask questions, and share your expertise
Announcements
Celebrating as our community reaches 100,000 members! Thank you!

All service can't start, and not have any service log.

avatar
Rising Star

I have a cluster with 5 servers (hostname hadoop-215 to hadoop-219);

 

Today, I changed Hue configuration, but it can't startup.

Then I tried out other services(Impala/HBase thrift server),  both of them can't be started, and have no logs in /var/logs/{service_name}.

 

 

 

 

cdh.png

 

$ tail /var/log/cloudera-scm-server/cloudera-scm-server.log

2015-10-19 13:46:39,857 INFO StaleEntityEviction:com.cloudera.cmf.model.HeartbeatStore: Reaped 1 process heartbeats
2015-10-19 13:46:39,861 INFO StaleEntityEviction:com.cloudera.server.cmf.StaleEntityEvictionThread: Reaped total of 0 deleted commands
2015-10-19 13:46:39,862 INFO StaleEntityEviction:com.cloudera.server.cmf.StaleEntityEvictionThread: Found no commands older than 2013-10-19T05:46:39.861Z to reap.
2015-10-19 13:46:39,863 INFO StaleEntityEviction:com.cloudera.server.cmf.node.NodeScannerService: Reaped 0 requests.
2015-10-19 13:46:39,863 INFO StaleEntityEviction:com.cloudera.server.cmf.node.NodeConfiguratorService: Reaped 0 requests.
2015-10-19 13:46:48,220 INFO 1810859969@scm-web-125735:com.cloudera.cmf.service.ServiceHandlerRegistry: Executing service command Start SvcCmdArgs{targetRoles=[DbRole{id=107, name=hue-HUE_SERVER-b672e8f973cd8ddace77def263871fb0, hostName=hadoop-215}], args=[]}. Service: DbService{id=23, name=hue}
2015-10-19 13:46:48,243 INFO 1810859969@scm-web-125735:com.cloudera.cmf.service.ServiceHandlerRegistry: Executing role command Start BasicCmdArgs{args=[]}. Service: DbService{id=23, name=hue} Role: DbRole{id=107, name=hue-HUE_SERVER-b672e8f973cd8ddace77def263871fb0, hostName=hadoop-215}
2015-10-19 13:46:48,307 INFO 1810859969@scm-web-125735:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: Added BringUp command to service DbService{id=23, name=hue}.
2015-10-19 13:46:48,340 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:46:53,345 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:46:58,350 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:03,354 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:03,434 INFO 1338356818@agentServer-14005:com.cloudera.cmf.command.components.StalenessChecker: No staleness check scheduled, scheduling one in 30 seconds
2015-10-19 13:47:03,456 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:08,460 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:13,465 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:18,471 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:23,476 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:28,481 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:33,434 INFO ScheduledStalenessChecker:com.cloudera.cmf.service.ServiceHandlerRegistry: Executing command ProcessStalenessCheckCommand BasicCmdArgs{args=[First reason why: Process (id=940) has a brand new heartbeat]}.
2015-10-19 13:47:33,440 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:33,949 INFO ProcessStalenessDetector-0:com.cloudera.cmf.service.AbstractRoleHandler: (26 skipped) Client configs of DbService{id=22, name=zookeeper} wanted but not available.
2015-10-19 13:47:33,977 INFO ProcessStalenessDetector-0:com.cloudera.cmf.service.config.components.ProcessStalenessDetector: Staleness check done. Duration: PT0.535S
2015-10-19 13:47:33,982 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:38,989 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:43,993 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:48,998 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:54,003 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:47:59,008 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:48:04,012 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:48:09,017 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:48:14,022 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:48:19,027 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:48:24,032 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:48:29,038 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:48:34,042 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:48:39,054 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:48:44,059 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:48:49,063 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:48:54,068 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:48:59,072 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:49:04,077 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:49:09,081 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:49:14,085 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) is still active on service DbService{id=23, name=hue}.
2015-10-19 13:49:19,090 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: Aborting BringUp command (3097) on service DbService{id=23, name=hue} role DbRole{id=107, name=hue-HUE_SERVER-b672e8f973cd8ddace77def263871fb0, hostName=hadoop-215}.
2015-10-19 13:49:19,098 INFO CommandPusher:com.cloudera.cmf.service.AbstractBringUpBringDownCommands: BringUp command (3096) has finished on service DbService{id=23, name=hue}.
2015-10-19 13:49:33,589 WARN 770526468@agentServer-14007:com.cloudera.server.cmf.AgentProtocolImpl: Received Process Heartbeat for unknown (or duplicate) process. Ignoring. This is expected to happen once after old process eviction or process deletion (as happens in restarts). id=940 name=null host=1db4c618-eee6-49cc-9d8f-475b1fe7fd15/hadoop-215
2015-10-19 13:56:39,865 INFO StaleEntityEviction:com.cloudera.cmf.model.HeartbeatStore: Reaped 1 process heartbeats
2015-10-19 13:56:39,872 INFO StaleEntityEviction:com.cloudera.server.cmf.StaleEntityEvictionThread: Reaped total of 0 deleted commands
2015-10-19 13:56:39,874 INFO StaleEntityEviction:com.cloudera.server.cmf.StaleEntityEvictionThread: Found no commands older than 2013-10-19T05:56:39.872Z to reap.
2015-10-19 13:56:39,875 INFO StaleEntityEviction:com.cloudera.server.cmf.node.NodeScannerService: Reaped 0 requests.
2015-10-19 13:56:39,875 INFO StaleEntityEviction:com.cloudera.server.cmf.node.NodeConfiguratorService: Reaped 0 requests.
2015-10-19 14:00:00,003 INFO com.cloudera.cmf.scheduler-1_Worker-1:com.cloudera.cmf.service.ServiceHandlerRegistry: Executing command GlobalPoolsRefresh BasicCmdArgs{scheduleId=1, scheduledTime=2015-10-19T06:00:00.000Z}.
2015-10-19 14:00:00,140 INFO com.cloudera.cmf.scheduler-1_Worker-1:com.cloudera.cmf.scheduler.CommandDispatcherJob: Skipping scheduled command 'GlobalPoolsRefresh' since it is a noop.

$ tail /var/log/cloudera-scm-agent/cloudera-scm-agent.log

[19/Oct/2015 13:46:48 +0000] 16355 CP Server Thread-6 _cplogging   INFO     192.168.4.215 - - [19/Oct/2015:13:46:48] "GET /heartbeat HTTP/1.1" 200 2 "" "NING/1.0"
[19/Oct/2015 13:46:48 +0000] 16355 MainThread util         INFO     Using generic audit plugin for process hue-HUE_SERVER
[19/Oct/2015 13:46:48 +0000] 16355 MainThread util         INFO     Creating metadata plugin for process hue-HUE_SERVER
[19/Oct/2015 13:46:48 +0000] 16355 MainThread util         INFO     Using specific metadata plugin for process hue-HUE_SERVER
[19/Oct/2015 13:46:48 +0000] 16355 MainThread util         INFO     Using generic metadata plugin for process hue-HUE_SERVER
[19/Oct/2015 13:46:48 +0000] 16355 MainThread __init__     INFO     Instantiating generic monitor for service HUE and role HUE_SERVER
[19/Oct/2015 13:46:48 +0000] 16355 MainThread agent        INFO     Activating Process 940-hue-HUE_SERVER
[19/Oct/2015 13:46:48 +0000] 16355 MainThread agent        INFO     Created /var/run/cloudera-scm-agent/process/940-hue-HUE_SERVER
[19/Oct/2015 13:46:48 +0000] 16355 MainThread agent        INFO     Chowning /var/run/cloudera-scm-agent/process/940-hue-HUE_SERVER to hue (480) hue (479)
[19/Oct/2015 13:46:48 +0000] 16355 MainThread agent        INFO     Chmod'ing /var/run/cloudera-scm-agent/process/940-hue-HUE_SERVER to 0751
[19/Oct/2015 13:46:48 +0000] 16355 MainThread parcel       INFO     prepare_environment begin: {u'GPLEXTRAS': u'5.4.3-1.cdh5.4.3.p0.5', u'CDH': u'5.4.3-1.cdh5.4.3.p0.6', u'KAFKA': u'0.8.2.0-1.kafka1.3.0.p0.29'}, [u'cdh'], [u'cdh-plugin', u'hue-plugin']
[19/Oct/2015 13:46:48 +0000] 16355 MainThread parcel       INFO     The following requested parcels are not available: {}
[19/Oct/2015 13:46:48 +0000] 16355 MainThread parcel       INFO     Obtained tags ['kafka'] for parcel KAFKA
[19/Oct/2015 13:46:48 +0000] 16355 MainThread parcel       INFO     Obtained tags ['cdh', 'impala', 'sentry', 'solr', 'spark'] for parcel CDH
[19/Oct/2015 13:46:48 +0000] 16355 MainThread parcel       INFO     Obtained tags ['cdh-plugin', 'impala-plugin', 'solr-plugin', 'spark-plugin', 'hadoop_lzo'] for parcel GPLEXTRAS
[19/Oct/2015 13:46:48 +0000] 16355 MainThread parcel       INFO     prepare_environment end: {'CDH': '5.4.3-1.cdh5.4.3.p0.6', 'GPLEXTRAS': '5.4.3-1.cdh5.4.3.p0.5'}
[19/Oct/2015 13:46:48 +0000] 16355 MainThread util         INFO     Extracted 35 files and 0 dirs to /var/run/cloudera-scm-agent/process/940-hue-HUE_SERVER.
[19/Oct/2015 13:46:48 +0000] 16355 MainThread agent        INFO     Created /var/run/cloudera-scm-agent/process/940-hue-HUE_SERVER/logs
[19/Oct/2015 13:46:48 +0000] 16355 MainThread agent        INFO     Chowning /var/run/cloudera-scm-agent/process/940-hue-HUE_SERVER/logs to hue (480) hue (479)
[19/Oct/2015 13:46:48 +0000] 16355 MainThread agent        INFO     Chmod'ing /var/run/cloudera-scm-agent/process/940-hue-HUE_SERVER/logs to 0751
[19/Oct/2015 13:46:48 +0000] 16355 MainThread cgroups      INFO     Creating cgroup /var/run/cloudera-scm-agent/cgroups/blkio/940-hue-HUE_SERVER
[19/Oct/2015 13:46:48 +0000] 16355 MainThread cgroups      INFO     Creating cgroup /var/run/cloudera-scm-agent/cgroups/cpuacct/940-hue-HUE_SERVER
[19/Oct/2015 13:46:48 +0000] 16355 MainThread cgroups      INFO     Creating cgroup /var/run/cloudera-scm-agent/cgroups/cpu/940-hue-HUE_SERVER
[19/Oct/2015 13:46:48 +0000] 16355 MainThread cgroups      INFO     Reconfiguring cgroup pseudofile /var/run/cloudera-scm-agent/cgroups/cpu/940-hue-HUE_SERVER/cpu.rt_runtime_us with value 1000
[19/Oct/2015 13:46:48 +0000] 16355 MainThread agent        INFO     reading limits: {u'limit_memlock': None, u'limit_fds': None}
[19/Oct/2015 13:46:48 +0000] 16355 MainThread agent        INFO     Triggering supervisord update.
[19/Oct/2015 13:46:48 +0000] 16355 MainThread navigator_plugin INFO     Scheduling a refresh for Audit Plugin for hue-HUE_SERVER with pipelines []
[19/Oct/2015 13:46:48 +0000] 16355 MainThread navigator_plugin INFO     Scheduling a refresh for Metadata Plugin for hue-HUE_SERVER with pipelines []
[19/Oct/2015 13:46:48 +0000] 16355 MainThread abstract_monitor INFO     Refreshing GenericMonitor HUE-HUE_SERVER for None
[19/Oct/2015 13:46:48 +0000] 16355 MainThread __init__     INFO     New monitor: (<cmf.monitor.generic.GenericMonitor object at 0x416f550>,)
[19/Oct/2015 13:46:51 +0000] 16355 Audit-Plugin navigator_plugin INFO     Refreshing Audit Plugin for hue-HUE_SERVER with pipelines []
[19/Oct/2015 13:46:51 +0000] 16355 Audit-Plugin navigator_plugin_pipeline INFO     Stopping Navigator Plugin Pipeline '' for hue-HUE_SERVER (log dir: None)
[19/Oct/2015 13:46:51 +0000] 16355 Metadata-Plugin navigator_plugin INFO     Refreshing Metadata Plugin for hue-HUE_SERVER with pipelines []
[19/Oct/2015 13:46:51 +0000] 16355 Metadata-Plugin navigator_plugin_pipeline INFO     Stopping Navigator Plugin Pipeline '' for hue-HUE_SERVER (log dir: None)
[19/Oct/2015 13:47:48 +0000] 16355 MonitorDaemon-Scheduler __init__     INFO     Monitor expired: ('GenericMonitor HUE-HUE_SERVER for hue-HUE_SERVER-b672e8f973cd8ddace77def263871fb0',)
[19/Oct/2015 13:49:33 +0000] 16355 MainThread agent        INFO     Deactivating process 940-hue-HUE_SERVER
[19/Oct/2015 13:49:33 +0000] 16355 MainThread agent        INFO     Supervisord doesn't know about process 940-hue-HUE_SERVER. It cannot be deactivated by this Agent, assuming it even exists
[19/Oct/2015 13:49:33 +0000] 16355 MainThread agent        INFO     Deleting process 940-hue-HUE_SERVER
[19/Oct/2015 13:49:33 +0000] 16355 MainThread cgroups      INFO     Destroying cgroup /var/run/cloudera-scm-agent/cgroups/blkio/940-hue-HUE_SERVER
[19/Oct/2015 13:49:33 +0000] 16355 MainThread cgroups      INFO     Destroying cgroup /var/run/cloudera-scm-agent/cgroups/cpuacct/940-hue-HUE_SERVER
[19/Oct/2015 13:49:33 +0000] 16355 MainThread cgroups      INFO     Destroying cgroup /var/run/cloudera-scm-agent/cgroups/cpu/940-hue-HUE_SERVER
[19/Oct/2015 13:49:33 +0000] 16355 MainThread agent        INFO     Triggering supervisord update.
[19/Oct/2015 13:49:33 +0000] 16355 MainThread agent        INFO     Retiring process 940-hue-HUE_SERVER
[19/Oct/2015 13:49:36 +0000] 16355 Audit-Plugin navigator_plugin INFO     stopping Audit Plugin for hue-HUE_SERVER with pipelines []
[19/Oct/2015 13:49:36 +0000] 16355 Audit-Plugin navigator_plugin_pipeline INFO     Stopping Navigator Plugin Pipeline '' for hue-HUE_SERVER (log dir: None)
[19/Oct/2015 13:49:36 +0000] 16355 Metadata-Plugin navigator_plugin INFO     stopping Metadata Plugin for hue-HUE_SERVER with pipelines []
[19/Oct/2015 13:49:36 +0000] 16355 Metadata-Plugin navigator_plugin_pipeline INFO     Stopping Navigator Plugin Pipeline '' for hue-HUE_SERVER (log dir: None)

 

Now, I moved the Hue server to another server, It's start successed.

But the server hadoop-215 is my master server(have namenode, resource manager, hbase master on it), can't be stoped.

 

 

Any body can help me ? 

 

 

 

 

 

 

 

 

 

 

 

 

1 ACCEPTED SOLUTION

avatar
Rising Star
I restarted the cm-agent on the error server. The problem solved..

View solution in original post

1 REPLY 1

avatar
Rising Star
I restarted the cm-agent on the error server. The problem solved..