Skip to content

Instantly share code, notes, and snippets.

@Theramas

Theramas/GCE 2012 debug

Created November 2, 2015 09:43
Show Gist options
  • Save Theramas/73d5d4cfb082926ff52a to your computer and use it in GitHub Desktop.
Save Theramas/73d5d4cfb082926ff52a to your computer and use it in GitHub Desktop.
Download ZIP
Raw
GCE 2012 debug
2015-11-02 09:33:53,240+00:00 - INFO - scalarizr.app - [pid: 1780] Starting scalarizr 4.1.0.695
2015-11-02 09:33:53,240+00:00 - DEBUG - scalarizr.node - Reloading C:\opt\scalarizr\etc/private.d\config.ini
2015-11-02 09:33:53,270+00:00 - DEBUG - agent.cloudinit.metadata.docker - Fitting...
2015-11-02 09:33:53,270+00:00 - DEBUG - agent.cloudinit.metadata.docker - Does not fit
2015-11-02 09:33:53,270+00:00 - DEBUG - agent.cloudinit.metadata.verizon - Fitting...
2015-11-02 09:33:53,270+00:00 - DEBUG - common.utils.subprocess2 - Executing subprocess: ('vmtoolsd --cmd "info-get guestinfo.personality.count"',)
2015-11-02 09:33:53,270+00:00 - DEBUG - agent.cloudinit.metadata.configdrive - Fitting...
2015-11-02 09:33:53,270+00:00 - DEBUG - agent.cloudinit.metadata.configdrive - Does not fit: Not supported on Windows
2015-11-02 09:33:53,270+00:00 - DEBUG - agent.cloudinit.metadata.personality - Fitting...
2015-11-02 09:33:53,270+00:00 - DEBUG - agent.cloudinit.metadata.personality - Does not fit: User-data files ['C:\\Program Files\\Scalarizr\\etc\\private.d\\.user-data', 'C:\\.scalr-user-data'] do not exist or not accessible.
2015-11-02 09:33:53,270+00:00 - DEBUG - agent.cloudinit.metadata.cloudstack - Fitting...
2015-11-02 09:33:53,270+00:00 - DEBUG - agent.cloudinit.metadata.openstack - Fitting...
2015-11-02 09:33:53,270+00:00 - DEBUG - agent.cloudinit.metadata.gce - Fitting...
2015-11-02 09:33:53,286+00:00 - DEBUG - agent.cloudinit.metadata.azure - Fitting...
2015-11-02 09:33:53,286+00:00 - DEBUG - agent.cloudinit.metadata.ec2 - Fitting...
2015-11-02 09:33:53,286+00:00 - DEBUG - agent.cloudinit.metadata.gce - Fits
2015-11-02 09:33:53,286+00:00 - DEBUG - common.utils.subprocess2 - Subprocess stderr: 'vmtoolsd' is not recognized as an internal or external command,
operable program or batch file.
2015-11-02 09:33:53,286+00:00 - DEBUG - common.utils.subprocess2 - Subprocess returncode: 1
2015-11-02 09:33:53,286+00:00 - DEBUG - agent.cloudinit.metadata.verizon - Does not fit: Command 'vmtoolsd --cmd "info-get guestinfo.personality.count"' returned non-zero exit status 1
stdout:
stderr: 'vmtoolsd' is not recognized as an internal or external command,
operable program or batch file.
2015-11-02 09:33:53,349+00:00 - DEBUG - agent.cloudinit.metadata.openstack - Does not fit
2015-11-02 09:33:53,349+00:00 - DEBUG - agent.cloudinit.metadata.ec2 - Does not fit
2015-11-02 09:33:53,365+00:00 - DEBUG - agent.cloudinit.metadata.cloudstack - Select DHCP server: 169.254.169.254
2015-11-02 09:33:53,427+00:00 - DEBUG - agent.cloudinit.metadata.cloudstack - Does not fit
2015-11-02 09:34:03,431+00:00 - DEBUG - agent.cloudinit.metadata.azure - Does not fit
2015-11-02 09:34:03,431+00:00 - DEBUG - agent.cloudinit.metadata - User-data providers that fit: [<GceProvider>]
2015-11-02 09:34:03,431+00:00 - DEBUG - agent.cloudinit.metadata - Best match: <GceProvider>
2015-11-02 09:34:03,431+00:00 - INFO - agent.cloudinit.metadata - User-Data provider: gce
2015-11-02 09:34:03,431+00:00 - INFO - scalarizr.app - This image was bundled with cloud API call. Cleauping ancestor server data
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.app - Applying user-data to configuration
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.app - User-data (Instance):
{u'behaviors': u'base,chef',
u'cloud_location_zone': u'',
u'cloud_server_id': u'',
u'cloud_storage_path': u'gcs://',
u'custom.scm_branch': u'master-fix-SCALARIZR-2054-install-hangs',
u'env_id': u'3414',
u'farm_roleid': u'105108',
u'farmid': u'21564',
u'hash': u'5a3620ee3efcc8',
u'httpproto': u'https',
u'message_format': u'json',
u'owner_email': u'n.kalmikov@scalr.com',
u'p2p_producer_endpoint': u'https://my.scalr.com/messaging',
u'platform': u'gce',
u'queryenv_url': u'https://my.scalr.com/query-env',
u'realrolename': u'base-windows2012-devel',
u'region': u'us-central1-a',
u'role': u'base,chef',
u'roleid': u'54654',
u'server_index': u'1',
u'serverid': u'e217a30a-9683-4423-9953-93363ec5fe08',
u'szr_key': u'DI0KWoLoSdp0HBH18ptaiRW58a3R2vBH+nBK5gjV'}
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.config - Updating configuration file C:\opt\scalarizr\etc\private.d\config.ini
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.app - Reloading configuration after user-data applying
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.config - Bootstrap INI configuration (reload=True)
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.config - Loading main configuration
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\config.ini
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\private.d\config.ini
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.config - Loading platform configuration
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\gce.ini
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.config - Loading behaviours configuration
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\chef.ini
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.config - Loading handlers configuration
2015-11-02 09:34:03,431+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\ip_list_builder.ini
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\script_executor.ini
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\hooks.ini
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Bootstrap INI configuration (reload=True)
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Loading main configuration
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\config.ini
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\private.d\config.ini
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Loading platform configuration
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\gce.ini
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Loading behaviours configuration
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\chef.ini
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Loading handlers configuration
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\ip_list_builder.ini
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\script_executor.ini
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\hooks.ini
2015-11-02 09:34:03,443+00:00 - DEBUG - scalarizr.app - Initialize platform
2015-11-02 09:34:03,552+00:00 - DEBUG - scalarizr.util.wintool - Waiting ImageState: IMAGE_STATE_COMPLETE ...
2015-11-02 09:34:03,552+00:00 - DEBUG - scalarizr.util.wintool - Reached ImageState: IMAGE_STATE_COMPLETE
2015-11-02 09:34:03,552+00:00 - INFO - scalarizr.util.wintool - Windows is ready!
2015-11-02 09:34:03,552+00:00 - DEBUG - scalarizr.app - Database doesn't exist, creating new one from script
2015-11-02 09:34:03,552+00:00 - DEBUG - scalarizr.app - Open SQLite database (file: C:\opt\scalarizr\etc\private.d\db.sqlite)
2015-11-02 09:34:03,568+00:00 - DEBUG - scalarizr.util - Wait 0.10 seconds before the next attempt
2015-11-02 09:34:03,568+00:00 - DEBUG - scalarizr.app - Open SQLite database (file: C:\opt\scalarizr\etc\private.d\db.sqlite)
2015-11-02 09:34:03,772+00:00 - DEBUG - scalarizr.util - system: ('sc', 'query', 'ScalrUpdClient')
2015-11-02 09:34:03,834+00:00 - DEBUG - root - stdout:
SERVICE_NAME: ScalrUpdClient
TYPE : 10 WIN32_OWN_PROCESS
STATE : 1 STOPPED
WIN32_EXIT_CODE : 0 (0x0)
SERVICE_EXIT_CODE : 0 (0x0)
CHECKPOINT : 0x0
WAIT_HINT : 0x0
2015-11-02 09:34:03,834+00:00 - INFO - scalarizr.util.initdv2 - Starting ScalrUpdClient
2015-11-02 09:34:04,411+00:00 - DEBUG - scalarizr.app - Fetching UpdateClient status...
2015-11-02 09:34:06,272+00:00 - DEBUG - scalarizr.app - UpdateClient status: {u'package_type': u'fogyish', u'dist': u'Windows 6.2.9200 2012Server', u'farm_role_id': u'105108', u'repository': u'master-fix-SCALARIZR-2054-install-hangs', u'scalr_id': u'ab6d8171', u'installed': u'3.8.5-1', u'queryenv_url': u'https://my.scalr.com/query-env', u'service_status': u'unknown', u'repo_url': u'http://stridercd.scalr-labs.com/scalarizr/win/develop/master-fix-SCALARIZR-2054-install-hangs/', u'messaging_url': u'https://my.scalr.com/messaging', u'downgrades_enabled': True, u'server_id': u'e217a30a-9683-4423-9953-93363ec5fe08', u'candidate': u'4.1.b695', u'service_version': u'4.1.0.695', u'package': u'scalarizr', u'executed_at': u'Mon 02 Nov 2015 09:32:47 UTC', u'platform': u'gce', u'state': u'in-progress/install', u'prev_state': u'in-progress/prepare', u'system_id': u'GoogleCloud-521AC34ED2511BF8CFCCDE68FA65AB9D', u'scalr_version': u'5.10.7', u'error': u''}
2015-11-02 09:34:06,272+00:00 - INFO - scalarizr.app - UpdateClient state: in-progress/install
2015-11-02 09:34:06,272+00:00 - INFO - scalarizr.app - Update is in-progress, exiting
2015-11-02 09:34:07,052+00:00 - INFO - scalarizr.app - [pid: 2760] Starting scalarizr 4.1.0.695
2015-11-02 09:34:07,052+00:00 - DEBUG - scalarizr.node - Reloading C:\opt\scalarizr\etc/private.d\config.ini
2015-11-02 09:34:07,052+00:00 - DEBUG - agent.cloudinit.metadata.docker - Fitting...
2015-11-02 09:34:07,052+00:00 - DEBUG - agent.cloudinit.metadata.docker - Does not fit
2015-11-02 09:34:07,052+00:00 - DEBUG - agent.cloudinit.metadata.verizon - Fitting...
2015-11-02 09:34:07,052+00:00 - DEBUG - common.utils.subprocess2 - Executing subprocess: ('vmtoolsd --cmd "info-get guestinfo.personality.count"',)
2015-11-02 09:34:07,052+00:00 - DEBUG - agent.cloudinit.metadata.configdrive - Fitting...
2015-11-02 09:34:07,052+00:00 - DEBUG - agent.cloudinit.metadata.configdrive - Does not fit: Not supported on Windows
2015-11-02 09:34:07,052+00:00 - DEBUG - agent.cloudinit.metadata.personality - Fitting...
2015-11-02 09:34:07,052+00:00 - DEBUG - agent.cloudinit.metadata.personality - Does not fit: User-data files ['C:\\Program Files\\Scalarizr\\etc\\private.d\\.user-data', 'C:\\.scalr-user-data'] do not exist or not accessible.
2015-11-02 09:34:07,052+00:00 - DEBUG - agent.cloudinit.metadata.cloudstack - Fitting...
2015-11-02 09:34:07,052+00:00 - DEBUG - agent.cloudinit.metadata.openstack - Fitting...
2015-11-02 09:34:07,068+00:00 - DEBUG - agent.cloudinit.metadata.gce - Fitting...
2015-11-02 09:34:07,068+00:00 - DEBUG - agent.cloudinit.metadata.azure - Fitting...
2015-11-02 09:34:07,068+00:00 - DEBUG - agent.cloudinit.metadata.ec2 - Fitting...
2015-11-02 09:34:07,084+00:00 - DEBUG - agent.cloudinit.metadata.gce - Fits
2015-11-02 09:34:07,084+00:00 - DEBUG - common.utils.subprocess2 - Subprocess stderr: 'vmtoolsd' is not recognized as an internal or external command,
operable program or batch file.
2015-11-02 09:34:07,084+00:00 - DEBUG - common.utils.subprocess2 - Subprocess returncode: 1
2015-11-02 09:34:07,084+00:00 - DEBUG - agent.cloudinit.metadata.verizon - Does not fit: Command 'vmtoolsd --cmd "info-get guestinfo.personality.count"' returned non-zero exit status 1
stdout:
stderr: 'vmtoolsd' is not recognized as an internal or external command,
operable program or batch file.
2015-11-02 09:34:07,131+00:00 - DEBUG - agent.cloudinit.metadata.openstack - Does not fit
2015-11-02 09:34:07,147+00:00 - DEBUG - agent.cloudinit.metadata.ec2 - Does not fit
2015-11-02 09:34:07,147+00:00 - DEBUG - agent.cloudinit.metadata.cloudstack - Select DHCP server: 169.254.169.254
2015-11-02 09:34:07,209+00:00 - DEBUG - agent.cloudinit.metadata.cloudstack - Does not fit
2015-11-02 09:34:17,224+00:00 - DEBUG - agent.cloudinit.metadata.azure - Does not fit
2015-11-02 09:34:17,224+00:00 - DEBUG - agent.cloudinit.metadata - User-data providers that fit: [<GceProvider>]
2015-11-02 09:34:17,224+00:00 - DEBUG - agent.cloudinit.metadata - Best match: <GceProvider>
2015-11-02 09:34:17,224+00:00 - INFO - agent.cloudinit.metadata - User-Data provider: gce
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.app - Applying user-data to configuration
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.app - User-data (Instance):
{u'behaviors': u'base,chef',
u'cloud_location_zone': u'',
u'cloud_server_id': u'',
u'cloud_storage_path': u'gcs://',
u'custom.scm_branch': u'master-fix-SCALARIZR-2054-install-hangs',
u'env_id': u'3414',
u'farm_roleid': u'105108',
u'farmid': u'21564',
u'hash': u'5a3620ee3efcc8',
u'httpproto': u'https',
u'message_format': u'json',
u'owner_email': u'n.kalmikov@scalr.com',
u'p2p_producer_endpoint': u'https://my.scalr.com/messaging',
u'platform': u'gce',
u'queryenv_url': u'https://my.scalr.com/query-env',
u'realrolename': u'base-windows2012-devel',
u'region': u'us-central1-a',
u'role': u'base,chef',
u'roleid': u'54654',
u'server_index': u'1',
u'serverid': u'e217a30a-9683-4423-9953-93363ec5fe08',
u'szr_key': u'DI0KWoLoSdp0HBH18ptaiRW58a3R2vBH+nBK5gjV'}
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Updating configuration file C:\opt\scalarizr\etc\private.d\config.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.app - Reloading configuration after user-data applying
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Bootstrap INI configuration (reload=True)
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Loading main configuration
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\config.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\private.d\config.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Loading platform configuration
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\gce.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Loading behaviours configuration
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\chef.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Loading handlers configuration
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\ip_list_builder.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\script_executor.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\hooks.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Bootstrap INI configuration (reload=True)
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Loading main configuration
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\config.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\private.d\config.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Loading platform configuration
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\gce.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Loading behaviours configuration
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\chef.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Loading handlers configuration
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\ip_list_builder.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\script_executor.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.config - Reading configuration file C:\opt\scalarizr\etc\public.d\hooks.ini
2015-11-02 09:34:17,224+00:00 - DEBUG - scalarizr.app - Initialize platform
2015-11-02 09:34:17,318+00:00 - DEBUG - scalarizr.util.wintool - Waiting ImageState: IMAGE_STATE_COMPLETE ...
2015-11-02 09:34:17,318+00:00 - DEBUG - scalarizr.util.wintool - Reached ImageState: IMAGE_STATE_COMPLETE
2015-11-02 09:34:17,318+00:00 - INFO - scalarizr.util.wintool - Windows is ready!
2015-11-02 09:34:17,318+00:00 - DEBUG - scalarizr.util - Wait 0.10 seconds before the next attempt
2015-11-02 09:34:17,318+00:00 - DEBUG - scalarizr.app - Open SQLite database (file: C:\opt\scalarizr\etc\private.d\db.sqlite)
2015-11-02 09:34:17,552+00:00 - DEBUG - scalarizr.util - system: ('sc', 'query', 'ScalrUpdClient')
2015-11-02 09:34:17,552+00:00 - DEBUG - root - stdout:
SERVICE_NAME: ScalrUpdClient
TYPE : 10 WIN32_OWN_PROCESS
STATE : 4 RUNNING
(STOPPABLE, NOT_PAUSABLE, IGNORES_SHUTDOWN)
WIN32_EXIT_CODE : 0 (0x0)
SERVICE_EXIT_CODE : 0 (0x0)
CHECKPOINT : 0x0
WAIT_HINT : 0x0
2015-11-02 09:34:17,552+00:00 - DEBUG - scalarizr.app - Fetching UpdateClient status...
2015-11-02 09:34:18,224+00:00 - DEBUG - scalarizr.app - UpdateClient status: {u'package_type': u'fogyish', u'dist': u'Windows 6.2.9200 2012Server', u'farm_role_id': u'105108', u'repository': u'master-fix-SCALARIZR-2054-install-hangs', u'scalr_id': u'ab6d8171', u'installed': u'3.8.5-1', u'queryenv_url': u'https://my.scalr.com/query-env', u'service_status': u'unknown', u'repo_url': u'http://stridercd.scalr-labs.com/scalarizr/win/develop/master-fix-SCALARIZR-2054-install-hangs/', u'messaging_url': u'https://my.scalr.com/messaging', u'downgrades_enabled': True, u'server_id': u'e217a30a-9683-4423-9953-93363ec5fe08', u'candidate': u'4.1.b695', u'service_version': u'4.1.0.695', u'package': u'scalarizr', u'executed_at': u'Mon 02 Nov 2015 09:32:47 UTC', u'platform': u'gce', u'state': u'completed', u'prev_state': u'in-progress/install', u'system_id': u'GoogleCloud-521AC34ED2511BF8CFCCDE68FA65AB9D', u'scalr_version': u'5.10.7', u'error': u''}
2015-11-02 09:34:18,224+00:00 - INFO - scalarizr.app - UpdateClient state: completed
2015-11-02 09:34:18,224+00:00 - DEBUG - scalarizr.config - Bootstrap INI configuration (reload=False)
2015-11-02 09:34:18,224+00:00 - DEBUG - scalarizr.config - Bootstrap INI configuration (reload=False)
2015-11-02 09:34:18,224+00:00 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.lifecycle
2015-11-02 09:34:18,256+00:00 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.lifecycle
2015-11-02 09:34:18,272+00:00 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.ip_list_builder
2015-11-02 09:34:18,272+00:00 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.ip_list_builder
2015-11-02 09:34:18,272+00:00 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.script_executor
2015-11-02 09:34:18,272+00:00 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.script_executor
2015-11-02 09:34:18,272+00:00 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.hooks
2015-11-02 09:34:18,272+00:00 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.hooks
2015-11-02 09:34:18,272+00:00 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.ssh_auth_keys
2015-11-02 09:34:18,272+00:00 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.ssh_auth_keys
2015-11-02 09:34:18,272+00:00 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.deploy
2015-11-02 09:34:18,272+00:00 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.deploy
2015-11-02 09:34:18,272+00:00 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.gce.rebundle
2015-11-02 09:34:18,302+00:00 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.gce.rebundle
2015-11-02 09:34:18,302+00:00 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.gce.lifecycle
2015-11-02 09:34:18,302+00:00 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.gce.lifecycle
2015-11-02 09:34:18,302+00:00 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.gce.vol
2015-11-02 09:34:18,302+00:00 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.gce.vol
2015-11-02 09:34:18,302+00:00 - DEBUG - scalarizr.config - Importing module scalarizr.handlers.chef
2015-11-02 09:34:18,302+00:00 - DEBUG - scalarizr.config - Lookup config container in module scalarizr.handlers.chef
2015-11-02 09:34:18,302+00:00 - DEBUG - scalarizr.node - Reloading C:\opt\scalarizr\etc/private.d\config.ini
2015-11-02 09:34:18,302+00:00 - DEBUG - scalarizr.app - Got scalr url: 'https://my.scalr.com'
2015-11-02 09:34:18,302+00:00 - DEBUG - scalarizr.app - Initialize QueryEnv client
2015-11-02 09:34:18,302+00:00 - DEBUG - scalarizr.node - Reloading C:\opt\scalarizr\etc/public.d\config.ini
2015-11-02 09:34:18,365+00:00 - DEBUG - scalarizr.queryenv - Call QueryEnv: https://my.scalr.com/query-env/2015-04-10/get-global-config
2015-11-02 09:34:18,365+00:00 - DEBUG - scalarizr.queryenv - QueryEnv request: {'operation': 'get-global-config', 'version': u'2015-04-10'}
2015-11-02 09:34:19,302+00:00 - DEBUG - scalarizr.queryenv - QueryEnv response (parsed): {'scalr.id': 'ab6d8171', 'scalr.version': '5.10.7', 'dns.static.endpoint': 'scalr-dns.net'}
2015-11-02 09:34:19,302+00:00 - DEBUG - scalarizr.queryenv - Call QueryEnv: https://my.scalr.com/query-env/2015-04-10/list-farm-role-params-json
2015-11-02 09:34:19,302+00:00 - DEBUG - scalarizr.queryenv - QueryEnv request: {'operation': 'list-farm-role-params-json', 'version': u'2015-04-10', 'farm-role-id': '105108'}
2015-11-02 09:34:19,756+00:00 - DEBUG - scalarizr.queryenv - QueryEnv response (parsed): {u'chef': {}, u'base': {u'reboot_after_hostinit_phase': 0, u'disable_firewall_management': 0, u'abort_init_on_script_fail': 1, u'hostname': u'', u'update': {u'deb_repo_url': u'http://stridercd.scalr-labs.com/scalarizr/apt-plain/develop master-fix-SCALARIZR-2054-install-hangs/', u'repository': u'master-fix-SCALARIZR-2054-install-hangs', u'api_port': u'8008', u'server_url': u'http://update.scalr.net/', u'msi_repo_url': u'http://stridercd.scalr-labs.com/scalarizr/msi/develop/master-fix-SCALARIZR-2054-install-hangs/', u'rpm_repo_url': u'http://stridercd.scalr-labs.com/scalarizr/rpm/develop/master-fix-SCALARIZR-2054-install-hangs/rhel/$releasever/$basearch', u'client_mode': u'client', u'win_repo_url': u'http://stridercd.scalr-labs.com/scalarizr/win/develop/master-fix-SCALARIZR-2054-install-hangs/'}, u'api_port': u'8010', u'messaging_port': u'8013', u'resume_strategy': u'reboot', u'union_script_executor': 1, u'keep_scripting_logs_time': u'86400'}}
2015-11-02 09:34:19,756+00:00 - DEBUG - scalarizr.app - Initialize messaging
2015-11-02 09:34:19,756+00:00 - DEBUG - scalarizr.app - Initialize message handlers
2015-11-02 09:34:19,756+00:00 - DEBUG - scalarizr.handlers - Initializing message listener
2015-11-02 09:34:19,756+00:00 - DEBUG - scalarizr.handlers - Keywords for each Handler::accept
{'behaviour': ['base', 'chef'],
'dist': ('', '', ''),
'os': ('Windows',
'e217a30a-9683-4423-9953-93363ec5fe08',
'2012Server',
'6.2.9200',
'AMD64',
'Intel64 Family 6 Model 45 Stepping 7, GenuineIntel'),
'platform': 'gce'}
2015-11-02 09:34:19,756+00:00 - DEBUG - scalarizr.handlers - Collecting message handlers...
2015-11-02 09:34:19,756+00:00 - DEBUG - scalarizr.messaging.p2p.store - Add rotate messages table task for periodical executor
2015-11-02 09:34:19,756+00:00 - DEBUG - scalarizr.handlers - Message handlers chain:
[<scalarizr.handlers.ip_list_builder.IpListBuilder object at 0x0000000002B16668>,
<scalarizr.handlers.block_device.BlockDeviceHandler object at 0x0000000002B16940>,
<scalarizr.handlers.deploy.DeploymentHandler object at 0x0000000002B16828>,
<scalarizr.handlers.chef.ChefHandler object at 0x0000000002B16978>,
<scalarizr.handlers.gce.lifecycle.GceLifeCycle object at 0x0000000002B16908>,
<scalarizr.handlers.gce.rebundle.GceRebundleWindowsHandler object at 0x0000000002B168D0>,
<scalarizr.handlers.hooks.HooksHandler object at 0x0000000002B167B8>,
<scalarizr.handlers.script_executor.ScriptExecutor object at 0x0000000002B166D8>,
<scalarizr.handlers.lifecycle.LifeCycleHandler object at 0x0000000002B16358>]
2015-11-02 09:34:19,818+00:00 - DEBUG - scalarizr.util - system: ('ntpdate', '-u', 'metadata.google.internal')
2015-11-02 09:34:19,818+00:00 - DEBUG - scalarizr.handlers.lifecycle - Adding iptables rules for scalarizr ports
2015-11-02 09:34:19,818+00:00 - DEBUG - scalarizr.handlers.hooks - Hook on 'init'() {}
2015-11-02 09:34:19,818+00:00 - INFO - scalarizr.app - Starting API server on http://0.0.0.0:8010
2015-11-02 09:34:19,818+00:00 - INFO - scalarizr.messaging.p2p.consumer - Building message consumer server on 0.0.0.0:8013
2015-11-02 09:34:19,818+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Starting message consumer http://0.0.0.0:8013
2015-11-02 09:34:19,818+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Starting message handler
2015-11-02 09:34:19,818+00:00 - DEBUG - scalarizr.util.PeriodicalExecutor - Executing task Rotate messages sqlite table
2015-11-02 09:34:19,818+00:00 - DEBUG - scalarizr.handlers.script_executor - Starting log_rotate routine
2015-11-02 09:34:19,849+00:00 - DEBUG - scalarizr.handlers.script_executor - STATE[script_executor.in_progress]: None
2015-11-02 09:34:19,881+00:00 - DEBUG - scalarizr.handlers.script_executor - Restoring 0 in-progress scripts
2015-11-02 09:34:19,881+00:00 - INFO - scalarizr.handlers.lifecycle - Starting initialization
2015-11-02 09:34:19,943+00:00 - DEBUG - scalarizr.handlers.hooks - Hook on 'before_host_init'(<scalarizr.messaging.p2p.store.P2pMessage object at 0x0000000002B296A0>,) {}
2015-11-02 09:34:19,943+00:00 - DEBUG - scalarizr.messaging.p2p.producer - Sending message 'HostInit' into queue 'control'
2015-11-02 09:34:19,990+00:00 - DEBUG - scalarizr.node - Reloading C:\opt\scalarizr\etc/private.d\config.ini
2015-11-02 09:34:19,990+00:00 - DEBUG - scalarizr.messaging.p2p.producer - Delivering message 'HostInit' {'local_ip': u'10.240.0.19', 'seconds_since_boot': 128.01, 'seconds_since_start': 12.89, 'behaviour': ['base', 'chef'], 'crypto_key': 'n296whnnOS9Qm8dW55BEem496XlrXZSbGfCt45/H7RZf9NmNBuaMqA==\n', 'role_name': 'base-windows2012-devel', 'remote_ip': u'23.236.49.82'}. Json: True, Headers: {'Content-Type': 'application/json'}
2015-11-02 09:34:19,990+00:00 - DEBUG - scalarizr.messaging.p2p.security - Encrypting message
2015-11-02 09:34:20,147+00:00 - DEBUG - scalarizr.messaging.p2p.producer - Message 'HostInit' delivered (message_id: 03b43328-bad9-4647-8b20-a7785efb543e)
2015-11-02 09:34:20,177+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Waiting message acknowledge event: HostInit
2015-11-02 09:34:24,115+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Received ingoing message in queue: 'control'
2015-11-02 09:34:24,115+00:00 - DEBUG - scalarizr.messaging.p2p.security - Decrypting message
2015-11-02 09:34:24,115+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Decoding message: {
"body": {
"server_id": "e217a30a-9683-4423-9953-93363ec5fe08",
"server_index": "1",
"farm_role_id": "",
"handlers": [
"base"
],
"event_id": "44048d9c-4ed8-42f0-9c16-1d9a5884fb28",
"local_ip": "",
"cloud_location": "us-central1-a",
"farm_crypto_key": "2b2LK5ONLgUkp8f8/8tdv/yUd/yTSZ6pW0oybe7b",
"behaviour": "",
"role_name": "",
"base": {
"reboot_after_hostinit_phase": 0,
"disable_firewall_management": 0,
"abort_init_on_script_fail": 1,
"hostname": "",
"update": {
"deb_repo_url": "http://stridercd.scalr-labs.com/scalarizr/apt-plain/develop master-fix-SCALARIZR-2054-install-hangs/",
"repository": "master-fix-SCALARIZR-2054-install-hangs",
"api_port": "8008",
"server_url": "http://update.scalr.net/",
"msi_repo_url": "http://stridercd.scalr-labs.com/scalarizr/msi/develop/master-fix-SCALARIZR-2054-install-hangs/",
"rpm_repo_url": "http://stridercd.scalr-labs.com/scalarizr/rpm/develop/master-fix-SCALARIZR-2054-install-hangs/rhel/$releasever/$basearch",
"client_mode": "client",
"win_repo_url": "http://stridercd.scalr-labs.com/scalarizr/win/develop/master-fix-SCALARIZR-2054-install-hangs/"
},
"api_port": "8010",
"messaging_port": "8013",
"resume_strategy": "reboot",
"union_script_executor": 1,
"keep_scripting_logs_time": "86400"
},
"global_variables": [
{
"value": "scalr.com:scalr-labs/global/images/windows2012-devel-21102015",
"name": "SCALR_IMAGE_ID",
"system": 1
},
{
"value": "23.236.49.82",
"name": "SCALR_EXTERNAL_IP",
"system": 1
},
{
"value": "10.240.0.19",
"name": "SCALR_INTERNAL_IP",
"system": 1
},
{
"value": "base-windows2012-devel",
"name": "SCALR_ROLE_NAME",
"system": 1
},
{
"value": "",
"name": "SCALR_ISDBMASTER",
"system": 1
},
{
"value": "1",
"name": "SCALR_INSTANCE_INDEX",
"system": 1
},
{
"value": "n1-standard-1",
"name": "SCALR_SERVER_TYPE",
"system": 1
},
{
"value": "",
"name": "SCALR_SERVER_HOSTNAME",
"system": 1
},
{
"value": "e217a30a-9683-4423-9953-93363ec5fe08",
"name": "SCALR_SERVER_ID",
"system": 1
},
{
"value": "21564",
"name": "SCALR_FARM_ID",
"system": 1
},
{
"value": "105108",
"name": "SCALR_FARM_ROLE_ID",
"system": 1
},
{
"value": "base-windows2012-devel",
"name": "SCALR_FARM_ROLE_ALIAS",
"system": 1
},
{
"value": "Nick-K-2",
"name": "SCALR_FARM_NAME",
"system": 1
},
{
"value": "5a3620ee3efcc8",
"name": "SCALR_FARM_HASH",
"system": 1
},
{
"value": "n.kalmikov@scalr.com",
"name": "SCALR_FARM_OWNER_EMAIL",
"system": 1
},
{
"value": "Development",
"name": "SCALR_FARM_TEAM",
"system": 1
},
{
"value": "base,chef",
"name": "SCALR_BEHAVIORS",
"system": 1
},
{
"value": "3414",
"name": "SCALR_ENV_ID",
"system": 1
},
{
"value": "Development",
"name": "SCALR_ENV_NAME",
"system": 1
},
{
"value": "us-central1-a",
"name": "SCALR_CLOUD_LOCATION",
"system": 1
},
{
"value": "e217a30a-9683-4423-9953-93363ec5fe08",
"name": "SCALR_CLOUD_SERVER_ID",
"system": 1
},
{
"value": "us-central1-a",
"name": "SCALR_CLOUD_LOCATION_ZONE",
"system": 1
},
{
"value": "6aafa5bb-00e7-4aa0-b770-1b451886559b",
"name": "SCALR_COST_CENTER_ID",
"system": 1
},
{
"value": "CC-Scalr",
"name": "SCALR_COST_CENTER_BC",
"system": 1
},
{
"value": "Cost Center Scalr (263)",
"name": "SCALR_COST_CENTER_NAME",
"system": 1
},
{
"value": "a05c75a2-151d-4b84-8dc3-842306ddd2e1",
"name": "SCALR_PROJECT_ID",
"system": 1
},
{
"value": "PR-Scalr",
"name": "SCALR_PROJECT_BC",
"system": 1
},
{
"value": "Project Scalr (263)",
"name": "SCALR_PROJECT_NAME",
"system": 1
},
{
"value": "ohkyghuih",
"name": "account"
},
{
"value": "egeg",
"name": "ergeg"
},
{
"value": "egerg",
"name": "ffev"
},
{
"value": "er",
"name": "fgerg"
},
{
"value": "eg",
"name": "gge"
},
{
"value": "REVIZOR_CHEF_VARIABLE_VALUE_WORK",
"name": "revizor_chef_variable"
},
{
"value": "^#(_hostname_failed_)&^",
"name": "REVIZOR_FAILED_HOSTNAME"
},
{
"value": "test value 123",
"name": "test"
},
{
"value": "defwer",
"name": "wrw"
}
],
"message_id": "55b641ee-ba28-49c3-9871-2dcecdd4e559",
"remote_ip": ""
},
"meta": {
"server_id": "e217a30a-9683-4423-9953-93363ec5fe08",
"scalr_version": "5.10.7"
},
"name": "HostInitResponse",
"id": "55b641ee-ba28-49c3-9871-2dcecdd4e559"
}
2015-11-02 09:34:24,115+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Received message 'HostInitResponse' (message_id: 55b641ee-ba28-49c3-9871-2dcecdd4e559, format: json)
2015-11-02 09:34:24,147+00:00 - DEBUG - scalarizr.messaging.p2p.store - Commiting put_ingoing
2015-11-02 09:34:24,147+00:00 - DEBUG - scalarizr.messaging.p2p.store - Commited put_ingoing
2015-11-02 09:34:24,147+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - "POST /control HTTP/1.0" 201 -
2015-11-02 09:34:27,818+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Received ingoing message in queue: 'control'
2015-11-02 09:34:27,818+00:00 - DEBUG - scalarizr.messaging.p2p.security - Decrypting message
2015-11-02 09:34:27,834+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Decoding message: {
"body": {
"server_id": "e217a30a-9683-4423-9953-93363ec5fe08",
"server_index": "1",
"farm_role_id": "105108",
"handlers": [
"base"
],
"event_id": "44048d9c-4ed8-42f0-9c16-1d9a5884fb28",
"farm_role_alias": "base-windows2012-devel",
"local_ip": "10.240.0.19",
"cloud_location": "us-central1-a",
"ssh_pub_key": "",
"behaviour": [
"base",
"chef"
],
"crypto_key": "",
"debug_scripting_count": 0,
"role_name": "base-windows2012-devel",
"debug_scripting_error": "",
"base": {
"reboot_after_hostinit_phase": 0,
"disable_firewall_management": 0,
"abort_init_on_script_fail": 1,
"hostname": "",
"update": {
"deb_repo_url": "http://stridercd.scalr-labs.com/scalarizr/apt-plain/develop master-fix-SCALARIZR-2054-install-hangs/",
"repository": "master-fix-SCALARIZR-2054-install-hangs",
"api_port": "8008",
"server_url": "http://update.scalr.net/",
"msi_repo_url": "http://stridercd.scalr-labs.com/scalarizr/msi/develop/master-fix-SCALARIZR-2054-install-hangs/",
"rpm_repo_url": "http://stridercd.scalr-labs.com/scalarizr/rpm/develop/master-fix-SCALARIZR-2054-install-hangs/rhel/$releasever/$basearch",
"client_mode": "client",
"win_repo_url": "http://stridercd.scalr-labs.com/scalarizr/win/develop/master-fix-SCALARIZR-2054-install-hangs/"
},
"api_port": "8010",
"messaging_port": "8013",
"resume_strategy": "reboot",
"union_script_executor": 1,
"keep_scripting_logs_time": "86400"
},
"scripts": [],
"global_variables": [
{
"value": "scalr.com:scalr-labs/global/images/windows2012-devel-21102015",
"name": "SCALR_IMAGE_ID",
"system": 1
},
{
"value": "23.236.49.82",
"name": "SCALR_EXTERNAL_IP",
"system": 1
},
{
"value": "10.240.0.19",
"name": "SCALR_INTERNAL_IP",
"system": 1
},
{
"value": "base-windows2012-devel",
"name": "SCALR_ROLE_NAME",
"system": 1
},
{
"value": "",
"name": "SCALR_ISDBMASTER",
"system": 1
},
{
"value": "1",
"name": "SCALR_INSTANCE_INDEX",
"system": 1
},
{
"value": "n1-standard-1",
"name": "SCALR_SERVER_TYPE",
"system": 1
},
{
"value": "",
"name": "SCALR_SERVER_HOSTNAME",
"system": 1
},
{
"value": "e217a30a-9683-4423-9953-93363ec5fe08",
"name": "SCALR_SERVER_ID",
"system": 1
},
{
"value": "21564",
"name": "SCALR_FARM_ID",
"system": 1
},
{
"value": "105108",
"name": "SCALR_FARM_ROLE_ID",
"system": 1
},
{
"value": "base-windows2012-devel",
"name": "SCALR_FARM_ROLE_ALIAS",
"system": 1
},
{
"value": "Nick-K-2",
"name": "SCALR_FARM_NAME",
"system": 1
},
{
"value": "5a3620ee3efcc8",
"name": "SCALR_FARM_HASH",
"system": 1
},
{
"value": "n.kalmikov@scalr.com",
"name": "SCALR_FARM_OWNER_EMAIL",
"system": 1
},
{
"value": "Development",
"name": "SCALR_FARM_TEAM",
"system": 1
},
{
"value": "base,chef",
"name": "SCALR_BEHAVIORS",
"system": 1
},
{
"value": "3414",
"name": "SCALR_ENV_ID",
"system": 1
},
{
"value": "Development",
"name": "SCALR_ENV_NAME",
"system": 1
},
{
"value": "us-central1-a",
"name": "SCALR_CLOUD_LOCATION",
"system": 1
},
{
"value": "e217a30a-9683-4423-9953-93363ec5fe08",
"name": "SCALR_CLOUD_SERVER_ID",
"system": 1
},
{
"value": "us-central1-a",
"name": "SCALR_CLOUD_LOCATION_ZONE",
"system": 1
},
{
"value": "6aafa5bb-00e7-4aa0-b770-1b451886559b",
"name": "SCALR_COST_CENTER_ID",
"system": 1
},
{
"value": "CC-Scalr",
"name": "SCALR_COST_CENTER_BC",
"system": 1
},
{
"value": "Cost Center Scalr (263)",
"name": "SCALR_COST_CENTER_NAME",
"system": 1
},
{
"value": "a05c75a2-151d-4b84-8dc3-842306ddd2e1",
"name": "SCALR_PROJECT_ID",
"system": 1
},
{
"value": "PR-Scalr",
"name": "SCALR_PROJECT_BC",
"system": 1
},
{
"value": "Project Scalr (263)",
"name": "SCALR_PROJECT_NAME",
"system": 1
},
{
"value": "ohkyghuih",
"name": "account"
},
{
"value": "egeg",
"name": "ergeg"
},
{
"value": "egerg",
"name": "ffev"
},
{
"value": "er",
"name": "fgerg"
},
{
"value": "eg",
"name": "gge"
},
{
"value": "REVIZOR_CHEF_VARIABLE_VALUE_WORK",
"name": "revizor_chef_variable"
},
{
"value": "^#(_hostname_failed_)&^",
"name": "REVIZOR_FAILED_HOSTNAME"
},
{
"value": "test value 123",
"name": "test"
},
{
"value": "defwer",
"name": "wrw"
}
],
"message_id": "ec947217-4856-4950-b812-419527e46582",
"remote_ip": "23.236.49.82"
},
"meta": {
"server_id": "e217a30a-9683-4423-9953-93363ec5fe08",
"scalr_version": "5.10.7"
},
"name": "HostInit",
"id": "ec947217-4856-4950-b812-419527e46582"
}
2015-11-02 09:34:27,834+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Received message 'HostInit' (message_id: ec947217-4856-4950-b812-419527e46582, format: json)
2015-11-02 09:34:27,865+00:00 - DEBUG - scalarizr.messaging.p2p.store - Commiting put_ingoing
2015-11-02 09:34:27,865+00:00 - DEBUG - scalarizr.messaging.p2p.store - Commited put_ingoing
2015-11-02 09:34:27,865+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - "POST /control HTTP/1.1" 201 -
2015-11-02 09:34:27,911+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Going to handle_one_message. Thread: MessageHandler
2015-11-02 09:34:27,911+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Notify message listeners (message_id: ec947217-4856-4950-b812-419527e46582)
2015-11-02 09:34:27,911+00:00 - DEBUG - scalarizr.handlers - Handle 'HostInit'
2015-11-02 09:34:27,911+00:00 - DEBUG - scalarizr.handlers - Scalr version: (5, 10, 7)
2015-11-02 09:34:27,911+00:00 - DEBUG - scalarizr.handlers - Call handler ScriptExecutor
2015-11-02 09:34:27,911+00:00 - DEBUG - scalarizr.handlers.script_executor - Scalr notified me that 'HostInit' fired
2015-11-02 09:34:27,911+00:00 - DEBUG - scalarizr.handlers - Empty scripts list. Breaking
2015-11-02 09:34:27,911+00:00 - DEBUG - scalarizr.handlers - Call handler LifeCycleHandler
2015-11-02 09:34:27,911+00:00 - DEBUG - scalarizr.handlers.lifecycle - HI.body.base: {u'reboot_after_hostinit_phase': 0, u'disable_firewall_management': 0, u'abort_init_on_script_fail': 1, u'hostname': u'', u'update': {u'deb_repo_url': u'http://stridercd.scalr-labs.com/scalarizr/apt-plain/develop master-fix-SCALARIZR-2054-install-hangs/', u'repository': u'master-fix-SCALARIZR-2054-install-hangs', u'api_port': u'8008', u'server_url': u'http://update.scalr.net/', u'msi_repo_url': u'http://stridercd.scalr-labs.com/scalarizr/msi/develop/master-fix-SCALARIZR-2054-install-hangs/', u'rpm_repo_url': u'http://stridercd.scalr-labs.com/scalarizr/rpm/develop/master-fix-SCALARIZR-2054-install-hangs/rhel/$releasever/$basearch', u'client_mode': u'client', u'win_repo_url': u'http://stridercd.scalr-labs.com/scalarizr/win/develop/master-fix-SCALARIZR-2054-install-hangs/'}, u'api_port': u'8010', u'messaging_port': u'8013', u'resume_strategy': u'reboot', u'union_script_executor': 1, u'keep_scripting_logs_time': u'86400'}
2015-11-02 09:34:27,911+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Mark message (message_id: ec947217-4856-4950-b812-419527e46582) as handled
2015-11-02 09:34:27,974+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Completed handle_one_message. Thread: MessageHandler
2015-11-02 09:34:27,974+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Found a message and continue message handler
2015-11-02 09:34:27,974+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Fired message acknowledge event: HostInit
2015-11-02 09:34:27,974+00:00 - INFO - scalarizr.config - State: initializing
2015-11-02 09:34:27,974+00:00 - DEBUG - scalarizr.handlers.hooks - Hook on 'host_init'() {}
2015-11-02 09:34:27,974+00:00 - DEBUG - scalarizr.handlers.hooks - Hook on 'start'() {}
2015-11-02 09:34:28,084+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Notify message listeners (message_id: 55b641ee-ba28-49c3-9871-2dcecdd4e559)
2015-11-02 09:34:28,084+00:00 - DEBUG - scalarizr.handlers - Handle 'HostInitResponse'
2015-11-02 09:34:28,084+00:00 - DEBUG - scalarizr.handlers - Scalr version: (5, 10, 7)
2015-11-02 09:34:28,084+00:00 - DEBUG - scalarizr.handlers - Call handler LifeCycleHandler
2015-11-02 09:34:28,084+00:00 - DEBUG - scalarizr.handlers.lifecycle - bus.init_op: <scalarizr.api.operation.Operation object at 0x0000000002A0D3C8>
2015-11-02 09:34:28,084+00:00 - DEBUG - scalarizr.util.flag - Touch file 'C:\opt\scalarizr\etc\private.d\.hir'
2015-11-02 09:34:28,084+00:00 - DEBUG - scalarizr.handlers.lifecycle - HIR.body.base: {u'reboot_after_hostinit_phase': 0, u'disable_firewall_management': 0, u'abort_init_on_script_fail': 1, u'hostname': u'', u'update': {u'deb_repo_url': u'http://stridercd.scalr-labs.com/scalarizr/apt-plain/develop master-fix-SCALARIZR-2054-install-hangs/', u'repository': u'master-fix-SCALARIZR-2054-install-hangs', u'api_port': u'8008', u'server_url': u'http://update.scalr.net/', u'msi_repo_url': u'http://stridercd.scalr-labs.com/scalarizr/msi/develop/master-fix-SCALARIZR-2054-install-hangs/', u'rpm_repo_url': u'http://stridercd.scalr-labs.com/scalarizr/rpm/develop/master-fix-SCALARIZR-2054-install-hangs/rhel/$releasever/$basearch', u'client_mode': u'client', u'win_repo_url': u'http://stridercd.scalr-labs.com/scalarizr/win/develop/master-fix-SCALARIZR-2054-install-hangs/'}, u'api_port': u'8010', u'messaging_port': u'8013', u'resume_strategy': u'reboot', u'union_script_executor': 1, u'keep_scripting_logs_time': u'86400'}
2015-11-02 09:34:28,084+00:00 - INFO - scalarizr.ops.system.init - Configuring storage volumes
2015-11-02 09:34:28,084+00:00 - DEBUG - scalarizr.queryenv - Call QueryEnv: https://my.scalr.com/query-env/2015-04-10/list-ebs-mountpoints
2015-11-02 09:34:28,084+00:00 - DEBUG - scalarizr.queryenv - QueryEnv request: {'operation': 'list-ebs-mountpoints', 'version': u'2015-04-10'}
2015-11-02 09:34:28,240+00:00 - DEBUG - scalarizr.queryenv - QueryEnv response (parsed): []
2015-11-02 09:34:28,240+00:00 - DEBUG - scalarizr.handlers.hooks - Hook on 'host_init_response'(<scalarizr.messaging.p2p.store.P2pMessage object at 0x0000000002B16E80>,) {}
2015-11-02 09:34:28,240+00:00 - DEBUG - scalarizr.node - Reloading C:\opt\scalarizr\etc/public.d/config.ini
2015-11-02 09:34:28,240+00:00 - DEBUG - scalarizr.node - Reloading C:\opt\scalarizr\etc/private.d/config.ini
2015-11-02 09:34:28,240+00:00 - DEBUG - scalarizr.handlers.hooks - Hook on 'before_host_up'(<scalarizr.messaging.p2p.store.P2pMessage object at 0x0000000002A0D0F0>,) {}
2015-11-02 09:34:28,240+00:00 - DEBUG - scalarizr.handlers.ip_list_builder - Rebuild farm hosts structure
2015-11-02 09:34:28,240+00:00 - DEBUG - scalarizr.queryenv - Call QueryEnv: https://my.scalr.com/query-env/2015-04-10/list-roles
2015-11-02 09:34:28,240+00:00 - DEBUG - scalarizr.queryenv - QueryEnv request: {'operation': 'list-roles', 'version': u'2015-04-10'}
2015-11-02 09:34:28,411+00:00 - DEBUG - scalarizr.queryenv - QueryEnv response (parsed): [behaviour = ['base', 'chef']; name = base-windows2012-devel; hosts = []; farm_role_id = 105108;]
2015-11-02 09:34:28,427+00:00 - DEBUG - scalarizr.messaging.p2p.producer - Sending message 'BeforeHostUp' into queue 'control'
2015-11-02 09:34:28,459+00:00 - DEBUG - scalarizr.messaging.p2p.producer - Delivering message 'BeforeHostUp' {'behaviour': ['base', 'chef'], 'role_name': 'base-windows2012-devel', 'local_ip': u'10.240.0.19', 'remote_ip': u'23.236.49.82'}. Json: True, Headers: {'Content-Type': 'application/json'}
2015-11-02 09:34:28,459+00:00 - DEBUG - scalarizr.messaging.p2p.security - Encrypting message
2015-11-02 09:34:28,802+00:00 - DEBUG - scalarizr.messaging.p2p.producer - Message 'BeforeHostUp' delivered (message_id: a66df7bd-304f-474c-ae71-63a50436408d)
2015-11-02 09:34:28,834+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Starting message subhandler thread: BeforeHostUpHandler
2015-11-02 09:34:28,834+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Waiting message subhandler thread: BeforeHostUpHandler
2015-11-02 09:34:28,834+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Starting message handler
2015-11-02 09:34:33,145+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Received ingoing message in queue: 'control'
2015-11-02 09:34:33,145+00:00 - DEBUG - scalarizr.messaging.p2p.security - Decrypting message
2015-11-02 09:34:33,161+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Decoding message: {
"body": {
"server_id": "e217a30a-9683-4423-9953-93363ec5fe08",
"server_index": "1",
"farm_role_id": "105108",
"local_ip": "10.240.0.19",
"event_id": "735a6175-7a3c-4a20-a857-001b43d8b55e",
"farm_role_alias": "base-windows2012-devel",
"handlers": [],
"cloud_location": "us-central1-a",
"behaviour": [
"base",
"chef"
],
"debug_scripting_count": 0,
"role_name": "base-windows2012-devel",
"debug_scripting_error": "",
"scripts": [],
"global_variables": [
{
"value": "scalr.com:scalr-labs/global/images/windows2012-devel-21102015",
"name": "SCALR_IMAGE_ID",
"system": 1
},
{
"value": "23.236.49.82",
"name": "SCALR_EXTERNAL_IP",
"system": 1
},
{
"value": "10.240.0.19",
"name": "SCALR_INTERNAL_IP",
"system": 1
},
{
"value": "base-windows2012-devel",
"name": "SCALR_ROLE_NAME",
"system": 1
},
{
"value": "",
"name": "SCALR_ISDBMASTER",
"system": 1
},
{
"value": "1",
"name": "SCALR_INSTANCE_INDEX",
"system": 1
},
{
"value": "n1-standard-1",
"name": "SCALR_SERVER_TYPE",
"system": 1
},
{
"value": "",
"name": "SCALR_SERVER_HOSTNAME",
"system": 1
},
{
"value": "e217a30a-9683-4423-9953-93363ec5fe08",
"name": "SCALR_SERVER_ID",
"system": 1
},
{
"value": "21564",
"name": "SCALR_FARM_ID",
"system": 1
},
{
"value": "105108",
"name": "SCALR_FARM_ROLE_ID",
"system": 1
},
{
"value": "base-windows2012-devel",
"name": "SCALR_FARM_ROLE_ALIAS",
"system": 1
},
{
"value": "Nick-K-2",
"name": "SCALR_FARM_NAME",
"system": 1
},
{
"value": "5a3620ee3efcc8",
"name": "SCALR_FARM_HASH",
"system": 1
},
{
"value": "n.kalmikov@scalr.com",
"name": "SCALR_FARM_OWNER_EMAIL",
"system": 1
},
{
"value": "Development",
"name": "SCALR_FARM_TEAM",
"system": 1
},
{
"value": "base,chef",
"name": "SCALR_BEHAVIORS",
"system": 1
},
{
"value": "3414",
"name": "SCALR_ENV_ID",
"system": 1
},
{
"value": "Development",
"name": "SCALR_ENV_NAME",
"system": 1
},
{
"value": "us-central1-a",
"name": "SCALR_CLOUD_LOCATION",
"system": 1
},
{
"value": "e217a30a-9683-4423-9953-93363ec5fe08",
"name": "SCALR_CLOUD_SERVER_ID",
"system": 1
},
{
"value": "us-central1-a",
"name": "SCALR_CLOUD_LOCATION_ZONE",
"system": 1
},
{
"value": "6aafa5bb-00e7-4aa0-b770-1b451886559b",
"name": "SCALR_COST_CENTER_ID",
"system": 1
},
{
"value": "CC-Scalr",
"name": "SCALR_COST_CENTER_BC",
"system": 1
},
{
"value": "Cost Center Scalr (263)",
"name": "SCALR_COST_CENTER_NAME",
"system": 1
},
{
"value": "a05c75a2-151d-4b84-8dc3-842306ddd2e1",
"name": "SCALR_PROJECT_ID",
"system": 1
},
{
"value": "PR-Scalr",
"name": "SCALR_PROJECT_BC",
"system": 1
},
{
"value": "Project Scalr (263)",
"name": "SCALR_PROJECT_NAME",
"system": 1
},
{
"value": "ohkyghuih",
"name": "account"
},
{
"value": "egeg",
"name": "ergeg"
},
{
"value": "egerg",
"name": "ffev"
},
{
"value": "er",
"name": "fgerg"
},
{
"value": "eg",
"name": "gge"
},
{
"value": "REVIZOR_CHEF_VARIABLE_VALUE_WORK",
"name": "revizor_chef_variable"
},
{
"value": "^#(_hostname_failed_)&^",
"name": "REVIZOR_FAILED_HOSTNAME"
},
{
"value": "test value 123",
"name": "test"
},
{
"value": "defwer",
"name": "wrw"
}
],
"message_id": "d54027fa-c843-4024-9071-c2ba342d8f36",
"remote_ip": "23.236.49.82"
},
"meta": {
"server_id": "e217a30a-9683-4423-9953-93363ec5fe08",
"scalr_version": "5.10.7"
},
"name": "BeforeHostUp",
"id": "d54027fa-c843-4024-9071-c2ba342d8f36"
}
2015-11-02 09:34:33,161+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Received message 'BeforeHostUp' (message_id: d54027fa-c843-4024-9071-c2ba342d8f36, format: json)
2015-11-02 09:34:33,193+00:00 - DEBUG - scalarizr.messaging.p2p.store - Commiting put_ingoing
2015-11-02 09:34:33,193+00:00 - DEBUG - scalarizr.messaging.p2p.store - Commited put_ingoing
2015-11-02 09:34:33,193+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - "POST /control HTTP/1.1" 201 -
2015-11-02 09:34:33,209+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Going to handle_one_message. Thread: BeforeHostUpHandler
2015-11-02 09:34:33,209+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Notify message listeners (message_id: d54027fa-c843-4024-9071-c2ba342d8f36)
2015-11-02 09:34:33,209+00:00 - DEBUG - scalarizr.handlers - Handle 'BeforeHostUp'
2015-11-02 09:34:33,209+00:00 - DEBUG - scalarizr.handlers - Scalr version: (5, 10, 7)
2015-11-02 09:34:33,209+00:00 - DEBUG - scalarizr.handlers - Call handler ScriptExecutor
2015-11-02 09:34:33,209+00:00 - DEBUG - scalarizr.handlers.script_executor - Scalr notified me that 'BeforeHostUp' fired
2015-11-02 09:34:33,209+00:00 - DEBUG - scalarizr.handlers - Empty scripts list. Breaking
2015-11-02 09:34:33,209+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Mark message (message_id: d54027fa-c843-4024-9071-c2ba342d8f36) as handled
2015-11-02 09:34:33,224+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Completed handle_one_message. Thread: BeforeHostUpHandler
2015-11-02 09:34:33,224+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - BeforeHostUp case. Interrupting message handler
2015-11-02 09:34:33,224+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Completed message subhandler thread: BeforeHostUpHandler
2015-11-02 09:34:33,224+00:00 - DEBUG - scalarizr.messaging.p2p.producer - Sending message 'HostUp' into queue 'control'
2015-11-02 09:34:33,256+00:00 - DEBUG - scalarizr.messaging.p2p.producer - Delivering message 'HostUp' {'behaviour': ['base', 'chef'], 'role_name': 'base-windows2012-devel', 'local_ip': u'10.240.0.19', 'base': {'hostname': '', 'keep_scripting_logs_time': 86400}, 'remote_ip': u'23.236.49.82'}. Json: True, Headers: {'Content-Type': 'application/json'}
2015-11-02 09:34:33,256+00:00 - DEBUG - scalarizr.messaging.p2p.security - Encrypting message
2015-11-02 09:34:33,397+00:00 - DEBUG - scalarizr.messaging.p2p.producer - Message 'HostUp' delivered (message_id: f16f5b15-db6d-4cc7-88e1-803de0f377c0)
2015-11-02 09:34:33,427+00:00 - INFO - scalarizr.config - State: running
2015-11-02 09:34:33,427+00:00 - DEBUG - scalarizr.handlers.hooks - Hook on 'host_up'() {}
2015-11-02 09:34:33,427+00:00 - DEBUG - scalarizr.messaging.p2p.producer - Sending message 'OperationResult' into queue 'control'
2015-11-02 09:34:33,459+00:00 - DEBUG - scalarizr.messaging.p2p.security - Encrypting message
2015-11-02 09:34:33,647+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Mark message (message_id: 55b641ee-ba28-49c3-9871-2dcecdd4e559) as handled
2015-11-02 09:34:38,302+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Received ingoing message in queue: 'control'
2015-11-02 09:34:38,302+00:00 - DEBUG - scalarizr.messaging.p2p.security - Decrypting message
2015-11-02 09:34:38,302+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Decoding message: {
"body": {
"server_id": "e217a30a-9683-4423-9953-93363ec5fe08",
"server_index": "1",
"farm_role_id": "105108",
"local_ip": "10.240.0.19",
"event_id": "d1461bda-01a2-4139-8007-1262c386b8d4",
"farm_role_alias": "base-windows2012-devel",
"handlers": [
"base"
],
"cloud_location": "us-central1-a",
"behaviour": [
"base",
"chef"
],
"debug_scripting_count": 0,
"role_name": "base-windows2012-devel",
"debug_scripting_error": "",
"scripts": [],
"global_variables": [
{
"value": "scalr.com:scalr-labs/global/images/windows2012-devel-21102015",
"name": "SCALR_IMAGE_ID",
"system": 1
},
{
"value": "23.236.49.82",
"name": "SCALR_EXTERNAL_IP",
"system": 1
},
{
"value": "10.240.0.19",
"name": "SCALR_INTERNAL_IP",
"system": 1
},
{
"value": "base-windows2012-devel",
"name": "SCALR_ROLE_NAME",
"system": 1
},
{
"value": "",
"name": "SCALR_ISDBMASTER",
"system": 1
},
{
"value": "1",
"name": "SCALR_INSTANCE_INDEX",
"system": 1
},
{
"value": "n1-standard-1",
"name": "SCALR_SERVER_TYPE",
"system": 1
},
{
"value": "",
"name": "SCALR_SERVER_HOSTNAME",
"system": 1
},
{
"value": "e217a30a-9683-4423-9953-93363ec5fe08",
"name": "SCALR_SERVER_ID",
"system": 1
},
{
"value": "21564",
"name": "SCALR_FARM_ID",
"system": 1
},
{
"value": "105108",
"name": "SCALR_FARM_ROLE_ID",
"system": 1
},
{
"value": "base-windows2012-devel",
"name": "SCALR_FARM_ROLE_ALIAS",
"system": 1
},
{
"value": "Nick-K-2",
"name": "SCALR_FARM_NAME",
"system": 1
},
{
"value": "5a3620ee3efcc8",
"name": "SCALR_FARM_HASH",
"system": 1
},
{
"value": "n.kalmikov@scalr.com",
"name": "SCALR_FARM_OWNER_EMAIL",
"system": 1
},
{
"value": "Development",
"name": "SCALR_FARM_TEAM",
"system": 1
},
{
"value": "base,chef",
"name": "SCALR_BEHAVIORS",
"system": 1
},
{
"value": "3414",
"name": "SCALR_ENV_ID",
"system": 1
},
{
"value": "Development",
"name": "SCALR_ENV_NAME",
"system": 1
},
{
"value": "us-central1-a",
"name": "SCALR_CLOUD_LOCATION",
"system": 1
},
{
"value": "e217a30a-9683-4423-9953-93363ec5fe08",
"name": "SCALR_CLOUD_SERVER_ID",
"system": 1
},
{
"value": "us-central1-a",
"name": "SCALR_CLOUD_LOCATION_ZONE",
"system": 1
},
{
"value": "6aafa5bb-00e7-4aa0-b770-1b451886559b",
"name": "SCALR_COST_CENTER_ID",
"system": 1
},
{
"value": "CC-Scalr",
"name": "SCALR_COST_CENTER_BC",
"system": 1
},
{
"value": "Cost Center Scalr (263)",
"name": "SCALR_COST_CENTER_NAME",
"system": 1
},
{
"value": "a05c75a2-151d-4b84-8dc3-842306ddd2e1",
"name": "SCALR_PROJECT_ID",
"system": 1
},
{
"value": "PR-Scalr",
"name": "SCALR_PROJECT_BC",
"system": 1
},
{
"value": "Project Scalr (263)",
"name": "SCALR_PROJECT_NAME",
"system": 1
},
{
"value": "ohkyghuih",
"name": "account"
},
{
"value": "egeg",
"name": "ergeg"
},
{
"value": "egerg",
"name": "ffev"
},
{
"value": "er",
"name": "fgerg"
},
{
"value": "eg",
"name": "gge"
},
{
"value": "REVIZOR_CHEF_VARIABLE_VALUE_WORK",
"name": "revizor_chef_variable"
},
{
"value": "^#(_hostname_failed_)&^",
"name": "REVIZOR_FAILED_HOSTNAME"
},
{
"value": "test value 123",
"name": "test"
},
{
"value": "defwer",
"name": "wrw"
}
],
"message_id": "0abfd78c-d415-4af1-868b-7434968593a4",
"remote_ip": "23.236.49.82"
},
"meta": {
"server_id": "e217a30a-9683-4423-9953-93363ec5fe08",
"scalr_version": "5.10.7"
},
"name": "HostUp",
"id": "0abfd78c-d415-4af1-868b-7434968593a4"
}
2015-11-02 09:34:38,302+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Received message 'HostUp' (message_id: 0abfd78c-d415-4af1-868b-7434968593a4, format: json)
2015-11-02 09:34:38,334+00:00 - DEBUG - scalarizr.messaging.p2p.store - Commiting put_ingoing
2015-11-02 09:34:38,334+00:00 - DEBUG - scalarizr.messaging.p2p.store - Commited put_ingoing
2015-11-02 09:34:38,334+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - "POST /control HTTP/1.1" 201 -
2015-11-02 09:34:38,411+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Notify message listeners (message_id: 0abfd78c-d415-4af1-868b-7434968593a4)
2015-11-02 09:34:38,411+00:00 - DEBUG - scalarizr.handlers - Handle 'HostUp'
2015-11-02 09:34:38,411+00:00 - DEBUG - scalarizr.handlers - Scalr version: (5, 10, 7)
2015-11-02 09:34:38,411+00:00 - DEBUG - scalarizr.handlers - Call handler IpListBuilder
2015-11-02 09:34:38,411+00:00 - DEBUG - scalarizr.handlers.ip_list_builder - Add host (role_name: base-windows2012-devel, behaviour: [u'base', u'chef'], ip: 10.240.0.19)
2015-11-02 09:34:38,411+00:00 - DEBUG - scalarizr.handlers.ip_list_builder - Create dir C:\opt\scalarizr\etc\private.d\hosts\base-windows2012-devel
2015-11-02 09:34:38,411+00:00 - DEBUG - scalarizr.handlers.ip_list_builder - Touch file C:\opt\scalarizr\etc\private.d\hosts\base-windows2012-devel\10.240.0.19
2015-11-02 09:34:38,427+00:00 - DEBUG - scalarizr.handlers.ip_list_builder - Create dir C:\opt\scalarizr\etc\private.d\hosts\base
2015-11-02 09:34:38,427+00:00 - DEBUG - scalarizr.handlers.ip_list_builder - Touch file C:\opt\scalarizr\etc\private.d\hosts\base\10.240.0.19
2015-11-02 09:34:38,427+00:00 - DEBUG - scalarizr.handlers.ip_list_builder - Create dir C:\opt\scalarizr\etc\private.d\hosts\chef
2015-11-02 09:34:38,427+00:00 - DEBUG - scalarizr.handlers.ip_list_builder - Touch file C:\opt\scalarizr\etc\private.d\hosts\chef\10.240.0.19
2015-11-02 09:34:38,427+00:00 - DEBUG - scalarizr.handlers - Call handler ScriptExecutor
2015-11-02 09:34:38,427+00:00 - DEBUG - scalarizr.handlers.script_executor - Scalr notified me that 'HostUp' fired
2015-11-02 09:34:38,427+00:00 - DEBUG - scalarizr.handlers - Empty scripts list. Breaking
2015-11-02 09:34:38,427+00:00 - DEBUG - scalarizr.messaging.p2p.consumer - Mark message (message_id: 0abfd78c-d415-4af1-868b-7434968593a4) as handled
2015-11-02 09:35:31,145+00:00 - WARNING - scalarizr.rpc - Caught API exception. com_error: (-2147221020, 'Invalid syntax', None, None)
Traceback (most recent call last):
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\scalarizr-4.1.0.695-py2.7.egg\scalarizr\rpc.py", line 139, in handle_request
result = self._invoke_method(fn, params)
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\scalarizr-4.1.0.695-py2.7.egg\scalarizr\rpc.py", line 206, in _invoke_method
return method(**params)
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\scalarizr-4.1.0.695-py2.7.egg\scalarizr\util\__init__.py", line 902, in decorator
return fn(*args, **kwargs)
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\scalarizr-4.1.0.695-py2.7.egg\scalarizr\api\system.py", line 1010, in mem_info
wmi = client.GetObject('winmgmts:')
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\pywin32-219-py2.7-win-amd64.egg\win32com\client\__init__.py", line 72, in GetObject
return Moniker(Pathname, clsctx)
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\pywin32-219-py2.7-win-amd64.egg\win32com\client\__init__.py", line 87, in Moniker
moniker, i, bindCtx = pythoncom.MkParseDisplayName(Pathname)
com_error: (-2147221020, 'Invalid syntax', None, None)
2015-11-02 09:37:34,756+00:00 - WARNING - scalarizr.rpc - Caught API exception. com_error: (-2147221020, 'Invalid syntax', None, None)
Traceback (most recent call last):
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\scalarizr-4.1.0.695-py2.7.egg\scalarizr\rpc.py", line 139, in handle_request
result = self._invoke_method(fn, params)
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\scalarizr-4.1.0.695-py2.7.egg\scalarizr\rpc.py", line 206, in _invoke_method
return method(**params)
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\scalarizr-4.1.0.695-py2.7.egg\scalarizr\util\__init__.py", line 902, in decorator
return fn(*args, **kwargs)
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\scalarizr-4.1.0.695-py2.7.egg\scalarizr\api\system.py", line 1010, in mem_info
wmi = client.GetObject('winmgmts:')
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\pywin32-219-py2.7-win-amd64.egg\win32com\client\__init__.py", line 72, in GetObject
return Moniker(Pathname, clsctx)
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\pywin32-219-py2.7-win-amd64.egg\win32com\client\__init__.py", line 87, in Moniker
moniker, i, bindCtx = pythoncom.MkParseDisplayName(Pathname)
com_error: (-2147221020, 'Invalid syntax', None, None)
2015-11-02 09:39:30,334+00:00 - WARNING - scalarizr.rpc - Caught API exception. com_error: (-2147221020, 'Invalid syntax', None, None)
Traceback (most recent call last):
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\scalarizr-4.1.0.695-py2.7.egg\scalarizr\rpc.py", line 139, in handle_request
result = self._invoke_method(fn, params)
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\scalarizr-4.1.0.695-py2.7.egg\scalarizr\rpc.py", line 206, in _invoke_method
return method(**params)
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\scalarizr-4.1.0.695-py2.7.egg\scalarizr\util\__init__.py", line 902, in decorator
return fn(*args, **kwargs)
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\scalarizr-4.1.0.695-py2.7.egg\scalarizr\api\system.py", line 1010, in mem_info
wmi = client.GetObject('winmgmts:')
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\pywin32-219-py2.7-win-amd64.egg\win32com\client\__init__.py", line 72, in GetObject
return Moniker(Pathname, clsctx)
File "C:\opt\scalarizr\4.1.0.695\embedded\lib\site-packages\pywin32-219-py2.7-win-amd64.egg\win32com\client\__init__.py", line 87, in Moniker
moniker, i, bindCtx = pythoncom.MkParseDisplayName(Pathname)
com_error: (-2147221020, 'Invalid syntax', None, None)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment