INFO 2017-08-24 09:57:46,592 main.py:90 - loglevel=logging.INFO INFO 2017-08-24 09:57:46,592 main.py:90 - loglevel=logging.INFO INFO 2017-08-24 09:57:46,592 main.py:90 - loglevel=logging.INFO INFO 2017-08-24 09:57:46,594 DataCleaner.py:39 - Data cleanup thread started INFO 2017-08-24 09:57:46,594 DataCleaner.py:120 - Data cleanup started INFO 2017-08-24 09:57:46,595 DataCleaner.py:122 - Data cleanup finished INFO 2017-08-24 09:57:46,655 PingPortListener.py:50 - Ping port listener started on port: 8670 INFO 2017-08-24 09:57:46,655 main.py:349 - Connecting to Ambari server at https://10.10.48.3:8440 (10.10.48.3) INFO 2017-08-24 09:57:46,656 NetUtil.py:62 - Connecting to https://10.10.48.3:8440/ca INFO 2017-08-24 09:57:46,858 main.py:359 - Connected to Ambari server 10.10.48.3 INFO 2017-08-24 09:57:46,863 threadpool.py:52 - Started thread pool with 3 core threads and 20 maximum threads WARNING 2017-08-24 09:57:46,863 AlertSchedulerHandler.py:261 - [AlertScheduler] /var/lib/ambari-agent/cache/alerts/definitions.json not found or invalid. No alerts will be scheduled until registration occurs. INFO 2017-08-24 09:57:46,863 AlertSchedulerHandler.py:156 - [AlertScheduler] Starting ; currently running: False INFO 2017-08-24 09:57:47,546 hostname.py:95 - Read public hostname 'proxy-server.novalocal' from http://169.254.169.254/latest/meta-data/public-hostname INFO 2017-08-24 09:57:47,554 logger.py:71 - call[['test', '-w', '/dev']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 09:57:47,559 logger.py:71 - call returned (0, '') INFO 2017-08-24 09:57:47,559 logger.py:71 - call[['test', '-w', '/run']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 09:57:47,563 logger.py:71 - call returned (0, '') INFO 2017-08-24 09:57:47,563 logger.py:71 - call[['test', '-w', '/']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 09:57:47,567 logger.py:71 - call returned (0, '') INFO 2017-08-24 09:57:47,568 logger.py:71 - call[['test', '-w', '/sys/fs/cgroup']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 09:57:47,572 logger.py:71 - call returned (0, '') INFO 2017-08-24 09:57:47,572 logger.py:71 - call[['test', '-w', '/run/lock']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 09:57:47,575 logger.py:71 - call returned (0, '') INFO 2017-08-24 09:57:47,576 logger.py:71 - call[['test', '-w', '/run/shm']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 09:57:47,580 logger.py:71 - call returned (0, '') INFO 2017-08-24 09:57:47,580 logger.py:71 - call[['test', '-w', '/run/user']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 09:57:47,584 logger.py:71 - call returned (0, '') INFO 2017-08-24 09:57:47,608 Facter.py:194 - Directory: '/etc/resource_overrides' does not exist - it won't be used for gathering system resources. INFO 2017-08-24 09:57:47,844 Controller.py:160 - Registering with slave0.acme11.com (10.10.48.5) (agent='{"hardwareProfile": {"kernel": "Linux", "domain": "acme11.com", "physicalprocessorcount": 4, "kernelrelease": "3.13.0-119-generic", "uptime_days": "0", "memorytotal": 8176772, "swapfree": "0.00 GB", "memorysize": 8176772, "osfamily": "ubuntu", "swapsize": "0.00 GB", "processorcount": 4, "netmask": "255.255.255.0", "timezone": "IST", "hardwareisa": "x86_64", "memoryfree": 7550056, "operatingsystem": "ubuntu", "kernelmajversion": "3.13", "kernelversion": "3.13.0", "macaddress": "02:C2:47:CE:F4:1C", "operatingsystemrelease": "14.04", "ipaddress": "10.10.48.5", "hostname": "slave0", "uptime_hours": "0", "fqdn": "slave0.acme11.com", "id": "root", "architecture": "x86_64", "selinux": false, "mounts": [{"available": "4077128", "used": "12", "percent": "1%", "device": "udev", "mountpoint": "/dev", "type": "devtmpfs", "size": "4077140"}, {"available": "817312", "used": "368", "percent": "1%", "device": "tmpfs", "mountpoint": "/run", "type": "tmpfs", "size": "817680"}, {"available": "28101212", "used": "1392980", "percent": "5%", "device": "/dev/vda1", "mountpoint": "/", "type": "ext4", "size": "30831612"}, {"available": "4", "used": "0", "percent": "0%", "device": "none", "mountpoint": "/sys/fs/cgroup", "type": "tmpfs", "size": "4"}, {"available": "5120", "used": "0", "percent": "0%", "device": "none", "mountpoint": "/run/lock", "type": "tmpfs", "size": "5120"}, {"available": "4088384", "used": "0", "percent": "0%", "device": "none", "mountpoint": "/run/shm", "type": "tmpfs", "size": "4088384"}, {"available": "102400", "used": "0", "percent": "0%", "device": "none", "mountpoint": "/run/user", "type": "tmpfs", "size": "102400"}], "hardwaremodel": "x86_64", "uptime_seconds": "46", "interfaces": "eth0,lo"}, "currentPingPort": 8670, "prefix": "/var/lib/ambari-agent/data", "agentVersion": "2.4.1.0", "agentEnv": {"transparentHugePage": "always", "hostHealth": {"agentTimeStampAtReporting": 1503548867840, "activeJavaProcs": [], "liveServices": [{"status": "Healthy", "name": "ntp", "desc": ""}]}, "reverseLookup": true, "alternatives": [], "umask": "18", "firewallName": "ufw", "stackFoldersAndFiles": [], "existingUsers": [], "firewallRunning": false}, "timestamp": 1503548867611, "hostname": "slave0.acme11.com", "responseId": -1, "publicHostname": "proxy-server.novalocal"}') INFO 2017-08-24 09:57:47,844 NetUtil.py:62 - Connecting to https://10.10.48.3:8440/connection_info INFO 2017-08-24 09:57:48,015 security.py:100 - SSL Connect being called.. connecting to the server INFO 2017-08-24 09:57:48,149 security.py:61 - SSL connection established. Two-way SSL authentication is turned off on the server. ERROR 2017-08-24 09:57:48,188 Controller.py:180 - Cannot register host with non compatible agent version, hostname=slave0.acme11.com, agentVersion=2.4.1.0, serverVersion=2.2.1.1 INFO 2017-08-24 09:57:48,188 Controller.py:463 - Registration response from 10.10.48.3 was FAILED INFO 2017-08-24 09:57:48,188 Controller.py:478 - Registration response from %s didn't contain 'response' as a key INFO 2017-08-24 09:57:48,188 Controller.py:450 - Finished heartbeating and registering cycle INFO 2017-08-24 09:57:48,188 Controller.py:456 - Controller thread has successfully finished INFO 2017-08-24 09:57:48,268 ExitHelper.py:53 - Performing cleanup before exiting... INFO 2017-08-24 09:57:48,268 ExitHelper.py:67 - Cleanup finished, exiting with code:0 INFO 2017-08-24 10:02:38,953 main.py:90 - loglevel=logging.INFO INFO 2017-08-24 10:02:38,954 main.py:90 - loglevel=logging.INFO INFO 2017-08-24 10:02:38,954 main.py:90 - loglevel=logging.INFO INFO 2017-08-24 10:02:38,955 DataCleaner.py:39 - Data cleanup thread started INFO 2017-08-24 10:02:38,956 DataCleaner.py:120 - Data cleanup started INFO 2017-08-24 10:02:38,957 DataCleaner.py:122 - Data cleanup finished INFO 2017-08-24 10:02:38,965 PingPortListener.py:50 - Ping port listener started on port: 8670 INFO 2017-08-24 10:02:38,966 main.py:349 - Connecting to Ambari server at https://10.10.48.3:8440 (10.10.48.3) INFO 2017-08-24 10:02:38,966 NetUtil.py:62 - Connecting to https://10.10.48.3:8440/ca INFO 2017-08-24 10:02:39,093 main.py:359 - Connected to Ambari server 10.10.48.3 INFO 2017-08-24 10:02:39,099 threadpool.py:52 - Started thread pool with 3 core threads and 20 maximum threads WARNING 2017-08-24 10:02:39,099 AlertSchedulerHandler.py:261 - [AlertScheduler] /var/lib/ambari-agent/cache/alerts/definitions.json not found or invalid. No alerts will be scheduled until registration occurs. INFO 2017-08-24 10:02:39,099 AlertSchedulerHandler.py:156 - [AlertScheduler] Starting ; currently running: False INFO 2017-08-24 10:02:39,373 hostname.py:95 - Read public hostname 'proxy-server.novalocal' from http://169.254.169.254/latest/meta-data/public-hostname INFO 2017-08-24 10:02:39,379 logger.py:71 - call[['test', '-w', '/dev']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:02:39,383 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:02:39,383 logger.py:71 - call[['test', '-w', '/run']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:02:39,387 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:02:39,387 logger.py:71 - call[['test', '-w', '/']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:02:39,391 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:02:39,391 logger.py:71 - call[['test', '-w', '/sys/fs/cgroup']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:02:39,396 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:02:39,396 logger.py:71 - call[['test', '-w', '/run/lock']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:02:39,400 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:02:39,400 logger.py:71 - call[['test', '-w', '/run/shm']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:02:39,404 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:02:39,405 logger.py:71 - call[['test', '-w', '/run/user']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:02:39,409 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:02:39,428 Facter.py:194 - Directory: '/etc/resource_overrides' does not exist - it won't be used for gathering system resources. INFO 2017-08-24 10:02:39,524 Controller.py:160 - Registering with slave0.acme11.com (10.10.48.5) (agent='{"hardwareProfile": {"kernel": "Linux", "domain": "acme11.com", "physicalprocessorcount": 4, "kernelrelease": "3.13.0-119-generic", "uptime_days": "0", "memorytotal": 8176772, "swapfree": "0.00 GB", "memorysize": 8176772, "osfamily": "ubuntu", "swapsize": "0.00 GB", "processorcount": 4, "netmask": "255.255.255.0", "timezone": "IST", "hardwareisa": "x86_64", "memoryfree": 7565804, "operatingsystem": "ubuntu", "kernelmajversion": "3.13", "kernelversion": "3.13.0", "macaddress": "02:C2:47:CE:F4:1C", "operatingsystemrelease": "14.04", "ipaddress": "10.10.48.5", "hostname": "slave0", "uptime_hours": "0", "fqdn": "slave0.acme11.com", "id": "root", "architecture": "x86_64", "selinux": false, "mounts": [{"available": "4077128", "used": "12", "percent": "1%", "device": "udev", "mountpoint": "/dev", "type": "devtmpfs", "size": "4077140"}, {"available": "817304", "used": "376", "percent": "1%", "device": "tmpfs", "mountpoint": "/run", "type": "tmpfs", "size": "817680"}, {"available": "28101816", "used": "1392376", "percent": "5%", "device": "/dev/vda1", "mountpoint": "/", "type": "ext4", "size": "30831612"}, {"available": "4", "used": "0", "percent": "0%", "device": "none", "mountpoint": "/sys/fs/cgroup", "type": "tmpfs", "size": "4"}, {"available": "5120", "used": "0", "percent": "0%", "device": "none", "mountpoint": "/run/lock", "type": "tmpfs", "size": "5120"}, {"available": "4088384", "used": "0", "percent": "0%", "device": "none", "mountpoint": "/run/shm", "type": "tmpfs", "size": "4088384"}, {"available": "102400", "used": "0", "percent": "0%", "device": "none", "mountpoint": "/run/user", "type": "tmpfs", "size": "102400"}], "hardwaremodel": "x86_64", "uptime_seconds": "338", "interfaces": "eth0,lo"}, "currentPingPort": 8670, "prefix": "/var/lib/ambari-agent/data", "agentVersion": "2.4.1.0", "agentEnv": {"transparentHugePage": "always", "hostHealth": {"agentTimeStampAtReporting": 1503549159520, "activeJavaProcs": [], "liveServices": [{"status": "Healthy", "name": "ntp", "desc": ""}]}, "reverseLookup": true, "alternatives": [], "umask": "18", "firewallName": "ufw", "stackFoldersAndFiles": [], "existingUsers": [], "firewallRunning": false}, "timestamp": 1503549159431, "hostname": "slave0.acme11.com", "responseId": -1, "publicHostname": "proxy-server.novalocal"}') INFO 2017-08-24 10:02:39,524 NetUtil.py:62 - Connecting to https://10.10.48.3:8440/connection_info INFO 2017-08-24 10:02:39,644 security.py:100 - SSL Connect being called.. connecting to the server INFO 2017-08-24 10:02:39,756 security.py:61 - SSL connection established. Two-way SSL authentication is turned off on the server. ERROR 2017-08-24 10:02:39,761 Controller.py:180 - Cannot register host with non compatible agent version, hostname=slave0.acme11.com, agentVersion=2.4.1.0, serverVersion=2.2.1.1 INFO 2017-08-24 10:02:39,761 Controller.py:463 - Registration response from 10.10.48.3 was FAILED INFO 2017-08-24 10:02:39,761 Controller.py:478 - Registration response from %s didn't contain 'response' as a key INFO 2017-08-24 10:02:39,761 Controller.py:450 - Finished heartbeating and registering cycle INFO 2017-08-24 10:02:39,761 Controller.py:456 - Controller thread has successfully finished INFO 2017-08-24 10:02:39,802 ExitHelper.py:53 - Performing cleanup before exiting... INFO 2017-08-24 10:02:39,802 ExitHelper.py:67 - Cleanup finished, exiting with code:0 INFO 2017-08-24 10:05:10,975 main.py:90 - loglevel=logging.INFO INFO 2017-08-24 10:05:10,976 main.py:90 - loglevel=logging.INFO INFO 2017-08-24 10:05:10,976 main.py:90 - loglevel=logging.INFO INFO 2017-08-24 10:05:10,976 DataCleaner.py:39 - Data cleanup thread started INFO 2017-08-24 10:05:10,977 DataCleaner.py:120 - Data cleanup started INFO 2017-08-24 10:05:10,979 DataCleaner.py:122 - Data cleanup finished INFO 2017-08-24 10:05:10,987 PingPortListener.py:50 - Ping port listener started on port: 8670 INFO 2017-08-24 10:05:10,988 main.py:349 - Connecting to Ambari server at https://10.10.48.3:8440 (10.10.48.3) INFO 2017-08-24 10:05:10,988 NetUtil.py:62 - Connecting to https://10.10.48.3:8440/ca INFO 2017-08-24 10:05:11,087 main.py:359 - Connected to Ambari server 10.10.48.3 INFO 2017-08-24 10:05:11,093 threadpool.py:52 - Started thread pool with 3 core threads and 20 maximum threads WARNING 2017-08-24 10:05:11,093 AlertSchedulerHandler.py:261 - [AlertScheduler] /var/lib/ambari-agent/cache/alerts/definitions.json not found or invalid. No alerts will be scheduled until registration occurs. INFO 2017-08-24 10:05:11,093 AlertSchedulerHandler.py:156 - [AlertScheduler] Starting ; currently running: False INFO 2017-08-24 10:05:11,348 hostname.py:95 - Read public hostname 'proxy-server.novalocal' from http://169.254.169.254/latest/meta-data/public-hostname INFO 2017-08-24 10:05:11,352 logger.py:71 - call[['test', '-w', '/dev']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:05:11,357 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:05:11,357 logger.py:71 - call[['test', '-w', '/run']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:05:11,361 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:05:11,362 logger.py:71 - call[['test', '-w', '/']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:05:11,366 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:05:11,366 logger.py:71 - call[['test', '-w', '/sys/fs/cgroup']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:05:11,370 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:05:11,370 logger.py:71 - call[['test', '-w', '/run/lock']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:05:11,374 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:05:11,374 logger.py:71 - call[['test', '-w', '/run/shm']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:05:11,378 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:05:11,378 logger.py:71 - call[['test', '-w', '/run/user']] {'sudo': True, 'timeout': 5} INFO 2017-08-24 10:05:11,382 logger.py:71 - call returned (0, '') INFO 2017-08-24 10:05:11,403 Facter.py:194 - Directory: '/etc/resource_overrides' does not exist - it won't be used for gathering system resources. INFO 2017-08-24 10:05:11,497 Controller.py:160 - Registering with slave0.acme11.com (10.10.48.5) (agent='{"hardwareProfile": {"kernel": "Linux", "domain": "acme11.com", "physicalprocessorcount": 4, "kernelrelease": "3.13.0-119-generic", "uptime_days": "0", "memorytotal": 8176772, "swapfree": "0.00 GB", "memorysize": 8176772, "osfamily": "ubuntu", "swapsize": "0.00 GB", "processorcount": 4, "netmask": "255.255.255.0", "timezone": "IST", "hardwareisa": "x86_64", "memoryfree": 7537016, "operatingsystem": "ubuntu", "kernelmajversion": "3.13", "kernelversion": "3.13.0", "macaddress": "02:C2:47:CE:F4:1C", "operatingsystemrelease": "14.04", "ipaddress": "10.10.48.5", "hostname": "slave0", "uptime_hours": "0", "fqdn": "slave0.acme11.com", "id": "root", "architecture": "x86_64", "selinux": false, "mounts": [{"available": "4077128", "used": "12", "percent": "1%", "device": "udev", "mountpoint": "/dev", "type": "devtmpfs", "size": "4077140"}, {"available": "817304", "used": "376", "percent": "1%", "device": "tmpfs", "mountpoint": "/run", "type": "tmpfs", "size": "817680"}, {"available": "28101668", "used": "1392524", "percent": "5%", "device": "/dev/vda1", "mountpoint": "/", "type": "ext4", "size": "30831612"}, {"available": "4", "used": "0", "percent": "0%", "device": "none", "mountpoint": "/sys/fs/cgroup", "type": "tmpfs", "size": "4"}, {"available": "5120", "used": "0", "percent": "0%", "device": "none", "mountpoint": "/run/lock", "type": "tmpfs", "size": "5120"}, {"available": "4088384", "used": "0", "percent": "0%", "device": "none", "mountpoint": "/run/shm", "type": "tmpfs", "size": "4088384"}, {"available": "102400", "used": "0", "percent": "0%", "device": "none", "mountpoint": "/run/user", "type": "tmpfs", "size": "102400"}], "hardwaremodel": "x86_64", "uptime_seconds": "490", "interfaces": "eth0,lo"}, "currentPingPort": 8670, "prefix": "/var/lib/ambari-agent/data", "agentVersion": "2.4.1.0", "agentEnv": {"transparentHugePage": "always", "hostHealth": {"agentTimeStampAtReporting": 1503549311493, "activeJavaProcs": [], "liveServices": [{"status": "Healthy", "name": "ntp", "desc": ""}]}, "reverseLookup": true, "alternatives": [], "umask": "18", "firewallName": "ufw", "stackFoldersAndFiles": [], "existingUsers": [], "firewallRunning": false}, "timestamp": 1503549311406, "hostname": "slave0.acme11.com", "responseId": -1, "publicHostname": "proxy-server.novalocal"}') INFO 2017-08-24 10:05:11,497 NetUtil.py:62 - Connecting to https://10.10.48.3:8440/connection_info INFO 2017-08-24 10:05:11,605 security.py:100 - SSL Connect being called.. connecting to the server INFO 2017-08-24 10:05:11,699 security.py:61 - SSL connection established. Two-way SSL authentication is turned off on the server. ERROR 2017-08-24 10:05:11,704 Controller.py:180 - Cannot register host with non compatible agent version, hostname=slave0.acme11.com, agentVersion=2.4.1.0, serverVersion=2.2.1.1 INFO 2017-08-24 10:05:11,704 Controller.py:463 - Registration response from 10.10.48.3 was FAILED INFO 2017-08-24 10:05:11,704 Controller.py:478 - Registration response from %s didn't contain 'response' as a key INFO 2017-08-24 10:05:11,704 Controller.py:450 - Finished heartbeating and registering cycle INFO 2017-08-24 10:05:11,704 Controller.py:456 - Controller thread has successfully finished INFO 2017-08-24 10:05:11,796 ExitHelper.py:53 - Performing cleanup before exiting... INFO 2017-08-24 10:05:11,796 ExitHelper.py:67 - Cleanup finished, exiting with code:0