stratos-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Udara Liyanage <ud...@wso2.com>
Subject Re: Why agent wait to be intialized even after complete topology is received
Date Mon, 22 Dec 2014 08:46:05 GMT
Hi raj,

Could not fetch agent's log, instance got killed since it did not send
activate :-(

On Mon, Dec 22, 2014 at 1:13 PM, Rajkumar Rajaratnam <rajkumarr@wso2.com>
wrote:
>
> Hi Udara,
>
> Can you attach the agent log?
>
> Thanks.
>
> On Mon, Dec 22, 2014 at 1:04 PM, Udara Liyanage <udara@wso2.com> wrote:
>
>> Hi raj,
>>
>> I tested with lates python code base. This time member is in topology and
>> instance spawned event is also received. But yet instance activated is not
>> sent.
>>
>> *1) Instance started event received*
>>
>> 2014-12-22 12:53:49,904:DEBUG:Property added: [key] STRATOS_
>> MEMBER_PROPERTY_PRIMARY[value] false
>> 2014-12-22 12:53:49,911:DEBUG:Message received:
>> 'instance/status/InstanceStartedEvent':
>> '{\n    "clusterId": "mytomcat.tomcat.domain", \n    "clusterInstanceId":
>> "app_cartridge_v1-1", \n    "instanceId": "", \n    "memberId":
>> "mytomcat.tomcat.domain5dd544b1-d2d6-4ace-8e31-ecb07e116130", \n
>>  "networkPartitionId": "p1", \n    "partitionId": "P1", \n
>>  "serviceName": "tomcat"\n}'
>>
>> 2) Member in topology
>>
>> *Complete Topology:*
>>
>>
>> '{"topology":{"serviceMap":{"tomcat":{"serviceName":"tomcat","serviceType":"SingleTenant","clusterIdClusterMap":{"mytomcat.tomcat.domain":{"serviceName":"tomcat","clusterId":"mytomcat.tomcat.domain","autoscalePolicyName":"autoscale_policy_1","hostNames":["
>> mytomcat.isuruh.lk
>> "],"tenantRange":"*","isLbCluster":false,"isKubernetesCluster":false,"memberMap":{"mytomcat.tomcat.domain5dd544b1-d2d6-4ace-8e31-ecb07e116130":{"serviceName":"tomcat","clusterId":"mytomcat.tomcat.domain","memberId":"
>> *mytomcat.tomcat.domain5dd544b1-d2d6-4ace-8e31-ecb07e116130*
>> ","instanceId":"ap-southeast-1/i-0147e4cc","clusterInstanceId":"app_cartridge_v1-1","networkPartitionId":"p1","partitionId":"P1","initTime":1419232682397,"portMap":{"443":{"protocol":"https","value":443,"proxy":443},"80":{"protocol":"http","value":80,"proxy":80}},"memberPublicIPs":["54.254.118.165"],"defaultPublicIP":"54.254.118.165","memberPrivateIPs":["10.144.150.199"],"defaultPrivateIP":"10.144.150.199","properties":{"PRIMARY":"false","MIN_COUNT":"1"},"memberStateManager":{"stateStack":["Created","Starting"],"identifier":"mytomcat.tomcat.domain5dd544b1-d2d6-4ace-8e31-ecb07e116130"}}},"appId":"app_cartridge_v1","properties":{},"instanceIdToInstanceContextMap":{"app_cartridge_v1-1":{"instanceId":"app_cartridge_v1-1","instanceProperties":{},"lifeCycleStateManager":{"stateStack":["Created"],"identifier":"mytomcat.tomcat.domain_app_cartridge_v1-1"},"networkPartitionId":"p1"}}}},"portMap":{"443":{"protocol":"https","value":443,"proxy":443},"80":{"protocol":"http","value":80,"proxy":80}},"properties":{}}},"initialized":false}}'
>>
>> *Payload:*
>> APP_ID=app_cartridge_v1,GROUP_NAME=null,SERVICE_NAME=tomcat,HOST_NAME=
>> mytomcat.isuruh.lk
>> ,MULTITENANT=false,TENANT_ID=-1234,TENANT_RANGE=*,CARTRIDGE_ALIAS=mytomcat,CLUSTER_ID=mytomcat.tomcat.domain,CARTRIDGE_KEY=kYcZ4B4DPAeA1RGz,REPO_URL=
>> https://github.com/udaraliyanage/php,PORTS=80|443,PROVIDER=apache,PUPPET_IP=10.148.159.109,PUPPET_HOSTNAME=ip-10-148-159-109.ap-southeast-1.compute.internal,PUPPET_DNS_AVAILABLE=null,PUPPET_ENV=null,,,DEPLOYMENT=default,TOKEN=eyJhbGciOiJSUzI1NiJ9.eyJleHAiOjE4OTY4OTAwNDgsInN1YiI6ImFkbWluIiwiYXpwIjoiMjU1UUZET1UwbFlONmY5NE44aUVhQm5LdlBrYSIsImFwcElkIjoiYXBwX2NhcnRyaWRnZV92MSIsImF1ZCI6WyIyNTVRRkRPVTBsWU42Zjk0TjhpRWFCbkt2UGthIl0sImlzcyI6Imh0dHBzOlwvXC9sb2NhbGhvc3Q6OTQ0M1wvb2F1dGgyZW5kcG9pbnRzXC90b2tlbiIsImlhdCI6MTg5MzI5MDA0OH0.XB4wg8gQxzyH0jv5EIiWFjOG0VDZuFBswYG0G6zX3sjOEgXauywoga4pHmb9DWkKZU9nD5qRFLRyyiHi2onWr-TGtLNBn4qgRq94VDwjVgx5in4ND3f7q-xNSpLipMr_g_gtkbp9st-gZVeuB8y3u7jnjE8fuDEs63pQ75Drrhs,MEMBER_ID=
>> *mytomcat.tomcat.domain5dd544b1-d2d6-4ace-8e31-ecb07e116130*
>> ,INSTANCE_ID=null,CLUSTER_INSTANCE_ID=app_cartridge_v1-1,LB_CLUSTER_ID=null,NETWORK_PARTITION_ID=p1,PARTITION_ID=P1,PRIMARY=false,MIN_COUNT=1
>>
>> Carbon logs:
>>
>> [2014-12-22 07:18:41,444]  INFO
>> {org.apache.stratos.cloud.controller.messaging.publisher.TopologyEventPublisher}
>> -  Publishing instance spawned event: [service-name] tomcat [cluster-id]
>> mytomcat.tomcat.domain [cluster-instance-id] app_cartridge_v1-1 [member-id]
>> mytomcat.tomcat.domain5dd544b1-d2d6-4ace-8e31-ecb07e116130 [instance-id]
>> ap-southeast-1/i-0147e4cc [network-partition-id] p1 [partition-id] P1
>> [lb-cluster-id] null
>> [2014-12-22 07:18:41,464]  INFO
>> {org.apache.stratos.messaging.domain.topology.lifecycle.LifeCycleStateManager}
>> -  Life Cycle State Manager started for Element [
>> mytomcat.tomcat.domain5dd544b1-d2d6-4ace-8e31-ecb07e116130 ], initial
>> state: Created
>> [2014-12-22 07:18:41,469]  INFO
>> {org.apache.stratos.messaging.message.processor.topology.InstanceSpawnedMessageProcessor}
>> -  Member created: [service] tomcat [cluster] mytomcat.tomcat.domain
>> [member] mytomcat.tomcat.domain5dd544b1-d2d6-4ace-8e31-ecb07e116130
>> [instance] ap-southeast-1/i-0147e4cc [hierarchy-instance] app_cartridge_v1-1
>> [2014-12-22 07:18:41,480]  INFO
>> {org.apache.stratos.manager.topology.receiver.StratosManagerTopologyEventReceiver}
>> -  [InstanceSpawnedEventListener] Received: class
>> org.apache.stratos.messaging.event.topology.InstanceSpawnedEvent
>> [2014-12-22 07:24:01,218]  INFO
>> {org.apache.stratos.manager.listener.InstanceStatusListener} -  Instance
>> status message received
>> [2014-12-22 07:24:01,218]  INFO
>> {org.apache.stratos.manager.listener.InstanceStatusListener} -  Event class
>> name:
>> org.apache.stratos.messaging.event.instance.status.InstanceStartedEvent
>> [2014-12-22 07:24:01,219]  INFO
>> {org.apache.stratos.manager.listener.InstanceStatusListener} -  Cluster id:
>> mytomcat.tomcat.domain
>> [2014-12-22 07:24:01,220]  INFO
>> {org.apache.stratos.cloud.controller.messaging.topology.TopologyBuilder} -
>>  member started event adding status started
>> [2014-12-22 07:24:01,235]  INFO
>> {org.apache.stratos.cloud.controller.messaging.publisher.TopologyEventPublisher}
>> -  Publishing member started event: [service-name] tomcat [cluster-id]
>> mytomcat.tomcat.domain [cluster-instance-id] app_cartridge_v1-1 [member-id]
>> mytomcat.tomcat.domain5dd544b1-d2d6-4ace-8e31-ecb07e116130 [instance-id]
>>  [network-partition-id] p1 [partition-id] P1
>> [2014-12-22 07:24:01,250]  INFO
>> {org.apache.stratos.messaging.message.processor.topology.MemberStartedMessageProcessor}
>> -  Member started: [service] tomcat [cluster] mytomcat.tomcat.domain
>> [member] mytomcat.tomcat.domain5dd544b1-d2d6-4ace-8e31-ecb07e116130
>> [2014-12-22 07:24:01,250]  INFO
>> {org.apache.stratos.manager.topology.receiver.StratosManagerTopologyEventReceiver}
>> -  [MemberStartedEventListener] Received: class
>> org.apache.stratos.messaging.event.topology.MemberStartedEvent
>>
>>
>>
>> On Mon, Dec 22, 2014 at 12:26 PM, Udara Liyanage <udara@wso2.com> wrote:
>>>
>>> Hi Raj,
>>>
>>> Thanks for the info. I will check with updated agent.
>>>
>>> On Mon, Dec 22, 2014 at 12:24 PM, Rajkumar Rajaratnam <
>>> rajkumarr@wso2.com> wrote:
>>>>
>>>> Hi Udara,
>>>>
>>>> Are you using the latest python agent?
>>>>
>>>> Chamila changed the agent yesterday. Member object doesn't have an
>>>> attribute called "memberIp anymore. It has been changed to defaultPrivateIP.
>>>>
>>>> Latest python agent has this changes.
>>>>
>>>> Thanks.
>>>>
>>>> On Mon, Dec 22, 2014 at 12:22 PM, Rajkumar Rajaratnam <
>>>> rajkumarr@wso2.com> wrote:
>>>>
>>>>> Just noticed that there is an error in parsing complete topology
>>>>> event. So initialization didn't happen as Isuru mentioned.
>>>>>
>>>>> Thanks.
>>>>>
>>>>> On Mon, Dec 22, 2014 at 12:19 PM, Rajkumar Rajaratnam <
>>>>> rajkumarr@wso2.com> wrote:
>>>>>
>>>>>> Hi,
>>>>>>
>>>>>> AFAIR, agent will wait for two conditions to happen. It will proceed
>>>>>> if either of two conditions becomes true.
>>>>>>
>>>>>>   1. Member should be in the topology
>>>>>>   2. Agent should get instance spawned event from CC
>>>>>>
>>>>>> So either of them didn't happen for you.
>>>>>>
>>>>>> Thanks.
>>>>>>
>>>>>> On Mon, Dec 22, 2014 at 12:17 PM, Isuru Haththotuwa <
>>>>>> isuruh@apache.org> wrote:
>>>>>>
>>>>>>> HI Udara,
>>>>>>>
>>>>>>> There is an error log about parsing the complete Topology event.
>>>>>>> Maybe the event did not get parsed correctly, and the required
>>>>>>> initialization did not happen.
>>>>>>>
>>>>>>> On Mon, Dec 22, 2014 at 12:12 PM, Udara Liyanage <udara@wso2.com>
>>>>>>> wrote:
>>>>>>>
>>>>>>>> Hi Chamila,
>>>>>>>>
>>>>>>>> I get the ":Waiting for Cartridge Agent to be initialized..."
logs
>>>>>>>> continuously even though complete topology and complete tenant
event is
>>>>>>>> already received.
>>>>>>>>
>>>>>>>>
>>>>>>>> 2014-12-22 12:04:47,595:DEBUG:Waiting for Cartridge Agent
to be
>>>>>>>> initialized...
>>>>>>>> 2014-12-22 12:04:48,596:DEBUG:Waiting for Cartridge Agent
to be
>>>>>>>> initialized...
>>>>>>>> 2014-12-22 12:04:49,597:DEBUG:Waiting for Cartridge Agent
to be
>>>>>>>> initialized...
>>>>>>>> 2014-12-22 12:04:49,781:DEBUG:Message received:
>>>>>>>> 'topology/CompleteTopologyEvent':
>>>>>>>>
>>>>>>>> '{"topology":{"serviceMap":{"tomcat":{"serviceName":"tomcat","serviceType":"SingleTenant","clusterIdClusterMap":{"mytomcat.tomcat.domain":{"serviceName":"tomcat","clusterId":"mytomcat.tomcat.domain","autoscalePolicyName":"autoscale_policy_1","hostNames":["
>>>>>>>> mytomcat.isuruh.lk
>>>>>>>> "],"tenantRange":"*","isLbCluster":false,"isKubernetesCluster":false,"memberMap":{"mytomcat.tomcat.domainf1ee1c36-db95-452d-a1e7-1daf936e9101":{"serviceName":"tomcat","clusterId":"mytomcat.tomcat.domain","memberId":"mytomcat.tomcat.domainf1ee1c36-db95-452d-a1e7-1daf936e9101","instanceId":"ap-southeast-1/i-df7dde12","clusterInstanceId":"app_cartridge_v1-1","networkPartitionId":"p1","partitionId":"P1","initTime":1419229605140,"portMap":{"443":{"protocol":"https","value":443,"proxy":443},"80":{"protocol":"http","value":80,"proxy":80}},"memberPublicIPs":["54.151.190.32"],"defaultPublicIP":"54.151.190.32","memberPrivateIPs":["10.144.68.9"],"defaultPrivateIP":"10.144.68.9","properties":{"PRIMARY":"false","MIN_COUNT":"1"},"memberStateManager":{"stateStack":["Created"],"identifier":"mytomcat.tomcat.domainf1ee1c36-db95-452d-a1e7-1daf936e9101"}}},"appId":"app_cartridge_v1","properties":{},"instanceIdToInstanceContextMap":{"app_cartridge_v1-1":{"instanceId":"app_cartridge_v1-1","instanceProperties":{},"lifeCycleStateManager":{"stateStack":["Created"],"identifier":"mytomcat.tomcat.domain_app_cartridge_v1-1"},"networkPartitionId":"p1"}}}},"portMap":{"443":{"protocol":"https","value":443,"proxy":443},"80":{"protocol":"http","value":80,"proxy":80}},"properties":{}}},"initialized":false}}'
>>>>>>>> 2014-12-22 12:04:49,781:DEBUG:Executing handler for event
>>>>>>>> 'CompleteTopologyEvent'
>>>>>>>> 2014-12-22 12:04:49,781:DEBUG:Complete topology event received
>>>>>>>> 2014-12-22 12:04:49,782:ERROR:Error processing
>>>>>>>> 'CompleteTopologyEvent' event
>>>>>>>> Traceback (most recent call last):
>>>>>>>>   File
>>>>>>>> "/mnt/apache-stratos-python-cartridge-agent-4.1.0-SNAPSHOT/modules/subscriber/eventsubscriber.py",
>>>>>>>> line 81, in on_message
>>>>>>>>     handler(msg)
>>>>>>>>   File "agent.py", line 255, in on_complete_topology
>>>>>>>>     event_obj = CompleteTopologyEvent.create_from_json(msg.payload)
>>>>>>>>   File
>>>>>>>> "/mnt/apache-stratos-python-cartridge-agent-4.1.0-SNAPSHOT/modules/event/topology/events.py",
>>>>>>>> line 190, in create_from_json
>>>>>>>>     member_obj.member_ip = member_str["memberIp"]
>>>>>>>> KeyError: 'memberIp'
>>>>>>>> 2014-12-22 12:04:49,820:DEBUG:Message received:
>>>>>>>> 'tenant/CompleteTenantEvent':
>>>>>>>> '{"tenants":[]}'
>>>>>>>> 2014-12-22 12:04:49,820:DEBUG:Executing handler for event
>>>>>>>> 'CompleteTenantEvent'
>>>>>>>> 2014-12-22 12:04:49,820:INFO:Complete tenant event updating
task
>>>>>>>> disabled
>>>>>>>>
>>>>>>>>
>>>>>>>> *Carbon logs:*
>>>>>>>>
>>>>>>>> TID: [0] [STRATOS] [2014-12-22 06:27:21,240]  INFO
>>>>>>>> {org.apache.stratos.cloud.controller.messaging.publisher.TopologyEventPublisher}
>>>>>>>> -  Publishing instance spawned event: [service-name] tomcat
[cluster-id]
>>>>>>>> mytomcat.tomcat.domain [cluster-instance-id] app_cartridge_v1-1
[member-id]
>>>>>>>> mytomcat.tomcat.domainf1ee1c36-db95-452d-a1e7-1daf936e9101
[instance-id]
>>>>>>>> ap-southeast-1/i-df7dde12 [network-partition-id] p1 [partition-id]
P1
>>>>>>>> [lb-cluster-id] null
>>>>>>>> TID: [0] [STRATOS] [2014-12-22 06:27:21,261]  INFO
>>>>>>>> {org.apache.stratos.messaging.domain.topology.lifecycle.LifeCycleStateManager}
>>>>>>>> -  Life Cycle State Manager started for Element [
>>>>>>>> mytomcat.tomcat.domainf1ee1c36-db95-452d-a1e7-1daf936e9101
], initial
>>>>>>>> state: Created
>>>>>>>> TID: [0] [STRATOS] [2014-12-22 06:27:21,264]  INFO
>>>>>>>> {org.apache.stratos.messaging.message.processor.topology.InstanceSpawnedMessageProcessor}
>>>>>>>> -  Member created: [service] tomcat [cluster] mytomcat.tomcat.domain
>>>>>>>> [member] mytomcat.tomcat.domainf1ee1c36-db95-452d-a1e7-1daf936e9101
>>>>>>>> [instance] ap-southeast-1/i-df7dde12 [hierarchy-instance]
app_cartridge_v1-1
>>>>>>>> TID: [0] [STRATOS] [2014-12-22 06:27:21,268]  INFO
>>>>>>>> {org.apache.stratos.manager.topology.receiver.StratosManagerTopologyEventReceiver}
>>>>>>>> -  [InstanceSpawnedEventListener] Received: class
>>>>>>>> org.apache.stratos.messaging.event.topology.InstanceSpawnedEvent
>>>>>>>>
>>>>>>>> --
>>>>>>>>
>>>>>>>> Udara Liyanage
>>>>>>>> Software Engineer
>>>>>>>> WSO2, Inc.: http://wso2.com
>>>>>>>> lean. enterprise. middleware
>>>>>>>>
>>>>>>>> web: http://udaraliyanage.wordpress.com
>>>>>>>> phone:
>>>>>>>> +94 71 443 6897
>>>>>>>>
>>>>>>>> --
>>>>>>>> Thanks and Regards,
>>>>>>>>
>>>>>>>> Isuru H.
>>>>>>>> +94 716 358 048* <http://wso2.com/>*
>>>>>>>>
>>>>>>>>
>>>>>>>> * <http://wso2.com/>*
>>>>>>>>
>>>>>>>>
>>>>>>>>
>>>>>>
>>>>>>
>>>>>> --
>>>>>> Rajkumar Rajaratnam
>>>>>> Committer & PMC Member, Apache Stratos
>>>>>> Software Engineer, WSO2
>>>>>>
>>>>>> Mobile : +94777568639
>>>>>> Blog : rajkumarr.com
>>>>>>
>>>>>
>>>>>
>>>>>
>>>>> --
>>>>> Rajkumar Rajaratnam
>>>>> Committer & PMC Member, Apache Stratos
>>>>> Software Engineer, WSO2
>>>>>
>>>>> Mobile : +94777568639
>>>>> Blog : rajkumarr.com
>>>>>
>>>>
>>>>
>>>>
>>>> --
>>>> Rajkumar Rajaratnam
>>>> Committer & PMC Member, Apache Stratos
>>>> Software Engineer, WSO2
>>>>
>>>> Mobile : +94777568639
>>>> Blog : rajkumarr.com
>>>>
>>>
>>>
>>> --
>>>
>>> Udara Liyanage
>>> Software Engineer
>>> WSO2, Inc.: http://wso2.com
>>> lean. enterprise. middleware
>>>
>>> web: http://udaraliyanage.wordpress.com
>>> phone: +94 71 443 6897
>>>
>>
>>
>> --
>>
>> Udara Liyanage
>> Software Engineer
>> WSO2, Inc.: http://wso2.com
>> lean. enterprise. middleware
>>
>> web: http://udaraliyanage.wordpress.com
>> phone: +94 71 443 6897
>>
>
>
>
> --
> Rajkumar Rajaratnam
> Committer & PMC Member, Apache Stratos
> Software Engineer, WSO2
>
> Mobile : +94777568639
> Blog : rajkumarr.com
>


-- 

Udara Liyanage
Software Engineer
WSO2, Inc.: http://wso2.com
lean. enterprise. middleware

web: http://udaraliyanage.wordpress.com
phone: +94 71 443 6897

Mime
View raw message