[Resolved]Fail: ZooKeeper node /hiveserver2 is not ready yet in Azure Hadoop Cluster

In this article, we will explain how to resolve the “Fail: ZooKeeper node /hiveserver2 is not ready yet” in Azure Hadoop cluster in the Big Data environment.



Error : Fail: ZooKeeper node /hiveserver2 is not ready yet




Traceback (most recent call last):
File "/usr/lib/ambari-agent/lib/resource_management/libraries/functions/decorator.py", line 54, in wrapper
return function(*args, **kwargs)
File "/var/lib/ambari-agent/cache/stacks/HDP/3.0/services/HIVE/package/scripts/hive_service.py", line 189, in wait_for_znode
raise Fail(format("ZooKeeper node /{hive_server2_zookeeper_namespace} is not ready yet"))
Fail: ZooKeeper node /hiveserver2 is not ready yet

The above exception was the cause of the following exception:

Traceback (most recent call last):
File "/var/lib/ambari-agent/cache/stacks/HDP/3.0/services/HIVE/package/scripts/hive_server.py", line 143, in <module>
HiveServer().execute()
File "/usr/lib/ambari-agent/lib/resource_management/libraries/script/script.py", line 352, in execute
method(env)
File "/usr/lib/ambari-agent/lib/resource_management/libraries/script/script.py", line 1006, in restart
self.start(env, upgrade_type=upgrade_type)
File "/var/lib/ambari-agent/cache/stacks/HDP/3.0/services/HIVE/package/scripts/hive_server.py", line 53, in start
hive_service('hiveserver2', action = 'start', upgrade_type=upgrade_type)
File "/var/lib/ambari-agent/cache/stacks/HDP/3.0/services/HIVE/package/scripts/hive_service.py", line 101, in hive_service
wait_for_znode()
File "/usr/lib/ambari-agent/lib/resource_management/libraries/functions/decorator.py", line 62, in wrapper
return function(*args, **kwargs)
File "/var/lib/ambari-agent/cache/stacks/HDP/3.0/services/HIVE/package/scripts/hive_service.py", line 189, in wait_for_znode
raise Fail(format("ZooKeeper node /{hive_server2_zookeeper_namespace} is not ready yet"))
resource_management.core.exceptions.Fail: ZooKeeper node /hiveserver2 is not ready yet
stdout: /var/lib/ambari-agent/data/output/exact.txt

2021-12-18 10:58:10,613 - Stack Feature Version Info: Cluster Stack=3.1, Command Stack=None, Command Version=3.1.6.0-77 -> 3.1.6.0-77
2021-12-18 10:58:10,630 - Using hadoop conf dir: /usr/hdp/3.1.6.0-77/hadoop/conf
2021-12-18 10:58:10,849 - Stack Feature Version Info: Cluster Stack=3.1, Command Stack=None, Command Version=3.1.6.0-77 -> 3.1.6.0-77
2021-12-18 10:58:10,854 - Using hadoop conf dir: /usr/hdp/3.1.6.0-77/hadoop/conf
2021-12-18 10:58:10,855 - Skipping creation of User and Group as host is sys prepped or ignore_groupsusers_create flag is on
2021-12-18 10:58:10,855 - Skipping setting dfs cluster admin and tez view acls as host is sys prepped
2021-12-18 10:58:10,856 - FS Type: HDFS
2021-12-18 10:58:10,856 - Directory['/etc/hadoop'] {'mode': 0755}
2021-12-18 10:58:10,866 - File['/usr/hdp/3.1.6.0-77/hadoop/conf/hadoop-env.sh'] {'content': InlineTemplate(...), 'owner': 'root', 'group': 'hadoop'}
2021-12-18 10:58:10,868 - Directory['/var/lib/ambari-agent/tmp/hadoop_java_io_tmpdir'] {'owner': 'hdfs', 'group': 'hadoop', 'mode': 01777}
2021-12-18 10:58:10,887 - Execute[('setenforce', '0')] {'not_if': '(! which getenforce ) || (which getenforce && getenforce | grep -q Disabled)', 'sudo': True, 'only_if': 'test -f /selinux/enforce'}
2021-12-18 10:58:10,893 - Skipping Execute[('setenforce', '0')] due to not_if
2021-12-18 10:58:10,894 - Directory['/var/log/hadoop'] {'owner': 'root', 'create_parents': True, 'group': 'hadoop', 'mode': 0775, 'cd_access': 'a'}
2021-12-18 10:58:10,896 - Directory['/var/run/hadoop'] {'owner': 'root', 'create_parents': True, 'group': 'root', 'cd_access': 'a'}
2021-12-18 10:58:10,898 - Directory['/var/run/hadoop/hdfs'] {'owner': 'hdfs', 'cd_access': 'a'}
2021-12-18 10:58:10,899 - Directory['/tmp/hadoop-hdfs'] {'owner': 'hdfs', 'create_parents': True, 'cd_access': 'a'}
2021-12-18 10:58:10,902 - File['/usr/hdp/3.1.6.0-77/hadoop/conf/commons-logging.properties'] {'content': Template('commons-logging.properties.j2'), 'owner': 'root'}
2021-12-18 10:58:10,904 - File['/usr/hdp/3.1.6.0-77/hadoop/conf/health_check'] {'content': Template('health_check.j2'), 'owner': 'root'}
2021-12-18 10:58:10,909 - File['/usr/hdp/3.1.6.0-77/hadoop/conf/log4j.properties'] {'content': InlineTemplate(...), 'owner': 'hdfs', 'group': 'hadoop', 'mode': 0644}
Skipping copying of fast-hdfs-resource.jar as host is sys prepped
2021-12-18 10:58:10,917 - File['/usr/hdp/3.1.6.0-77/hadoop/conf/hadoop-metrics2.properties'] {'content': InlineTemplate(...), 'owner': 'hdfs', 'group': 'hadoop'}
2021-12-18 10:58:10,918 - File['/usr/hdp/3.1.6.0-77/hadoop/conf/task-log4j.properties'] {'content': StaticFile('task-log4j.properties'), 'mode': 0755}
2021-12-18 10:58:10,919 - File['/usr/hdp/3.1.6.0-77/hadoop/conf/configuration.xsl'] {'owner': 'hdfs', 'group': 'hadoop'}
2021-12-18 10:58:10,922 - File['/etc/hadoop/conf/topology_mappings.data'] {'owner': 'hdfs', 'content': Template('topology_mappings.data.j2'), 'only_if': 'test -d /etc/hadoop/conf', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:10,926 - File['/etc/hadoop/conf/topology_script.py'] {'content': StaticFile('topology_script.py'), 'only_if': 'test -d /etc/hadoop/conf', 'mode': 0755}
2021-12-18 10:58:10,930 - Skipping unlimited key JCE policy check and setup since the host is sys prepped
2021-12-18 10:58:11,313 - Using hadoop conf dir: /usr/hdp/3.1.6.0-77/hadoop/conf
2021-12-18 10:58:11,323 - call['ambari-python-wrap /usr/bin/hdp-select status hive-server2'] {'timeout': 20}
2021-12-18 10:58:11,346 - call returned (0, 'hive-server2 - 3.1.6.0-77')
2021-12-18 10:58:11,346 - Stack Feature Version Info: Cluster Stack=3.1, Command Stack=None, Command Version=3.1.6.0-77 -> 3.1.6.0-77
2021-12-18 10:58:11,365 - File['/var/lib/ambari-agent/cred/lib/CredentialUtil.jar'] {'content': DownloadSource('ht.com:8080/resources/CredentialUtil.jar'), 'mode': 0755}
2021-12-18 10:58:11,366 - Not downloading the file from .com:8080/resources/CredentialUtil.jar, because /var/lib/ambari-agent/tmp/CredentialUtil.jar already exists
2021-12-18 10:58:12,000 - call['ambari-sudo.sh su hive -l -s /bin/bash -c 'cat /var/run/hive/hive-server.pid 1>/tmp/tmpGIcDSq 2>/tmp/tmp8Z2d2n''] {'quiet': False}
2021-12-18 10:58:12,065 - call returned (0, '')
2021-12-18 10:58:12,065 - get_user_call_output returned (0, u'3683', u'')
2021-12-18 10:58:12,066 - Execute['ambari-sudo.sh kill 3683'] {'not_if': '! (ls /var/run/hive/hive-server.pid >/dev/null 2>&1 && ps -p 3683 >/dev/null 2>&1)'}
2021-12-18 10:58:12,087 - Execute['ambari-sudo.sh kill -9 3683'] {'not_if': '! (ls /var/run/hive/hive-server.pid >/dev/null 2>&1 && ps -p 3683 >/dev/null 2>&1) || ( sleep 5 && ! (ls /var/run/hive/hive-server.pid >/dev/null 2>&1 && ps -p 3683 >/dev/null 2>&1) )', 'ignore_failures': True}
2021-12-18 10:58:17,116 - Skipping Execute['ambari-sudo.sh kill -9 3683'] due to not_if
2021-12-18 10:58:17,117 - Execute['! (ls /var/run/hive/hive-server.pid >/dev/null 2>&1 && ps -p 3683 >/dev/null 2>&1)'] {'tries': 20, 'try_sleep': 3}
2021-12-18 10:58:17,133 - File['/var/run/hive/hive-server.pid'] {'action': ['delete']}
2021-12-18 10:58:17,134 - Deleting File['/var/run/hive/hive-server.pid']
2021-12-18 10:58:17,134 - Pid file /var/run/hive/hive-server.pid is empty or does not exist
2021-12-18 10:58:17,134 - Execute['find /var/log/hive -maxdepth 1 -type f -name '*.out' -exec echo '==> {} <==' \; -exec tail -n 100 {} \;'] {'logoutput': True, 'ignore_failures': True, 'user': 'hive'}
==> /var/log/hive/hive.out <==
2021-12-18 10:44:53,334 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_8 to /var/log/hive/hivemetastore.log.2021-12-18_7 with Files.move
2021-12-18 10:44:53,335 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_9 to /var/log/hive/hivemetastore.log.2021-12-18_8, renameEmptyFiles=true]
2021-12-18 10:44:53,335 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_9 to /var/log/hive/hivemetastore.log.2021-12-18_8 with Files.move
2021-12-18 10:44:53,335 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_10 to /var/log/hive/hivemetastore.log.2021-12-18_9, renameEmptyFiles=true]
2021-12-18 10:44:53,335 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_10 to /var/log/hive/hivemetastore.log.2021-12-18_9 with Files.move
2021-12-18 10:44:53,335 pool-5-thread-2 TRACE DefaultRolloverStrategy.purge() took 4.0 milliseconds
2021-12-18 10:44:53,335 pool-5-thread-2 DEBUG RollingFileManager executing synchronous FileRenameAction[/var/log/hive/hivemetastore.log to /var/log/hive/hivemetastore.log.2021-12-18_10, renameEmptyFiles=false]
2021-12-18 10:44:53,335 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log to /var/log/hive/hivemetastore.log.2021-12-18_10 with Files.move
2021-12-18 10:45:15,373 pool-5-thread-2 DEBUG Eligible files: {1=/var/log/hive/hivemetastore.log.2021-12-18_1, 2=/var/log/hive/hivemetastore.log.2021-12-18_2, 3=/var/log/hive/hivemetastore.log.2021-12-18_3, 4=/var/log/hive/hivemetastore.log.2021-12-18_4, 5=/var/log/hive/hivemetastore.log.2021-12-18_5, 6=/var/log/hive/hivemetastore.log.2021-12-18_6, 7=/var/log/hive/hivemetastore.log.2021-12-18_7, 8=/var/log/hive/hivemetastore.log.2021-12-18_8, 9=/var/log/hive/hivemetastore.log.2021-12-18_9, 10=/var/log/hive/hivemetastore.log.2021-12-18_10}
2021-12-18 10:45:15,373 pool-5-thread-2 DEBUG Deleting /var/log/hive/hivemetastore.log.2021-12-18_1
2021-12-18 10:45:15,375 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_2 to /var/log/hive/hivemetastore.log.2021-12-18_1, renameEmptyFiles=true]
2021-12-18 10:45:15,376 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_2 to /var/log/hive/hivemetastore.log.2021-12-18_1 with Files.move
2021-12-18 10:45:15,376 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_3 to /var/log/hive/hivemetastore.log.2021-12-18_2, renameEmptyFiles=true]
2021-12-18 10:45:15,376 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_3 to /var/log/hive/hivemetastore.log.2021-12-18_2 with Files.move
2021-12-18 10:45:15,376 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_4 to /var/log/hive/hivemetastore.log.2021-12-18_3, renameEmptyFiles=true]
2021-12-18 10:45:15,376 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_4 to /var/log/hive/hivemetastore.log.2021-12-18_3 with Files.move
2021-12-18 10:45:15,376 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_5 to /var/log/hive/hivemetastore.log.2021-12-18_4, renameEmptyFiles=true]
2021-12-18 10:45:15,376 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_5 to /var/log/hive/hivemetastore.log.2021-12-18_4 with Files.move
2021-12-18 10:45:15,376 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_6 to /var/log/hive/hivemetastore.log.2021-12-18_5, renameEmptyFiles=true]
2021-12-18 10:45:15,377 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_6 to /var/log/hive/hivemetastore.log.2021-12-18_5 with Files.move
2021-12-18 10:45:15,377 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_7 to /var/log/hive/hivemetastore.log.2021-12-18_6, renameEmptyFiles=true]
2021-12-18 10:45:15,377 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_7 to /var/log/hive/hivemetastore.log.2021-12-18_6 with Files.move
2021-12-18 10:45:15,377 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_8 to /var/log/hive/hivemetastore.log.2021-12-18_7, renameEmptyFiles=true]
2021-12-18 10:45:15,377 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_8 to /var/log/hive/hivemetastore.log.2021-12-18_7 with Files.move
2021-12-18 10:45:15,377 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_9 to /var/log/hive/hivemetastore.log.2021-12-18_8, renameEmptyFiles=true]
2021-12-18 10:45:15,377 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_9 to /var/log/hive/hivemetastore.log.2021-12-18_8 with Files.move
2021-12-18 10:45:15,377 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_10 to /var/log/hive/hivemetastore.log.2021-12-18_9, renameEmptyFiles=true]
2021-12-18 10:45:15,378 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_10 to /var/log/hive/hivemetastore.log.2021-12-18_9 with Files.move
2021-12-18 10:45:15,378 pool-5-thread-2 TRACE DefaultRolloverStrategy.purge() took 4.0 milliseconds
2021-12-18 10:45:15,378 pool-5-thread-2 DEBUG RollingFileManager executing synchronous FileRenameAction[/var/log/hive/hivemetastore.log to /var/log/hive/hivemetastore.log.2021-12-18_10, renameEmptyFiles=false]
2021-12-18 10:45:15,378 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log to /var/log/hive/hivemetastore.log.2021-12-18_10 with Files.move
2021-12-18 10:45:43,634 pool-5-thread-2 DEBUG Eligible files: {1=/var/log/hive/hivemetastore.log.2021-12-18_1, 2=/var/log/hive/hivemetastore.log.2021-12-18_2, 3=/var/log/hive/hivemetastore.log.2021-12-18_3, 4=/var/log/hive/hivemetastore.log.2021-12-18_4, 5=/var/log/hive/hivemetastore.log.2021-12-18_5, 6=/var/log/hive/hivemetastore.log.2021-12-18_6, 7=/var/log/hive/hivemetastore.log.2021-12-18_7, 8=/var/log/hive/hivemetastore.log.2021-12-18_8, 9=/var/log/hive/hivemetastore.log.2021-12-18_9, 10=/var/log/hive/hivemetastore.log.2021-12-18_10}
2021-12-18 10:45:43,634 pool-5-thread-2 DEBUG Deleting /var/log/hive/hivemetastore.log.2021-12-18_1
2021-12-18 10:45:43,636 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_2 to /var/log/hive/hivemetastore.log.2021-12-18_1, renameEmptyFiles=true]
2021-12-18 10:45:43,636 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_2 to /var/log/hive/hivemetastore.log.2021-12-18_1 with Files.move
2021-12-18 10:45:43,636 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_3 to /var/log/hive/hivemetastore.log.2021-12-18_2, renameEmptyFiles=true]
2021-12-18 10:45:43,636 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_3 to /var/log/hive/hivemetastore.log.2021-12-18_2 with Files.move
2021-12-18 10:45:43,636 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_4 to /var/log/hive/hivemetastore.log.2021-12-18_3, renameEmptyFiles=true]
2021-12-18 10:45:43,637 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_4 to /var/log/hive/hivemetastore.log.2021-12-18_3 with Files.move
2021-12-18 10:45:43,637 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_5 to /var/log/hive/hivemetastore.log.2021-12-18_4, renameEmptyFiles=true]
2021-12-18 10:45:43,637 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_5 to /var/log/hive/hivemetastore.log.2021-12-18_4 with Files.move
2021-12-18 10:45:43,637 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_6 to /var/log/hive/hivemetastore.log.2021-12-18_5, renameEmptyFiles=true]
2021-12-18 10:45:43,637 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_6 to /var/log/hive/hivemetastore.log.2021-12-18_5 with Files.move
2021-12-18 10:45:43,637 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_7 to /var/log/hive/hivemetastore.log.2021-12-18_6, renameEmptyFiles=true]
2021-12-18 10:45:43,637 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_7 to /var/log/hive/hivemetastore.log.2021-12-18_6 with Files.move
2021-12-18 10:45:43,638 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_8 to /var/log/hive/hivemetastore.log.2021-12-18_7, renameEmptyFiles=true]
2021-12-18 10:45:43,638 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_8 to /var/log/hive/hivemetastore.log.2021-12-18_7 with Files.move
2021-12-18 10:45:43,638 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_9 to /var/log/hive/hivemetastore.log.2021-12-18_8, renameEmptyFiles=true]
2021-12-18 10:45:43,638 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_9 to /var/log/hive/hivemetastore.log.2021-12-18_8 with Files.move
2021-12-18 10:45:43,638 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_10 to /var/log/hive/hivemetastore.log.2021-12-18_9, renameEmptyFiles=true]
2021-12-18 10:45:43,639 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_10 to /var/log/hive/hivemetastore.log.2021-12-18_9 with Files.move
2021-12-18 10:45:43,639 pool-5-thread-2 TRACE DefaultRolloverStrategy.purge() took 5.0 milliseconds
2021-12-18 10:45:43,639 pool-5-thread-2 DEBUG RollingFileManager executing synchronous FileRenameAction[/var/log/hive/hivemetastore.log to /var/log/hive/hivemetastore.log.2021-12-18_10, renameEmptyFiles=false]
2021-12-18 10:45:43,640 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log to /var/log/hive/hivemetastore.log.2021-12-18_10 with Files.move
2021-12-18 10:46:15,679 pool-5-thread-2 DEBUG Eligible files: {1=/var/log/hive/hivemetastore.log.2021-12-18_1, 2=/var/log/hive/hivemetastore.log.2021-12-18_2, 3=/var/log/hive/hivemetastore.log.2021-12-18_3, 4=/var/log/hive/hivemetastore.log.2021-12-18_4, 5=/var/log/hive/hivemetastore.log.2021-12-18_5, 6=/var/log/hive/hivemetastore.log.2021-12-18_6, 7=/var/log/hive/hivemetastore.log.2021-12-18_7, 8=/var/log/hive/hivemetastore.log.2021-12-18_8, 9=/var/log/hive/hivemetastore.log.2021-12-18_9, 10=/var/log/hive/hivemetastore.log.2021-12-18_10}
2021-12-18 10:46:15,679 pool-5-thread-2 DEBUG Deleting /var/log/hive/hivemetastore.log.2021-12-18_1
2021-12-18 10:46:15,681 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_2 to /var/log/hive/hivemetastore.log.2021-12-18_1, renameEmptyFiles=true]
2021-12-18 10:46:15,681 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_2 to /var/log/hive/hivemetastore.log.2021-12-18_1 with Files.move
2021-12-18 10:46:15,681 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_3 to /var/log/hive/hivemetastore.log.2021-12-18_2, renameEmptyFiles=true]
2021-12-18 10:46:15,681 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_3 to /var/log/hive/hivemetastore.log.2021-12-18_2 with Files.move
2021-12-18 10:46:15,681 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_4 to /var/log/hive/hivemetastore.log.2021-12-18_3, renameEmptyFiles=true]
2021-12-18 10:46:15,682 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_4 to /var/log/hive/hivemetastore.log.2021-12-18_3 with Files.move
2021-12-18 10:46:15,682 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_5 to /var/log/hive/hivemetastore.log.2021-12-18_4, renameEmptyFiles=true]
2021-12-18 10:46:15,682 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_5 to /var/log/hive/hivemetastore.log.2021-12-18_4 with Files.move
2021-12-18 10:46:15,682 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_6 to /var/log/hive/hivemetastore.log.2021-12-18_5, renameEmptyFiles=true]
2021-12-18 10:46:15,682 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_6 to /var/log/hive/hivemetastore.log.2021-12-18_5 with Files.move
2021-12-18 10:46:15,682 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_7 to /var/log/hive/hivemetastore.log.2021-12-18_6, renameEmptyFiles=true]
2021-12-18 10:46:15,682 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_7 to /var/log/hive/hivemetastore.log.2021-12-18_6 with Files.move
2021-12-18 10:46:15,682 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_8 to /var/log/hive/hivemetastore.log.2021-12-18_7, renameEmptyFiles=true]
2021-12-18 10:46:15,682 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_8 to /var/log/hive/hivemetastore.log.2021-12-18_7 with Files.move
2021-12-18 10:46:15,683 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_9 to /var/log/hive/hivemetastore.log.2021-12-18_8, renameEmptyFiles=true]
2021-12-18 10:46:15,683 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_9 to /var/log/hive/hivemetastore.log.2021-12-18_8 with Files.move
2021-12-18 10:46:15,683 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_10 to /var/log/hive/hivemetastore.log.2021-12-18_9, renameEmptyFiles=true]
2021-12-18 10:46:15,683 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_10 to /var/log/hive/hivemetastore.log.2021-12-18_9 with Files.move
2021-12-18 10:46:15,683 pool-5-thread-2 TRACE DefaultRolloverStrategy.purge() took 4.0 milliseconds
2021-12-18 10:46:15,683 pool-5-thread-2 DEBUG RollingFileManager executing synchronous FileRenameAction[/var/log/hive/hivemetastore.log to /var/log/hive/hivemetastore.log.2021-12-18_10, renameEmptyFiles=false]
2021-12-18 10:46:15,683 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log to /var/log/hive/hivemetastore.log.2021-12-18_10 with Files.move
2021-12-18 10:46:47,121 pool-5-thread-2 DEBUG Eligible files: {1=/var/log/hive/hivemetastore.log.2021-12-18_1, 2=/var/log/hive/hivemetastore.log.2021-12-18_2, 3=/var/log/hive/hivemetastore.log.2021-12-18_3, 4=/var/log/hive/hivemetastore.log.2021-12-18_4, 5=/var/log/hive/hivemetastore.log.2021-12-18_5, 6=/var/log/hive/hivemetastore.log.2021-12-18_6, 7=/var/log/hive/hivemetastore.log.2021-12-18_7, 8=/var/log/hive/hivemetastore.log.2021-12-18_8, 9=/var/log/hive/hivemetastore.log.2021-12-18_9, 10=/var/log/hive/hivemetastore.log.2021-12-18_10}
2021-12-18 10:46:47,121 pool-5-thread-2 DEBUG Deleting /var/log/hive/hivemetastore.log.2021-12-18_1
2021-12-18 10:46:47,123 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_2 to /var/log/hive/hivemetastore.log.2021-12-18_1, renameEmptyFiles=true]
2021-12-18 10:46:47,123 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_2 to /var/log/hive/hivemetastore.log.2021-12-18_1 with Files.move
2021-12-18 10:46:47,123 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_3 to /var/log/hive/hivemetastore.log.2021-12-18_2, renameEmptyFiles=true]
2021-12-18 10:46:47,124 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_3 to /var/log/hive/hivemetastore.log.2021-12-18_2 with Files.move
2021-12-18 10:46:47,124 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_4 to /var/log/hive/hivemetastore.log.2021-12-18_3, renameEmptyFiles=true]
2021-12-18 10:46:47,124 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_4 to /var/log/hive/hivemetastore.log.2021-12-18_3 with Files.move
2021-12-18 10:46:47,124 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_5 to /var/log/hive/hivemetastore.log.2021-12-18_4, renameEmptyFiles=true]
2021-12-18 10:46:47,124 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_5 to /var/log/hive/hivemetastore.log.2021-12-18_4 with Files.move
2021-12-18 10:46:47,124 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_6 to /var/log/hive/hivemetastore.log.2021-12-18_5, renameEmptyFiles=true]
2021-12-18 10:46:47,124 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_6 to /var/log/hive/hivemetastore.log.2021-12-18_5 with Files.move
2021-12-18 10:46:47,124 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_7 to /var/log/hive/hivemetastore.log.2021-12-18_6, renameEmptyFiles=true]
2021-12-18 10:46:47,125 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_7 to /var/log/hive/hivemetastore.log.2021-12-18_6 with Files.move
2021-12-18 10:46:47,125 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_8 to /var/log/hive/hivemetastore.log.2021-12-18_7, renameEmptyFiles=true]
2021-12-18 10:46:47,125 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_8 to /var/log/hive/hivemetastore.log.2021-12-18_7 with Files.move
2021-12-18 10:46:47,125 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_9 to /var/log/hive/hivemetastore.log.2021-12-18_8, renameEmptyFiles=true]
2021-12-18 10:46:47,125 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_9 to /var/log/hive/hivemetastore.log.2021-12-18_8 with Files.move
2021-12-18 10:46:47,125 pool-5-thread-2 DEBUG DefaultRolloverStrategy.purgeAscending executing FileRenameAction[/var/log/hive/hivemetastore.log.2021-12-18_10 to /var/log/hive/hivemetastore.log.2021-12-18_9, renameEmptyFiles=true]
2021-12-18 10:46:47,125 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log.2021-12-18_10 to /var/log/hive/hivemetastore.log.2021-12-18_9 with Files.move
2021-12-18 10:46:47,125 pool-5-thread-2 TRACE DefaultRolloverStrategy.purge() took 4.0 milliseconds
2021-12-18 10:46:47,126 pool-5-thread-2 DEBUG RollingFileManager executing synchronous FileRenameAction[/var/log/hive/hivemetastore.log to /var/log/hive/hivemetastore.log.2021-12-18_10, renameEmptyFiles=false]
2021-12-18 10:46:47,126 pool-5-thread-2 TRACE Renamed file /var/log/hive/hivemetastore.log to /var/log/hive/hivemetastore.log.2021-12-18_10 with Files.move
==> /var/log/hive/hive-server2.out <==
2021-12-18 09:58:08,918 main DEBUG Building Plugin[name=SizeBasedTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy].
2021-12-18 09:58:08,918 main DEBUG createPolicy(size="1024MB")
2021-12-18 09:58:08,919 main DEBUG Building Plugin[name=SizeBasedTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy].
2021-12-18 09:58:08,919 main DEBUG createPolicy(size="null")
2021-12-18 09:58:08,919 main DEBUG Building Plugin[name=Policies, class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
2021-12-18 09:58:08,919 main DEBUG createPolicy(={SizeBasedTriggeringPolicy(size=1073741824), SizeBasedTriggeringPolicy(size=10485760)})
2021-12-18 09:58:08,920 main DEBUG Building Plugin[name=DefaultRolloverStrategy, class=org.apache.logging.log4j.core.appender.rolling.DefaultRolloverStrategy].
2021-12-18 09:58:08,920 main DEBUG DefaultRolloverStrategy$Builder(max="10", min="null", fileIndex="null", compressionLevel="null", ={}, stopCustomActionsOnError="null", tempCompressedFilePattern="null", Configuration(HiveLog4j2))
2021-12-18 09:58:08,920 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.RollingRandomAccessFileAppender].
2021-12-18 09:58:08,921 main DEBUG RollingRandomAccessFileAppender$Builder(fileName="/var/log/hive/hiveserver2.log", filePattern="/var/log/hive/hiveserver2.log.%d{yyyy-MM-dd}_%i", append="null", Policies(CompositeTriggeringPolicy(policies=[SizeBasedTriggeringPolicy(size=1073741824), SizeBasedTriggeringPolicy(size=10485760)])), DefaultRolloverStrategy(DefaultRolloverStrategy(min=1, max=10, useMax=true)), advertise="null", advertiseURI="null", filePermissions="null", fileOwner="null", fileGroup="null", bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d{ISO8601} %5p [%t] %c{2}: %m%n), name="RFA", Configuration(HiveLog4j2), Filter=null)
2021-12-18 09:58:08,921 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2021-12-18 09:58:08,921 main DEBUG PatternLayout$Builder(pattern="%d{ISO8601} %-5p [%t]: %c{2} (%F:%M(%L)) - %m%n", PatternSelector=null, Configuration(HiveLog4j2), Replace=null, charset="null", alwaysWriteExceptions="null", disableAnsi="null", noConsoleNoAnsi="null", header="null", footer="null")
2021-12-18 09:58:08,921 main DEBUG Building Plugin[name=appender, class=com.microsoft.log4jappender.log4j2.FilterLogAppender].
2021-12-18 09:58:08,922 main DEBUG createAppender(PatternLayout(%d{ISO8601} %-5p [%t]: %c{2} (%F:%M(%L)) - %m%n), Filter=null, name="FilterLog", ignoreExceptions="null", source="", component="hiveserver2interactive", osType="Linux", whitelistFile="NA")
2021-12-18 09:58:08,922 main DEBUG Building Plugin[name=appenders, class=org.apache.logging.log4j.core.config.AppendersPlugin].
2021-12-18 09:58:08,922 main DEBUG createAppenders(={console, RFA, FilterLog})
2021-12-18 09:58:08,922 main DEBUG Configuration org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@7944b8b4 initialized
2021-12-18 09:58:08,922 main DEBUG Starting configuration org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@7944b8b4
2021-12-18 09:58:08,923 main DEBUG Started configuration org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@7944b8b4 OK.
2021-12-18 09:58:08,923 main TRACE Stopping org.apache.logging.log4j.core.config.DefaultConfiguration@66383c29...
2021-12-18 09:58:08,923 main TRACE DefaultConfiguration notified 1 ReliabilityStrategies that config will be stopped.
2021-12-18 09:58:08,923 main TRACE DefaultConfiguration stopping root LoggerConfig.
2021-12-18 09:58:08,924 main TRACE DefaultConfiguration notifying ReliabilityStrategies that appenders will be stopped.
2021-12-18 09:58:08,924 main TRACE DefaultConfiguration stopping remaining Appenders.
2021-12-18 09:58:08,924 main DEBUG Shutting down OutputStreamManager SYSTEM_OUT.false.false-3
2021-12-18 09:58:08,924 main DEBUG Shut down OutputStreamManager SYSTEM_OUT.false.false-3, all resources released: true
2021-12-18 09:58:08,924 main DEBUG Appender DefaultConsole-3 stopped with status true
2021-12-18 09:58:08,924 main TRACE DefaultConfiguration stopped 1 remaining Appenders.
2021-12-18 09:58:08,924 main TRACE DefaultConfiguration cleaning Appenders from 1 LoggerConfigs.
2021-12-18 09:58:08,924 main DEBUG Stopped org.apache.logging.log4j.core.config.DefaultConfiguration@66383c29 OK
2021-12-18 09:58:08,925 main TRACE Reregistering MBeans after reconfigure. Selector=org.apache.logging.log4j.core.async.AsyncLoggerContextSelector@4d9d1b69
2021-12-18 09:58:08,925 main TRACE Reregistering context (1/3): ' ' org.apache.logging.log4j.core.LoggerContext@1130520d
2021-12-18 09:58:08,925 main TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type= ]
2021-12-18 09:58:08,925 main TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type= ,component=StatusLogger]
2021-12-18 09:58:08,925 main TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type= ,component=ContextSelector]
2021-12-18 09:58:08,926 main TRACE Unregistering 6 MBeans: [org.apache.logging.log4j2:type= ,component=Loggers,name=org.apache.zookeeper.ClientCnxnSocketNIO, org.apache.logging.log4j2:type= ,component=Loggers,name=Datastore, org.apache.logging.log4j2:type= ,component=Loggers,name=, org.apache.logging.log4j2:type= ,component=Loggers,name=DataNucleus, org.apache.logging.log4j2:type= ,component=Loggers,name=JPOX, org.apache.logging.log4j2:type= ,component=Loggers,name=org.apache.zookeeper.server.NIOServerCnxn]
2021-12-18 09:58:08,926 main TRACE Unregistering 3 MBeans: [org.apache.logging.log4j2:type= ,component=Appenders,name=FilterLog, org.apache.logging.log4j2:type= ,component=Appenders,name=RFA, org.apache.logging.log4j2:type= ,component=Appenders,name=console]
2021-12-18 09:58:08,927 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type= ,component=AsyncAppenders,name=*'
2021-12-18 09:58:08,927 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type= ,component=AsyncLoggerRingBuffer'
2021-12-18 09:58:08,927 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type= ,component=Loggers,name=*,subtype=RingBuffer'
2021-12-18 09:58:08,927 main DEBUG Registering MBean org.apache.logging.log4j2:type=
2021-12-18 09:58:08,927 main DEBUG Registering MBean org.apache.logging.log4j2:type= ,component=StatusLogger
2021-12-18 09:58:08,928 main DEBUG Registering MBean org.apache.logging.log4j2:type= ,component=ContextSelector
2021-12-18 09:58:08,928 main DEBUG Registering MBean org.apache.logging.log4j2:type= ,component=Loggers,name=
2021-12-18 09:58:08,928 main DEBUG Registering MBean org.apache.logging.log4j2:type= ,component=Loggers,name=DataNucleus
2021-12-18 09:58:08,928 main DEBUG Registering MBean org.apache.logging.log4j2:type= ,component=Loggers,name=Datastore
2021-12-18 09:58:08,928 main DEBUG Registering MBean org.apache.logging.log4j2:type= ,component=Loggers,name=org.apache.zookeeper.ClientCnxnSocketNIO
2021-12-18 09:58:08,929 main DEBUG Registering MBean org.apache.logging.log4j2:type= ,component=Loggers,name=org.apache.zookeeper.server.NIOServerCnxn
2021-12-18 09:58:08,929 main DEBUG Registering MBean org.apache.logging.log4j2:type= ,component=Loggers,name=JPOX
2021-12-18 09:58:08,929 main DEBUG Registering MBean org.apache.logging.log4j2:type= ,component=Appenders,name=console
2021-12-18 09:58:08,929 main DEBUG Registering MBean org.apache.logging.log4j2:type= ,component=Appenders,name=RFA
2021-12-18 09:58:08,929 main DEBUG Registering MBean org.apache.logging.log4j2:type= ,component=Appenders,name=FilterLog
2021-12-18 09:58:08,930 main TRACE Reregistering context (2/3): 'AsyncContext@ ' org.apache.logging.log4j.core.async.AsyncLoggerContext@
2021-12-18 09:58:08,930 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@ '
2021-12-18 09:58:08,930 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@ ,component=StatusLogger'
2021-12-18 09:58:08,930 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@ ,component=ContextSelector'
2021-12-18 09:58:08,930 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=*'
2021-12-18 09:58:08,931 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@ ,component=Appenders,name=*'
2021-12-18 09:58:08,931 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@ ,component=AsyncAppenders,name=*'
2021-12-18 09:58:08,931 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@ ,component=AsyncLoggerRingBuffer'
2021-12-18 09:58:08,931 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=*,subtype=RingBuffer'
2021-12-18 09:58:08,931 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@
2021-12-18 09:58:08,932 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=AsyncLoggerRingBuffer
2021-12-18 09:58:08,932 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=StatusLogger
2021-12-18 09:58:08,932 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=ContextSelector
2021-12-18 09:58:08,932 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=
2021-12-18 09:58:08,933 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=DataNucleus
2021-12-18 09:58:08,933 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=Datastore
2021-12-18 09:58:08,933 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=org.apache.zookeeper.ClientCnxnSocketNIO
2021-12-18 09:58:08,933 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=org.apache.zookeeper.server.NIOServerCnxn
2021-12-18 09:58:08,934 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=JPOX
2021-12-18 09:58:08,934 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Appenders,name=console
2021-12-18 09:58:08,934 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Appenders,name=RFA
2021-12-18 09:58:08,934 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Appenders,name=FilterLog
2021-12-18 09:58:08,934 main TRACE Reregistering context (3/3): 'AsyncContext@ ' org.apache.logging.log4j.core.async.AsyncLoggerContext@c41709a
2021-12-18 09:58:08,934 main TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=AsyncContext@ ]
2021-12-18 09:58:08,935 main TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=AsyncContext@ ,component=StatusLogger]
2021-12-18 09:58:08,935 main TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=AsyncContext@ ,component=ContextSelector]
2021-12-18 09:58:08,935 main TRACE Unregistering 6 MBeans: [org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=Datastore, org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=JPOX, org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=DataNucleus, org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=, org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=org.apache.zookeeper.ClientCnxnSocketNIO, org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=org.apache.zookeeper.server.NIOServerCnxn]
2021-12-18 09:58:08,936 main TRACE Unregistering 3 MBeans: [org.apache.logging.log4j2:type=AsyncContext@ ,component=Appenders,name=FilterLog, org.apache.logging.log4j2:type=AsyncContext@ ,component=Appenders,name=console, org.apache.logging.log4j2:type=AsyncContext@ ,component=Appenders,name=RFA]
2021-12-18 09:58:08,936 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@ ,component=AsyncAppenders,name=*'
2021-12-18 09:58:08,936 main TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=AsyncContext@ ,component=AsyncLoggerRingBuffer]
2021-12-18 09:58:08,937 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=*,subtype=RingBuffer'
2021-12-18 09:58:08,937 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@
2021-12-18 09:58:08,937 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=AsyncLoggerRingBuffer
2021-12-18 09:58:08,937 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=StatusLogger
2021-12-18 09:58:08,937 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=ContextSelector
2021-12-18 09:58:08,938 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=
2021-12-18 09:58:08,938 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=DataNucleus
2021-12-18 09:58:08,938 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=Datastore
2021-12-18 09:58:08,938 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=org.apache.zookeeper.ClientCnxnSocketNIO
2021-12-18 09:58:08,938 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=org.apache.zookeeper.server.NIOServerCnxn
2021-12-18 09:58:08,939 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Loggers,name=JPOX
2021-12-18 09:58:08,939 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Appenders,name=console
2021-12-18 09:58:08,939 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Appenders,name=RFA
2021-12-18 09:58:08,939 main DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@ ,component=Appenders,name=FilterLog
2021-12-18 09:58:08,939 main TRACE Using DummyNanoClock for nanosecond timestamps.
2021-12-18 09:58:08,939 main DEBUG Reconfiguration complete for context[name=AsyncContext@ ] at URI /etc/hive/3.1.6.0-77/0/hive-log4j2.properties (org.apache.logging.log4j.core.async.AsyncLoggerContext@ ) with optional ClassLoader: null
2021-12-18 09:58:08,939 main DEBUG Shutdown hook enabled. Registering a new one.
2021-12-18 09:58:08,940 main DEBUG LoggerContext[name=AsyncContext@ , org.apache.logging.log4j.core.async.AsyncLoggerContext@ ] started OK.
2021-12-18 10:58:17,161 - Skipping LZO package installation as host is sys prepped
2021-12-18 10:58:17,161 - Directories to fill with configs: [u'/usr/hdp/current/hive-server2/conf', u'/usr/hdp/current/hive-server2/conf/']
2021-12-18 10:58:17,161 - Directory['/etc/hive/3.1.6.0-77/0'] {'owner': 'hive', 'group': 'hadoop', 'create_parents': True, 'mode': 0755}
2021-12-18 10:58:17,162 - XmlConfig['mapred-site.xml'] {'group': 'hadoop', 'conf_dir': '/etc/hive/3.1.6.0-77/0', 'mode': 0644, 'configuration_attributes': {}, 'owner': 'hive', 'configurations': ...}
2021-12-18 10:58:17,172 - Generating config: /etc/hive/3.1.6.0-77/0/mapred-site.xml
2021-12-18 10:58:17,172 - File['/etc/hive/3.1.6.0-77/0/mapred-site.xml'] {'owner': 'hive', 'content': InlineTemplate(...), 'group': 'hadoop', 'mode': 0644, 'encoding': 'UTF-8'}
2021-12-18 10:58:17,216 - File['/etc/hive/3.1.6.0-77/0/hive-default.xml.template'] {'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,217 - File['/etc/hive/3.1.6.0-77/0/hive-env.sh.template'] {'owner': 'hive', 'group': 'hadoop', 'mode': 0755}
2021-12-18 10:58:17,220 - File['/etc/hive/3.1.6.0-77/0/llap-daemon-log4j2.properties'] {'content': InlineTemplate(...), 'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,223 - File['/etc/hive/3.1.6.0-77/0/llap-cli-log4j2.properties'] {'content': InlineTemplate(...), 'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,225 - File['/etc/hive/3.1.6.0-77/0/hive-log4j2.properties'] {'content': InlineTemplate(...), 'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,227 - File['/etc/hive/3.1.6.0-77/0/hive-exec-log4j2.properties'] {'content': InlineTemplate(...), 'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,229 - File['/etc/hive/3.1.6.0-77/0/beeline-log4j2.properties'] {'content': InlineTemplate(...), 'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,230 - XmlConfig['beeline-site.xml'] {'owner': 'hive', 'group': 'hadoop', 'mode': 0644, 'conf_dir': '/etc/hive/3.1.6.0-77/0', 'configurations': {'beeline.hs2.jdbc.url.container': u'jdbc:hive2://zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181/;serviceDiscoveryMode=zooKeeper;zooKeeperNamespace=hiveserver2', 'beeline.hs2.jdbc.url.default': u'container'}}
2021-12-18 10:58:17,237 - Generating config: /etc/hive/3.1.6.0-77/0/beeline-site.xml
2021-12-18 10:58:17,237 - File['/etc/hive/3.1.6.0-77/0/beeline-site.xml'] {'owner': 'hive', 'content': InlineTemplate(...), 'group': 'hadoop', 'mode': 0644, 'encoding': 'UTF-8'}
2021-12-18 10:58:17,239 - File['/etc/hive/3.1.6.0-77/0/parquet-logging.properties'] {'content': ..., 'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,240 - Directory['/etc/hive/3.1.6.0-77/0'] {'owner': 'hive', 'group': 'hadoop', 'create_parents': True, 'mode': 0755}
2021-12-18 10:58:17,240 - XmlConfig['mapred-site.xml'] {'group': 'hadoop', 'conf_dir': '/etc/hive/3.1.6.0-77/0', 'mode': 0644, 'configuration_attributes': {}, 'owner': 'hive', 'configurations': ...}
2021-12-18 10:58:17,247 - Generating config: /etc/hive/3.1.6.0-77/0/mapred-site.xml
2021-12-18 10:58:17,247 - File['/etc/hive/3.1.6.0-77/0/mapred-site.xml'] {'owner': 'hive', 'content': InlineTemplate(...), 'group': 'hadoop', 'mode': 0644, 'encoding': 'UTF-8'}
2021-12-18 10:58:17,291 - File['/etc/hive/3.1.6.0-77/0/hive-default.xml.template'] {'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,292 - File['/etc/hive/3.1.6.0-77/0/hive-env.sh.template'] {'owner': 'hive', 'group': 'hadoop', 'mode': 0755}
2021-12-18 10:58:17,295 - File['/etc/hive/3.1.6.0-77/0/llap-daemon-log4j2.properties'] {'content': InlineTemplate(...), 'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,297 - File['/etc/hive/3.1.6.0-77/0/llap-cli-log4j2.properties'] {'content': InlineTemplate(...), 'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,300 - File['/etc/hive/3.1.6.0-77/0/hive-log4j2.properties'] {'content': InlineTemplate(...), 'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,302 - File['/etc/hive/3.1.6.0-77/0/hive-exec-log4j2.properties'] {'content': InlineTemplate(...), 'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,304 - File['/etc/hive/3.1.6.0-77/0/beeline-log4j2.properties'] {'content': InlineTemplate(...), 'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,305 - XmlConfig['beeline-site.xml'] {'owner': 'hive', 'group': 'hadoop', 'mode': 0644, 'conf_dir': '/etc/hive/3.1.6.0-77/0', 'configurations': {'beeline.hs2.jdbc.url.container': u'jdbc:hive2://zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181/;serviceDiscoveryMode=zooKeeper;zooKeeperNamespace=hiveserver2', 'beeline.hs2.jdbc.url.default': u'container'}}
2021-12-18 10:58:17,312 - Generating config: /etc/hive/3.1.6.0-77/0/beeline-site.xml
2021-12-18 10:58:17,312 - File['/etc/hive/3.1.6.0-77/0/beeline-site.xml'] {'owner': 'hive', 'content': InlineTemplate(...), 'group': 'hadoop', 'mode': 0644, 'encoding': 'UTF-8'}
2021-12-18 10:58:17,314 - File['/etc/hive/3.1.6.0-77/0/parquet-logging.properties'] {'content': ..., 'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 10:58:17,314 - File['/usr/hdp/current/hive-server2/conf/hive-site.jceks'] {'content': StaticFile('/var/lib/ambari-agent/cred/conf/hive_server/hive-site.jceks'), 'owner': 'hive', 'group': 'hadoop', 'mode': 0640}
2021-12-18 10:58:17,315 - Writing File['/usr/hdp/current/hive-server2/conf/hive-site.jceks'] because contents don't match
2021-12-18 10:58:17,315 - XmlConfig['hive-site.xml'] {'group': 'hadoop', 'conf_dir': '/usr/hdp/current/hive-server2/conf/', 'mode': 0644, 'configuration_attributes': {u'hidden': {u'javax.jdo.option.ConnectionPassword': u'HIVE_CLIENT,CONFIG_DOWNLOAD'}}, 'owner': 'hive', 'configurations': ...}
2021-12-18 10:58:17,322 - Generating config: /usr/hdp/current/hive-server2/conf/hive-site.xml
2021-12-18 10:58:17,322 - File['/usr/hdp/current/hive-server2/conf/hive-site.xml'] {'owner': 'hive', 'content': InlineTemplate(...), 'group': 'hadoop', 'mode': 0644, 'encoding': 'UTF-8'}
2021-12-18 10:58:17,473 - File['/usr/hdp/current/hive-server2/conf//hive-env.sh'] {'content': InlineTemplate(...), 'owner': 'hive', 'group': 'hadoop', 'mode': 0755}
2021-12-18 10:58:17,474 - Directory['/etc/security/limits.d'] {'owner': 'root', 'create_parents': True, 'group': 'root'}
2021-12-18 10:58:17,476 - File['/etc/security/limits.d/hive.conf'] {'content': Template('hive.conf.j2'), 'owner': 'root', 'group': 'root', 'mode': 0644}
2021-12-18 10:58:17,478 - File['/usr/hdp/current/hive-server2/conf/zkmigrator_jaas.conf'] {'content': Template('zkmigrator_jaas.conf.j2'), 'owner': 'hive', 'group': 'hadoop'}
2021-12-18 10:58:17,479 - File['/usr/lib/ambari-agent/DBConnectionVerification.jar'] {'content': DownloadSource(' .com:8080/resources/DBConnectionVerification.jar'), 'mode': 0644}
2021-12-18 10:58:17,479 - Not downloading the file from .com:8080/resources/DBConnectionVerification.jar, because /var/lib/ambari-agent/tmp/DBConnectionVerification.jar already exists
2021-12-18 10:58:17,479 - Directory['/var/run/hive'] {'owner': 'hive', 'create_parents': True, 'group': 'hadoop', 'mode': 0755, 'cd_access': 'a'}
2021-12-18 10:58:17,480 - Directory['/var/log/hive'] {'owner': 'hive', 'create_parents': True, 'group': 'hadoop', 'mode': 0755, 'cd_access': 'a'}
2021-12-18 10:58:17,480 - Directory['/var/lib/hive'] {'owner': 'hive', 'create_parents': True, 'group': 'hadoop', 'mode': 0755, 'cd_access': 'a'}
2021-12-18 10:58:17,482 - File['/var/lib/ambari-agent/tmp/start_hiveserver2_script'] {'content': Template('startHiveserver2.sh.j2'), 'mode': 0755}
2021-12-18 10:58:17,486 - File['/usr/hdp/current/hive-server2/conf/hadoop-metrics2-hiveserver2.properties'] {'content': Template('hadoop-metrics2-hiveserver2.properties.j2'), 'owner': 'hive', 'group': 'hadoop', 'mode': 0600}
2021-12-18 10:58:17,487 - XmlConfig['hiveserver2-site.xml'] {'group': 'hadoop', 'conf_dir': '/usr/hdp/current/hive-server2/conf/', 'mode': 0600, 'configuration_attributes': {}, 'owner': 'hive', 'configurations': ...}
2021-12-18 10:58:17,494 - Generating config: /usr/hdp/current/hive-server2/conf/hiveserver2-site.xml
2021-12-18 10:58:17,494 - File['/usr/hdp/current/hive-server2/conf/hiveserver2-site.xml'] {'owner': 'hive', 'content': InlineTemplate(...), 'group': 'hadoop', 'mode': 0600, 'encoding': 'UTF-8'}
2021-12-18 10:58:17,503 - Called copy_to_hdfs tarball: mapreduce
2021-12-18 10:58:17,503 - Stack Feature Version Info: Cluster Stack=3.1, Command Stack=None, Command Version=3.1.6.0-77 -> 3.1.6.0-77
2021-12-18 10:58:17,503 - Tarball version was calcuated as 3.1.6.0-77. Use Command Version: True
2021-12-18 10:58:17,503 - Skipping copying /usr/hdp/3.1.6.0-77/hadoop/mapreduce.tar.gz to /hdp/apps/3.1.6.0-77/mapreduce/mapreduce.tar.gz for mapreduce as it is a sys prepped host.
2021-12-18 10:58:17,503 - Called copy_to_hdfs tarball: tez
2021-12-18 10:58:17,503 - Stack Feature Version Info: Cluster Stack=3.1, Command Stack=None, Command Version=3.1.6.0-77 -> 3.1.6.0-77
2021-12-18 10:58:17,503 - Tarball version was calcuated as 3.1.6.0-77. Use Command Version: True
2021-12-18 10:58:17,503 - Skipping copying /usr/hdp/3.1.6.0-77/tez/lib/tez.tar.gz to /hdp/apps/3.1.6.0-77/tez/tez.tar.gz for tez as it is a sys prepped host.
2021-12-18 10:58:17,503 - Called copy_to_hdfs tarball: pig
2021-12-18 10:58:17,503 - Stack Feature Version Info: Cluster Stack=3.1, Command Stack=None, Command Version=3.1.6.0-77 -> 3.1.6.0-77
2021-12-18 10:58:17,504 - Tarball version was calcuated as 3.1.6.0-77. Use Command Version: True
2021-12-18 10:58:17,504 - Skipping copying /usr/hdp/3.1.6.0-77/pig/pig.tar.gz to /hdp/apps/3.1.6.0-77/pig/pig.tar.gz for pig as it is a sys prepped host.
2021-12-18 10:58:17,504 - Called copy_to_hdfs tarball: hive
2021-12-18 10:58:17,504 - Stack Feature Version Info: Cluster Stack=3.1, Command Stack=None, Command Version=3.1.6.0-77 -> 3.1.6.0-77
2021-12-18 10:58:17,504 - Tarball version was calcuated as 3.1.6.0-77. Use Command Version: True
2021-12-18 10:58:17,504 - Skipping copying /usr/hdp/3.1.6.0-77/hive/hive.tar.gz to /hdp/apps/3.1.6.0-77/hive/hive.tar.gz for hive as it is a sys prepped host.
2021-12-18 10:58:17,504 - Called copy_to_hdfs tarball: sqoop
2021-12-18 10:58:17,504 - Stack Feature Version Info: Cluster Stack=3.1, Command Stack=None, Command Version=3.1.6.0-77 -> 3.1.6.0-77
2021-12-18 10:58:17,504 - Tarball version was calcuated as 3.1.6.0-77. Use Command Version: True
2021-12-18 10:58:17,504 - Skipping copying /usr/hdp/3.1.6.0-77/sqoop/sqoop.tar.gz to /hdp/apps/3.1.6.0-77/sqoop/sqoop.tar.gz for sqoop as it is a sys prepped host.
2021-12-18 10:58:17,504 - Called copy_to_hdfs tarball: hadoop_streaming
2021-12-18 10:58:17,504 - Stack Feature Version Info: Cluster Stack=3.1, Command Stack=None, Command Version=3.1.6.0-77 -> 3.1.6.0-77
2021-12-18 10:58:17,504 - Tarball version was calcuated as 3.1.6.0-77. Use Command Version: True
2021-12-18 10:58:17,505 - Skipping copying /usr/hdp/3.1.6.0-77/hadoop-mapreduce/hadoop-streaming.jar to /hdp/apps/3.1.6.0-77/mapreduce/hadoop-streaming.jar for hadoop_streaming as it is a sys prepped host.
2021-12-18 10:58:17,505 - HdfsResource['/hive/warehouse/external/sys.db/'] {'security_enabled': True, 'hadoop_bin_dir': '/usr/hdp/3.1.6.0-77/hadoop/bin', 'keytab': '/etc/security/keytabs/hdfs.headless.keytab', 'dfs_type': 'HDFS', 'default_fs': 'abfs:// [email protected]', 'hdfs_resource_ignore_file': '/var/lib/ambari-agent/data/.hdfs_resource_ignore', 'hdfs_site': ..., 'kinit_path_local': '/usr/bin/kinit', 'principal_name': 'hdfs- @HDINSIGHT01.GO. .COM', 'user': 'hdfs', 'owner': 'hive', 'hadoop_conf_dir': '/usr/hdp/3.1.6.0-77/hadoop/conf', 'type': 'directory', 'action': ['create_on_execute'], 'immutable_paths': [u'/mr-history/done', u'/hive/warehouse/managed', u'/app-logs', u'/tmp', u'/hive/warehouse/external'], 'mode': 01755}
2021-12-18 10:58:17,507 - HdfsResource['/hive/warehouse/external/sys.db/query_data/'] {'security_enabled': True, 'hadoop_bin_dir': '/usr/hdp/3.1.6.0-77/hadoop/bin', 'keytab': '/etc/security/keytabs/hdfs.headless.keytab', 'dfs_type': 'HDFS', 'default_fs': 'abfs:// [email protected]', 'hdfs_resource_ignore_file': '/var/lib/ambari-agent/data/.hdfs_resource_ignore', 'hdfs_site': ..., 'kinit_path_local': '/usr/bin/kinit', 'principal_name': 'hdfs- @HDINSIGHT01.GO. .COM', 'user': 'hdfs', 'owner': 'hive', 'hadoop_conf_dir': '/usr/hdp/3.1.6.0-77/hadoop/conf', 'type': 'directory', 'action': ['create_on_execute'], 'immutable_paths': [u'/mr-history/done', u'/hive/warehouse/managed', u'/app-logs', u'/tmp', u'/hive/warehouse/external'], 'mode': 01777}
2021-12-18 10:58:17,508 - HdfsResource['/hive/warehouse/external/sys.db/dag_meta'] {'security_enabled': True, 'hadoop_bin_dir': '/usr/hdp/3.1.6.0-77/hadoop/bin', 'keytab': '/etc/security/keytabs/hdfs.headless.keytab', 'dfs_type': 'HDFS', 'default_fs': 'abfs:// [email protected]', 'hdfs_resource_ignore_file': '/var/lib/ambari-agent/data/.hdfs_resource_ignore', 'hdfs_site': ..., 'kinit_path_local': '/usr/bin/kinit', 'principal_name': 'hdfs- @HDINSIGHT01.GO. .COM', 'user': 'hdfs', 'owner': 'hive', 'hadoop_conf_dir': '/usr/hdp/3.1.6.0-77/hadoop/conf', 'type': 'directory', 'action': ['create_on_execute'], 'immutable_paths': [u'/mr-history/done', u'/hive/warehouse/managed', u'/app-logs', u'/tmp', u'/hive/warehouse/external'], 'mode': 01777}
2021-12-18 10:58:17,508 - HdfsResource['/hive/warehouse/external/sys.db/dag_data'] {'security_enabled': True, 'hadoop_bin_dir': '/usr/hdp/3.1.6.0-77/hadoop/bin', 'keytab': '/etc/security/keytabs/hdfs.headless.keytab', 'dfs_type': 'HDFS', 'default_fs': 'abfs:// [email protected]', 'hdfs_resource_ignore_file': '/var/lib/ambari-agent/data/.hdfs_resource_ignore', 'hdfs_site': ..., 'kinit_path_local': '/usr/bin/kinit', 'principal_name': 'hdfs- @HDINSIGHT01.GO. .COM', 'user': 'hdfs', 'owner': 'hive', 'hadoop_conf_dir': '/usr/hdp/3.1.6.0-77/hadoop/conf', 'type': 'directory', 'action': ['create_on_execute'], 'immutable_paths': [u'/mr-history/done', u'/hive/warehouse/managed', u'/app-logs', u'/tmp', u'/hive/warehouse/external'], 'mode': 01777}
2021-12-18 10:58:17,509 - HdfsResource['/hive/warehouse/external/sys.db/app_data'] {'security_enabled': True, 'hadoop_bin_dir': '/usr/hdp/3.1.6.0-77/hadoop/bin', 'keytab': '/etc/security/keytabs/hdfs.headless.keytab', 'dfs_type': 'HDFS', 'default_fs': 'abfs:// [email protected]', 'hdfs_resource_ignore_file': '/var/lib/ambari-agent/data/.hdfs_resource_ignore', 'hdfs_site': ..., 'kinit_path_local': '/usr/bin/kinit', 'principal_name': 'hdfs- @HDINSIGHT01.GO. .COM', 'user': 'hdfs', 'owner': 'hive', 'hadoop_conf_dir': '/usr/hdp/3.1.6.0-77/hadoop/conf', 'type': 'directory', 'action': ['create_on_execute'], 'immutable_paths': [u'/mr-history/done', u'/hive/warehouse/managed', u'/app-logs', u'/tmp', u'/hive/warehouse/external'], 'mode': 01777}
2021-12-18 10:58:17,510 - HdfsResource[None] {'security_enabled': True, 'hadoop_bin_dir': '/usr/hdp/3.1.6.0-77/hadoop/bin', 'keytab': '/etc/security/keytabs/hdfs.headless.keytab', 'dfs_type': 'HDFS', 'default_fs': 'abfs:// [email protected]', 'hdfs_resource_ignore_file': '/var/lib/ambari-agent/data/.hdfs_resource_ignore', 'hdfs_site': ..., 'kinit_path_local': '/usr/bin/kinit', 'principal_name': 'hdfs- @HDINSIGHT01.GO. .COM', 'user': 'hdfs', 'action': ['execute'], 'hadoop_conf_dir': '/usr/hdp/3.1.6.0-77/hadoop/conf', 'immutable_paths': [u'/mr-history/done', u'/hive/warehouse/managed', u'/app-logs', u'/tmp', u'/hive/warehouse/external']}
2021-12-18 10:58:17,510 - Execute['/usr/bin/kinit -kt /etc/security/keytabs/hdfs.headless.keytab hdfs- @HDINSIGHT01.GO. .COM'] {'user': 'hdfs'}
2021-12-18 10:58:17,549 - File['/var/lib/ambari-agent/tmp/hdfs_resources_1639825097.51.json'] {'content': ..., 'owner': 'hdfs'}
2021-12-18 10:58:17,550 - Writing File['/var/lib/ambari-agent/tmp/hdfs_resources_1639825097.51.json'] because it doesn't exist
2021-12-18 10:58:17,550 - Changing owner for /var/lib/ambari-agent/tmp/hdfs_resources_1639825097.51.json from 0 to hdfs
2021-12-18 10:58:17,550 - Execute['hadoop --config /usr/hdp/3.1.6.0-77/hadoop/conf jar /var/lib/ambari-agent/lib/fast-hdfs-resource.jar /var/lib/ambari-agent/tmp/hdfs_resources_1639825097.51.json'] {'logoutput': None, 'path': ['/usr/hdp/3.1.6.0-77/hadoop/bin'], 'user': 'hdfs'}
2021-12-18 10:58:21,681 - Directory['/usr/lib/ambari-logsearch-logfeeder/conf'] {'create_parents': True, 'mode': 0755, 'cd_access': 'a'}
2021-12-18 10:58:21,681 - Generate Log Feeder config file: /usr/lib/ambari-logsearch-logfeeder/conf/input.config-hive.json
2021-12-18 10:58:21,681 - File['/usr/lib/ambari-logsearch-logfeeder/conf/input.config-hive.json'] {'content': Template('input.config-hive.json.j2'), 'mode': 0644}
2021-12-18 10:58:21,682 - Hive: Setup ranger: command retry not enabled thus skipping if ranger admin is down !
2021-12-18 10:58:21,682 - HdfsResource['/ranger/audit'] {'security_enabled': True, 'hadoop_bin_dir': '/usr/hdp/3.1.6.0-77/hadoop/bin', 'keytab': '/etc/security/keytabs/hdfs.headless.keytab', 'dfs_type': 'HDFS', 'default_fs': 'abfs:// [email protected]', 'user': 'hdfs', 'hdfs_resource_ignore_file': '/var/lib/ambari-agent/data/.hdfs_resource_ignore', 'hdfs_site': ..., 'kinit_path_local': '/usr/bin/kinit', 'principal_name': 'hdfs- @HDINSIGHT01.GO. .COM', 'recursive_chmod': True, 'owner': 'hdfs', 'group': 'hdfs', 'hadoop_conf_dir': '/usr/hdp/3.1.6.0-77/hadoop/conf', 'type': 'directory', 'action': ['create_on_execute'], 'immutable_paths': [u'/mr-history/done', u'/hive/warehouse/managed', u'/app-logs', u'/tmp', u'/hive/warehouse/external'], 'mode': 0755}
2021-12-18 10:58:21,683 - HdfsResource['/ranger/audit/hiveServer2'] {'security_enabled': True, 'hadoop_bin_dir': '/usr/hdp/3.1.6.0-77/hadoop/bin', 'keytab': '/etc/security/keytabs/hdfs.headless.keytab', 'dfs_type': 'HDFS', 'default_fs': 'abfs:// [email protected]', 'user': 'hdfs', 'hdfs_resource_ignore_file': '/var/lib/ambari-agent/data/.hdfs_resource_ignore', 'hdfs_site': ..., 'kinit_path_local': '/usr/bin/kinit', 'principal_name': 'hdfs- @HDINSIGHT01.GO. .COM', 'recursive_chmod': True, 'owner': 'hive', 'group': 'hive', 'hadoop_conf_dir': '/usr/hdp/3.1.6.0-77/hadoop/conf', 'type': 'directory', 'action': ['create_on_execute'], 'immutable_paths': [u'/mr-history/done', u'/hive/warehouse/managed', u'/app-logs', u'/tmp', u'/hive/warehouse/external'], 'mode': 0700}
2021-12-18 10:58:21,684 - HdfsResource[None] {'security_enabled': True, 'hadoop_bin_dir': '/usr/hdp/3.1.6.0-77/hadoop/bin', 'keytab': '/etc/security/keytabs/hdfs.headless.keytab', 'dfs_type': 'HDFS', 'default_fs': 'abfs:// [email protected]', 'hdfs_resource_ignore_file': '/var/lib/ambari-agent/data/.hdfs_resource_ignore', 'hdfs_site': ..., 'kinit_path_local': '/usr/bin/kinit', 'principal_name': 'hdfs- @HDINSIGHT01.GO. .COM', 'user': 'hdfs', 'action': ['execute'], 'hadoop_conf_dir': '/usr/hdp/3.1.6.0-77/hadoop/conf', 'immutable_paths': [u'/mr-history/done', u'/hive/warehouse/managed', u'/app-logs', u'/tmp', u'/hive/warehouse/external']}
2021-12-18 10:58:21,684 - Execute['/usr/bin/kinit -kt /etc/security/keytabs/hdfs.headless.keytab hdfs- @HDINSIGHT01.GO. .COM'] {'user': 'hdfs'}
2021-12-18 10:58:21,710 - File['/var/lib/ambari-agent/tmp/hdfs_resources_1639825101.68.json'] {'content': '[{"group": "hdfs", "target": "/ranger/audit", "dfs_type": "HDFS", "recursiveChmod": true, "action": "create", "manageIfExists": true, "mode": "755", "nameservice": null, "owner": "hdfs", "type": "directory"}, {"group": "hive", "target": "/ranger/audit/hiveServer2", "dfs_type": "HDFS", "recursiveChmod": true, "action": "create", "manageIfExists": true, "mode": "700", "nameservice": null, "owner": "hive", "type": "directory"}]', 'owner': 'hdfs'}
2021-12-18 10:58:21,710 - Writing File['/var/lib/ambari-agent/tmp/hdfs_resources_1639825101.68.json'] because it doesn't exist
2021-12-18 10:58:21,711 - Changing owner for /var/lib/ambari-agent/tmp/hdfs_resources_1639825101.68.json from 0 to hdfs
2021-12-18 10:58:21,711 - Execute['hadoop --config /usr/hdp/3.1.6.0-77/hadoop/conf jar /var/lib/ambari-agent/lib/fast-hdfs-resource.jar /var/lib/ambari-agent/tmp/hdfs_resources_1639825101.68.json'] {'logoutput': None, 'path': ['/usr/hdp/3.1.6.0-77/hadoop/bin'], 'user': 'hdfs'}
2021-12-18 11:00:49,932 - call['ambari-python-wrap /usr/bin/hdp-select status hive-server2'] {'timeout': 20}
2021-12-18 11:00:49,954 - call returned (0, 'hive-server2 - 3.1.6.0-77')
2021-12-18 11:00:49,970 - Skipping Ranger API calls, as policy cache file exists for hive
2021-12-18 11:00:49,970 - If service name for hive is not created on Ranger Admin, then to re-create it delete policy cache file: /etc/ranger/ /policycache/hiveServer2_ .json
2021-12-18 11:00:49,971 - File['/usr/hdp/current/hive-server2/conf//ranger-security.xml'] {'content': InlineTemplate(...), 'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 11:00:49,972 - Writing File['/usr/hdp/current/hive-server2/conf//ranger-security.xml'] because contents don't match
2021-12-18 11:00:49,972 - Directory['/etc/ranger/ '] {'owner': 'hive', 'create_parents': True, 'group': 'hadoop', 'mode': 0775, 'cd_access': 'a'}
2021-12-18 11:00:49,973 - Directory['/etc/ranger/ /policycache'] {'owner': 'hive', 'group': 'hadoop', 'create_parents': True, 'mode': 0775, 'cd_access': 'a'}
2021-12-18 11:00:49,973 - File['/etc/ranger/ /policycache/hiveServer2_ .json'] {'owner': 'hive', 'group': 'hadoop', 'mode': 0644}
2021-12-18 11:00:49,974 - XmlConfig['ranger-hive-audit.xml'] {'group': 'hadoop', 'conf_dir': '/usr/hdp/current/hive-server2/conf/', 'mode': 0744, 'configuration_attributes': {}, 'owner': 'hive', 'configurations': ...}
2021-12-18 11:00:49,981 - Generating config: /usr/hdp/current/hive-server2/conf/ranger-hive-audit.xml
2021-12-18 11:00:49,981 - File['/usr/hdp/current/hive-server2/conf/ranger-hive-audit.xml'] {'owner': 'hive', 'content': InlineTemplate(...), 'group': 'hadoop', 'mode': 0744, 'encoding': 'UTF-8'}
2021-12-18 11:00:49,998 - XmlConfig['ranger-hive-security.xml'] {'group': 'hadoop', 'conf_dir': '/usr/hdp/current/hive-server2/conf/', 'mode': 0744, 'configuration_attributes': {}, 'owner': 'hive', 'configurations': ...}
2021-12-18 11:00:50,005 - Generating config: /usr/hdp/current/hive-server2/conf/ranger-hive-security.xml
2021-12-18 11:00:50,005 - File['/usr/hdp/current/hive-server2/conf/ranger-hive-security.xml'] {'owner': 'hive', 'content': InlineTemplate(...), 'group': 'hadoop', 'mode': 0744, 'encoding': 'UTF-8'}
2021-12-18 11:00:50,011 - XmlConfig['ranger-policymgr-ssl.xml'] {'group': 'hadoop', 'conf_dir': '/usr/hdp/current/hive-server2/conf/', 'mode': 0744, 'configuration_attributes': {}, 'owner': 'hive', 'configurations': ...}
2021-12-18 11:00:50,018 - Generating config: /usr/hdp/current/hive-server2/conf/ranger-policymgr-ssl.xml
2021-12-18 11:00:50,018 - File['/usr/hdp/current/hive-server2/conf/ranger-policymgr-ssl.xml'] {'owner': 'hive', 'content': InlineTemplate(...), 'group': 'hadoop', 'mode': 0744, 'encoding': 'UTF-8'}
2021-12-18 11:00:50,023 - Execute[(u'/usr/hdp/3.1.6.0-77/ranger-hive-plugin/ranger_credential_helper.py', '-l', u'/usr/hdp/3.1.6.0-77/ranger-hive-plugin/install/lib/*', '-f', u'/etc/ranger/ /cred.jceks', '-k', 'sslKeyStore', '-v', [PROTECTED], '-c', '1')] {'logoutput': True, 'environment': {'JAVA_HOME': u'/usr/lib/jvm/zulu-8-azure-amd64'}, 'sudo': True}
Using Java:/usr/lib/jvm/zulu-8-azure-amd64/bin/java
Alias sslKeyStore created successfully!
2021-12-18 11:00:51,528 - Execute[(u'/usr/hdp/3.1.6.0-77/ranger-hive-plugin/ranger_credential_helper.py', '-l', u'/usr/hdp/3.1.6.0-77/ranger-hive-plugin/install/lib/*', '-f', u'/etc/ranger/ /cred.jceks', '-k', 'sslTrustStore', '-v', [PROTECTED], '-c', '1')] {'logoutput': True, 'environment': {'JAVA_HOME': u'/usr/lib/jvm/zulu-8-azure-amd64'}, 'sudo': True}
Using Java:/usr/lib/jvm/zulu-8-azure-amd64/bin/java
Alias sslTrustStore created successfully!
2021-12-18 11:00:52,375 - File['/etc/ranger/ /cred.jceks'] {'owner': 'hive', 'group': 'hadoop', 'mode': 0640}
2021-12-18 11:00:52,376 - File['/etc/ranger/ /.cred.jceks.crc'] {'owner': 'hive', 'only_if': 'test -e /etc/ranger/ /.cred.jceks.crc', 'group': 'hadoop', 'mode': 0640}
2021-12-18 11:00:52,383 - call['ambari-sudo.sh su hive -l -s /bin/bash -c 'cat /var/run/hive/hive-server.pid 1>/tmp/ 2>/tmp/ ''] {'quiet': False}
2021-12-18 11:00:52,404 - call returned (1, '')
2021-12-18 11:00:52,404 - Execution of 'cat /var/run/hive/hive-server.pid 1>/tmp/ 2>/tmp/ ' returned 1. cat: /var/run/hive/hive-server.pid: No such file or directory

2021-12-18 11:00:52,405 - get_user_call_output returned (1, u'', u'cat: /var/run/hive/hive-server.pid: No such file or directory')
2021-12-18 11:00:52,405 - Skipping fs root check as fs_root does not start with hdfs://
2021-12-18 11:00:52,406 - Execute['/var/lib/ambari-agent/tmp/start_hiveserver2_script /var/log/hive/hive-server2.out /var/log/hive/hive-server2.err /var/run/hive/hive-server.pid /usr/hdp/current/hive-server2/conf/ /etc/tez/conf'] {'environment': {'HIVE_BIN': 'hive', 'JAVA_HOME': u'/usr/lib/jvm/zulu-8-azure-amd64', 'HADOOP_HOME': u'/usr/hdp/current/hadoop-client'}, 'not_if': 'ls /var/run/hive/hive-server.pid >/dev/null 2>&1 && ps -p >/dev/null 2>&1', 'user': 'hive', 'path': [u'/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/var/lib/ambari-agent:/usr/hdp/current/hive-server2/bin:/usr/hdp/3.1.6.0-77/hadoop/bin']}
2021-12-18 11:00:52,432 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:00:53,051 - call returned (1, '')
2021-12-18 11:00:53,052 - Will retry 29 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:01:03,062 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:01:03,659 - call returned (1, '')
2021-12-18 11:01:03,660 - Will retry 28 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:01:13,671 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:01:14,288 - call returned (1, '')
2021-12-18 11:01:14,289 - Will retry 27 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:01:24,299 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:01:24,929 - call returned (1, '')
2021-12-18 11:01:24,930 - Will retry 26 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:01:34,940 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:01:35,537 - call returned (1, '')
2021-12-18 11:01:35,538 - Will retry 25 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:01:45,548 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:01:46,149 - call returned (1, '')
2021-12-18 11:01:46,149 - Will retry 24 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:01:56,160 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:01:56,766 - call returned (1, '')
2021-12-18 11:01:56,768 - Will retry 23 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:02:06,770 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:02:07,368 - call returned (1, '')
2021-12-18 11:02:07,369 - Will retry 22 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:02:17,380 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:02:17,995 - call returned (1, '')
2021-12-18 11:02:17,996 - Will retry 21 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:02:28,006 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:02:28,606 - call returned (1, '')
2021-12-18 11:02:28,607 - Will retry 20 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:02:38,617 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:02:39,219 - call returned (1, '')
2021-12-18 11:02:39,220 - Will retry 19 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:02:49,227 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:02:49,831 - call returned (1, '')
2021-12-18 11:02:49,832 - Will retry 18 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:02:59,842 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:03:00,445 - call returned (1, '')
2021-12-18 11:03:00,446 - Will retry 17 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:03:10,457 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:03:11,056 - call returned (1, '')
2021-12-18 11:03:11,057 - Will retry 16 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:03:21,066 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:03:21,667 - call returned (1, '')
2021-12-18 11:03:21,668 - Will retry 15 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:03:31,678 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:03:32,308 - call returned (1, '')
2021-12-18 11:03:32,309 - Will retry 14 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:03:42,319 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:03:42,931 - call returned (1, '')
2021-12-18 11:03:42,932 - Will retry 13 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:03:52,942 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:03:53,540 - call returned (1, '')
2021-12-18 11:03:53,541 - Will retry 12 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:04:03,552 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:04:04,147 - call returned (1, '')
2021-12-18 11:04:04,148 - Will retry 11 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:04:14,158 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:04:14,747 - call returned (1, '')
2021-12-18 11:04:14,748 - Will retry 10 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:04:24,756 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:04:25,363 - call returned (1, '')
2021-12-18 11:04:25,363 - Will retry 9 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:04:35,364 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:04:35,965 - call returned (1, '')
2021-12-18 11:04:35,966 - Will retry 8 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:04:45,977 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:04:46,579 - call returned (1, '')
2021-12-18 11:04:46,579 - Will retry 7 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:04:56,590 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:04:57,180 - call returned (1, '')
2021-12-18 11:04:57,181 - Will retry 6 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:05:07,182 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:05:07,796 - call returned (1, '')
2021-12-18 11:05:07,797 - Will retry 5 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:05:17,808 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:05:18,404 - call returned (1, '')
2021-12-18 11:05:18,405 - Will retry 4 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:05:28,415 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:05:29,022 - call returned (1, '')
2021-12-18 11:05:29,023 - Will retry 3 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:05:39,033 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:05:39,633 - call returned (1, '')
2021-12-18 11:05:39,634 - Will retry 2 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:05:49,640 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:05:50,232 - call returned (1, '')
2021-12-18 11:05:50,232 - Will retry 1 time(s), caught exception: ZooKeeper node /hiveserver2 is not ready yet. Sleeping for 10 sec(s)
2021-12-18 11:06:00,240 - call['/usr/hdp/current/zookeeper-client/bin/zkCli.sh -server zk0- .go. .com:2181,zk1- .go. .com:2181,zk3- .go. .com:2181 ls /hiveserver2 | grep 'serverUri=''] {}
2021-12-18 11:06:00,841 - call returned (1, '')

Command failed after 1 tries

Solution:

Step 1 : Logged in Azure HDInsights and then stop the Hive service.
Step 2 : After 100% completed to the Hive then Start the service.