Registration Error on LWM2M Client

kapsali29 commented 6 years ago

Hi all,

I am using iotagent lwm2m docker container, so after provisioning a device with id robot1 and type Robot (example from Device provisioning), i go to set some values from the client, and I get the following error:

Error:
--------------------------------
Code: REGISTRATION_ERROR
Message: There was an error connecting to the LWM2M Server for registration: Error: No reply in 247s

Thanks

dcalvoalonso commented 6 years ago

Hi @kapsali29,

Could you please give us some more information about your testing environment?

For instance: which version of the docker container are you using? How have you executed the agent?

Thanks in advance and BR,

Daniel

kapsali29 commented 6 years ago

@dcalvoalonso , thanks for your immediate response. I am using the following docker image https://hub.docker.com/r/telefonicaiot/lightweightm2m-iotagent/. I dont know how, this stopped appearing. Now, i am sending through the client values and i cant see these values on Orion CB.

Using the following command i start the lwm2m client

docker exec -ti [AGENT_CONTAINER_ID_HERE] node_modules/lwm2m-node-lib/bin/iotagent-lwm2m-client.js

and following the documentation examples, i send values to the corresponding device, but nothing seems to change.

Thanks in advance

fgalan commented 6 years ago

@kapsali29 , I'm afraid I'm not sure what you mean...

I mean, by "I dont know how, this stopped appearing" it seems that the problem is no longer happening in your environment. However, by "i send values to the corresponding device, but nothing seems to change" seems the problem stills (i.e "no change" -> "the problem is the same").

Could you clarify what the exact situation is, please?

arilwan commented 6 years ago

I am have similar issue here. I set the values to robot1 attributes as far the tutorial, but connecting to server failed: I issued the command: LWM2M-Client> connect localhost 5684 robot1 /

And get this error message: Connecting to the server. This may take a while.

Error:

Code: REGISTRATION_ERROR Message: There was an error connecting to the LWM2M Server for registration: Error: No reply in 247s

dcalvoalonso commented 6 years ago

Hi @arilwan.

Could you please give us further details to try to help you? For instance: 1) Are you using docker or directly the code from the repository? 2) Could you please share your configuration file? 3) Could you please share the log output from the agent?

arilwan commented 6 years ago

Hi @dcalvoalonso,

Yes, I'm using docker to follow up the FIWARE-IOTAGENT-LWM2M Get Started available at https://fiware-iotagent-lwm2m.readthedocs.io/en/latest/deviceProvisioning/index.html
Here's the configuration: services: mongo: image: mongo:3.4 command: --nojournal orion: image: fiware/orion links:
- mongo ports:
- "1026:1026" command: -dbhost mongo lightweightm2m-iotagent: image: telefonicaiot/lightweightm2m-iotagent hostname: idas links:
- orion expose:
- "4041"
- "5684" ports: "docker-compose.yml" 50L, 933C

{ "logLevel": "DEBUG", "contextBroker": { "host": "orion", "port": "1026", "url": "http://orion:1026" }, "server": { "port": 4041 }, "deviceRegistry": { "type": "memory" }, "types": {}, "service": "smartGondor", "subservice": "/gardens", "providerUrl": "http://localhost:4041", "deviceRegistrationDuration": "P1M" } | comp=IoTAgent time=2018-09-15T09:48:46.899Z | lvl=INFO | corr=9b3f9317-7418-4726-bcb3-72ae34d45c15 | trans=9b3f9317-7418-4726-bcb3-72ae34d45c15 | op=IoTAgentNGSI.ContextServer | srv=n/a | subsrv=n/a | msg=Loading NGSI Contect server routes | comp=IoTAgent time=2018-09-15T09:48:46.901Z | lvl=DEBUG | corr=9b3f9317-7418-4726-bcb3-72ae34d45c15 | trans=9b3f9317-7418-4726-bcb3-72ae34d45c15 | op=IoTAgentNGSI.ContextServer | srv=n/a | subsrv=n/a | msg=Loading access validation Templates | comp=IoTAgent time=2018-09-15T09:48:46.909Z | lvl=DEBUG | corr=n/a | trans=n/a | op=LWM2MLib.COAPRouter | msg=Setting [registration] handler time=2018-09-15T09:48:46.909Z | lvl=DEBUG | corr=n/a | trans=n/a | op=LWM2MLib.COAPRouter | msg=Setting [unregistration] handler time=2018-09-15T09:48:46.910Z | lvl=DEBUG | corr=n/a | trans=n/a | op=LWM2MLib.COAPRouter | msg=Setting [updateRegistration] handler {"op":"IOTAgent.Global","time":"2018-09-15T09:48:46.910Z","lvl":"INFO","msg":"Agent started"} {"op":"IOTAgent.Executable","time":"2018-09-15T09:48:46.912Z","lvl":"INFO","msg":"Lightweight M2M IoT Agent started"} time=2018-09-17T10:25:35.888Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.GenericMiddlewares | srv=factory | subsrv=/robots | msg=Request for path [/iot/devices] from [localhost:4041] | comp=IoTAgent time=2018-09-17T10:25:35.891Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.GenericMiddlewares | srv=factory | subsrv=/robots | msg=Body:

{ "devices": [ { "device_id": "robot1", "entity_type": "Robot", "attributes": [ { "name": "Battery", "type": "number" } ], "lazy": [ { "name": "Message", "type": "string" } ], "commands": [ { "name": "Position", "type": "location" } ], "internal_attributes": { "lwm2mResourceMapping": { "Battery": { "objectType": 7392, "objectInstance": 0, "objectResource": 1 }, "Message": { "objectType": 7392, "objectInstance": 0, "objectResource": 2 }, "Position": { "objectType": 7392, "objectInstance": 0, "objectResource": 3 } } } } ] }

| comp=IoTAgent time=2018-09-17T10:25:35.897Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.DeviceProvisioning | srv=factory | subsrv=/robots | msg=Handling device provisioning request. | comp=IoTAgent time=2018-09-17T10:25:35.901Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.InMemoryGroupRegister | srv=factory | subsrv=/robots | msg=Looking for device params ["service","subservice","type"] | comp=IoTAgent time=2018-09-17T10:25:35.903Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Device name not found, falling back to deviceId:type [Robot:robot1] | comp=IoTAgent time=2018-09-17T10:25:35.906Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Registering device into NGSI Service: { "id": "robot1", "type": "Robot", "name": "Robot:robot1", "service": "factory", "subservice": "/robots", "active": [ { "name": "Battery", "type": "number", "object_id": "Battery" } ], "staticAttributes": [], "lazy": [ { "name": "Message", "type": "string", "object_id": "Message" } ], "commands": [ { "name": "Position", "type": "location", "object_id": "Position" } ], "internalAttributes": { "lwm2mResourceMapping": { "Battery": { "objectType": 7392, "objectInstance": 0, "objectResource": 1 }, "Message": { "objectType": 7392, "objectInstance": 0, "objectResource": 2 }, "Position": { "objectType": 7392, "objectInstance": 0, "objectResource": 3 } } }, "internalId": null, "subscriptions": [] } | comp=IoTAgent time=2018-09-17T10:25:35.908Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Sending device registrations to Context Broker at [http://orion:1026/NGSI9/registerContext] | comp=IoTAgent time=2018-09-17T10:25:35.908Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Using the following request:

{ "url": "http://orion:1026/NGSI9/registerContext", "method": "POST", "json": { "contextRegistrations": [ { "entities": [ { "type": "Robot", "isPattern": "false", "id": "Robot:robot1" } ], "attributes": [ { "name": "Message", "type": "string", "isDomain": "false" }, { "name": "Position", "type": "location", "isDomain": "false" } ], "providingApplication": "http://localhost:4041" } ], "duration": "P1M" }, "headers": { "fiware-service": "factory", "fiware-servicepath": "/robots" } }

| comp=IoTAgent time=2018-09-17T10:25:36.636Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Registration success. | comp=IoTAgent time=2018-09-17T10:25:36.641Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Creating initial entity in the Context Broker: { "url": "http://orion:1026/v1/updateContext", "method": "POST", "json": { "contextElements": [ { "type": "Robot", "isPattern": "false", "id": "Robot:robot1", "attributes": [ { "name": "Battery", "type": "number", "value": " " }, { "name": "Position_status", "type": "commandStatus", "value": "UNKNOWN" }, { "name": "Position_info", "type": "commandResult", "value": " " } ] } ], "updateAction": "APPEND" }, "headers": { "fiware-service": "factory", "fiware-servicepath": "/robots", "fiware-correlator": "d2156443-08a1-41f0-a450-fb0a7813c489" } } | comp=IoTAgent time=2018-09-17T10:25:37.220Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Initial entity created successfully. | comp=IoTAgent time=2018-09-17T10:25:37.221Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.InMemoryDeviceRegister | srv=factory | subsrv=/robots | msg=Storing device with id [robot1] and type [Robot] | comp=IoTAgent time=2018-09-17T10:25:37.222Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.DeviceProvisioning | srv=factory | subsrv=/robots | msg=Device provisioning request succeeded | comp=IoTAgent time=2018-09-17T10:25:37.231Z | lvl=DEBUG | corr=d2156443-08a1-41f0-a450-fb0a7813c489 | trans=d2156443-08a1-41f0-a450-fb0a7813c489 | op=IoTAgentNGSI.DomainControl | srv=factory | subsrv=/robots | msg=response-time: 1389 | comp=IoTAgent time=2018-09-17T10:41:21.285Z | lvl=DEBUG | corr=ab58f124-d41e-4b02-a92d-f1a2596c6f4f | trans=ab58f124-d41e-4b02-a92d-f1a2596c6f4f | op=IoTAgentNGSI.GenericMiddlewares | srv=factory | subsrv=/robots | msg=Request for path [/iot/devices] from [localhost:4041] | comp=IoTAgent time=2018-09-17T10:41:21.287Z | lvl=DEBUG | corr=ab58f124-d41e-4b02-a92d-f1a2596c6f4f | trans=ab58f124-d41e-4b02-a92d-f1a2596c6f4f | op=IoTAgentNGSI.GenericMiddlewares | srv=factory | subsrv=/robots | msg=Body:

{ "devices": [ { "device_id": "robot1", "entity_type": "Robot", "attributes": [ { "name": "Battery", "type": "number" } ], "lazy": [ { "name": "Message", "type": "string" } ], "commands": [ { "name": "Position", "type": "location" } ], "internal_attributes": { "lwm2mResourceMapping": { "Battery": { "objectType": 7392, "objectInstance": 0, "objectResource": 1 }, "Message": { "objectType": 7392, "objectInstance": 0, "objectResource": 2 }, "Position": { "objectType": 7392, "objectInstance": 0, "objectResource": 3 } } } } ] }

{ "devices": [ { "device_id": "robot2", "entity_type": "Robot", "attributes": [ { "name": "Battery", "type": "number" } ], "lazy": [ { "name": "Message", "type": "string" } ], "commands": [ { "name": "Position", "type": "location" } ], "internal_attributes": { "lwm2mResourceMapping": { "Battery": { "objectType": 7392, "objectInstance": 0, "objectResource": 1 }, "Message": { "objectType": 7392, "objectInstance": 0, "objectResource": 2 }, "Position": { "objectType": 7392, "objectInstance": 0, "objectResource": 3 } } } } ] }

| comp=IoTAgent time=2018-09-17T11:29:40.991Z | lvl=DEBUG | corr=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | trans=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | op=IoTAgentNGSI.DeviceProvisioning | srv=factory | subsrv=/robots | msg=Handling device provisioning request. | comp=IoTAgent time=2018-09-17T11:29:40.994Z | lvl=DEBUG | corr=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | trans=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | op=IoTAgentNGSI.InMemoryGroupRegister | srv=factory | subsrv=/robots | msg=Looking for device params ["service","subservice","type"] | comp=IoTAgent time=2018-09-17T11:29:40.995Z | lvl=DEBUG | corr=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | trans=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Device name not found, falling back to deviceId:type [Robot:robot2] | comp=IoTAgent time=2018-09-17T11:29:40.996Z | lvl=DEBUG | corr=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | trans=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Registering device into NGSI Service: { "id": "robot2", "type": "Robot", "name": "Robot:robot2", "service": "factory", "subservice": "/robots", "active": [ { "name": "Battery", "type": "number", "object_id": "Battery" } ], "staticAttributes": [], "lazy": [ { "name": "Message", "type": "string", "object_id": "Message" } ], "commands": [ { "name": "Position", "type": "location", "object_id": "Position" } ], "internalAttributes": { "lwm2mResourceMapping": { "Battery": { "objectType": 7392, "objectInstance": 0, "objectResource": 1 }, "Message": { "objectType": 7392, "objectInstance": 0, "objectResource": 2 }, "Position": { "objectType": 7392, "objectInstance": 0, "objectResource": 3 } } }, "internalId": null, "subscriptions": [] } | comp=IoTAgent time=2018-09-17T11:29:40.996Z | lvl=DEBUG | corr=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | trans=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Sending device registrations to Context Broker at [http://orion:1026/NGSI9/registerContext] | comp=IoTAgent time=2018-09-17T11:29:40.997Z | lvl=DEBUG | corr=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | trans=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Using the following request:

{ "url": "http://orion:1026/NGSI9/registerContext", "method": "POST", "json": { "contextRegistrations": [ { "entities": [ { "type": "Robot", "isPattern": "false", "id": "Robot:robot2" } ], "attributes": [ { "name": "Message", "type": "string", "isDomain": "false" }, { "name": "Position", "type": "location", "isDomain": "false" } ], "providingApplication": "http://localhost:4041" } ], "duration": "P1M" }, "headers": { "fiware-service": "factory", "fiware-servicepath": "/robots" } }

| comp=IoTAgent time=2018-09-17T11:29:41.014Z | lvl=DEBUG | corr=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | trans=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Registration success. | comp=IoTAgent time=2018-09-17T11:29:41.019Z | lvl=DEBUG | corr=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | trans=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Creating initial entity in the Context Broker: { "url": "http://orion:1026/v1/updateContext", "method": "POST", "json": { "contextElements": [ { "type": "Robot", "isPattern": "false", "id": "Robot:robot2", "attributes": [ { "name": "Battery", "type": "number", "value": " " }, { "name": "Position_status", "type": "commandStatus", "value": "UNKNOWN" }, { "name": "Position_info", "type": "commandResult", "value": " " } ] } ], "updateAction": "APPEND" }, "headers": { "fiware-service": "factory", "fiware-servicepath": "/robots", "fiware-correlator": "4e23d317-3f5f-4d25-bab8-ac03b92e3c9e" } } | comp=IoTAgent time=2018-09-17T11:29:41.029Z | lvl=DEBUG | corr=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | trans=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Initial entity created successfully. | comp=IoTAgent time=2018-09-17T11:29:41.030Z | lvl=DEBUG | corr=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | trans=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | op=IoTAgentNGSI.InMemoryDeviceRegister | srv=factory | subsrv=/robots | msg=Storing device with id [robot2] and type [Robot] | comp=IoTAgent time=2018-09-17T11:29:41.030Z | lvl=DEBUG | corr=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | trans=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | op=IoTAgentNGSI.DeviceProvisioning | srv=factory | subsrv=/robots | msg=Device provisioning request succeeded | comp=IoTAgent time=2018-09-17T11:29:41.031Z | lvl=DEBUG | corr=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | trans=4e23d317-3f5f-4d25-bab8-ac03b92e3c9e | op=IoTAgentNGSI.DomainControl | srv=factory | subsrv=/robots | msg=response-time: 47 | comp=IoTAgent time=2018-09-17T13:51:10.562Z | lvl=DEBUG | corr=fb677f17-5a4f-48e0-871f-e2dec2fc9e55 | trans=fb677f17-5a4f-48e0-871f-e2dec2fc9e55 | op=IoTAgentNGSI.GenericMiddlewares | srv=factory | subsrv=/robots | msg=Request for path [/iot/devices] from [localhost:4041] | comp=IoTAgent time=2018-09-17T13:51:10.564Z | lvl=DEBUG | corr=fb677f17-5a4f-48e0-871f-e2dec2fc9e55 | trans=fb677f17-5a4f-48e0-871f-e2dec2fc9e55 | op=IoTAgentNGSI.GenericMiddlewares | srv=factory | subsrv=/robots | msg=Body:

{ "devices": [ { "device_id": "robot2", "entity_type": "Robot", "attributes": [ { "name": "Battery", "type": "number" } ], "lazy": [ { "name": "Message", "type": "string" } ], "commands": [ { "name": "Position", "type": "location" } ], "internal_attributes": { "lwm2mResourceMapping": { "Battery": { "objectType": 7392, "objectInstance": 0, "objectResource": 1 }, "Message": { "objectType": 7392, "objectInstance": 0, "objectResource": 2 }, "Position": { "objectType": 7392, "objectInstance": 0, "objectResource": 3 } } } } ] }

{ "devices": [ { "device_id": "robot3", "entity_type": "Robot", "attributes": [ { "name": "Battery", "type": "number" } ], "lazy": [ { "name": "Message", "type": "string" } ], "commands": [ { "name": "Position", "type": "location" } ], "internal_attributes": { "lwm2mResourceMapping": { "Battery": { "objectType": 7392, "objectInstance": 0, "objectResource": 1 }, "Message": { "objectType": 7392, "objectInstance": 0, "objectResource": 2 }, "Position": { "objectType": 7392, "objectInstance": 0, "objectResource": 3 } } } } ] }

| comp=IoTAgent time=2018-09-17T13:56:48.737Z | lvl=DEBUG | corr=741c455a-a316-4da2-9dd1-de78a104fcfd | trans=741c455a-a316-4da2-9dd1-de78a104fcfd | op=IoTAgentNGSI.DeviceProvisioning | srv=factory | subsrv=/robots | msg=Handling device provisioning request. | comp=IoTAgent time=2018-09-17T13:56:48.739Z | lvl=DEBUG | corr=741c455a-a316-4da2-9dd1-de78a104fcfd | trans=741c455a-a316-4da2-9dd1-de78a104fcfd | op=IoTAgentNGSI.InMemoryGroupRegister | srv=factory | subsrv=/robots | msg=Looking for device params ["service","subservice","type"] | comp=IoTAgent time=2018-09-17T13:56:48.740Z | lvl=DEBUG | corr=741c455a-a316-4da2-9dd1-de78a104fcfd | trans=741c455a-a316-4da2-9dd1-de78a104fcfd | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Device name not found, falling back to deviceId:type [Robot:robot3] | comp=IoTAgent time=2018-09-17T13:56:48.745Z | lvl=DEBUG | corr=741c455a-a316-4da2-9dd1-de78a104fcfd | trans=741c455a-a316-4da2-9dd1-de78a104fcfd | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Registering device into NGSI Service: { "id": "robot3", "type": "Robot", "name": "Robot:robot3", "service": "factory", "subservice": "/robots", "active": [ { "name": "Battery", "type": "number", "object_id": "Battery" } ], "staticAttributes": [], "lazy": [ { "name": "Message", "type": "string", "object_id": "Message" } ], "commands": [ { "name": "Position", "type": "location", "object_id": "Position" } ], "internalAttributes": { "lwm2mResourceMapping": { "Battery": { "objectType": 7392, "objectInstance": 0, "objectResource": 1 }, "Message": { "objectType": 7392, "objectInstance": 0, "objectResource": 2 }, "Position": { "objectType": 7392, "objectInstance": 0, "objectResource": 3 } } }, "internalId": null, "subscriptions": [] } | comp=IoTAgent time=2018-09-17T13:56:48.746Z | lvl=DEBUG | corr=741c455a-a316-4da2-9dd1-de78a104fcfd | trans=741c455a-a316-4da2-9dd1-de78a104fcfd | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Sending device registrations to Context Broker at [http://orion:1026/NGSI9/registerContext] | comp=IoTAgent time=2018-09-17T13:56:48.746Z | lvl=DEBUG | corr=741c455a-a316-4da2-9dd1-de78a104fcfd | trans=741c455a-a316-4da2-9dd1-de78a104fcfd | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Using the following request:

{ "url": "http://orion:1026/NGSI9/registerContext", "method": "POST", "json": { "contextRegistrations": [ { "entities": [ { "type": "Robot", "isPattern": "false", "id": "Robot:robot3" } ], "attributes": [ { "name": "Message", "type": "string", "isDomain": "false" }, { "name": "Position", "type": "location", "isDomain": "false" } ], "providingApplication": "http://localhost:4041" } ], "duration": "P1M" }, "headers": { "fiware-service": "factory", "fiware-servicepath": "/robots" } }

| comp=IoTAgent time=2018-09-17T13:56:48.762Z | lvl=DEBUG | corr=741c455a-a316-4da2-9dd1-de78a104fcfd | trans=741c455a-a316-4da2-9dd1-de78a104fcfd | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Registration success. | comp=IoTAgent time=2018-09-17T13:56:48.763Z | lvl=DEBUG | corr=741c455a-a316-4da2-9dd1-de78a104fcfd | trans=741c455a-a316-4da2-9dd1-de78a104fcfd | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Creating initial entity in the Context Broker: { "url": "http://orion:1026/v1/updateContext", "method": "POST", "json": { "contextElements": [ { "type": "Robot", "isPattern": "false", "id": "Robot:robot3", "attributes": [ { "name": "Battery", "type": "number", "value": " " }, { "name": "Position_status", "type": "commandStatus", "value": "UNKNOWN" }, { "name": "Position_info", "type": "commandResult", "value": " " } ] } ], "updateAction": "APPEND" }, "headers": { "fiware-service": "factory", "fiware-servicepath": "/robots", "fiware-correlator": "741c455a-a316-4da2-9dd1-de78a104fcfd" } } | comp=IoTAgent time=2018-09-17T13:56:48.770Z | lvl=DEBUG | corr=741c455a-a316-4da2-9dd1-de78a104fcfd | trans=741c455a-a316-4da2-9dd1-de78a104fcfd | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Initial entity created successfully. | comp=IoTAgent time=2018-09-17T13:56:48.770Z | lvl=DEBUG | corr=741c455a-a316-4da2-9dd1-de78a104fcfd | trans=741c455a-a316-4da2-9dd1-de78a104fcfd | op=IoTAgentNGSI.InMemoryDeviceRegister | srv=factory | subsrv=/robots | msg=Storing device with id [robot3] and type [Robot] | comp=IoTAgent time=2018-09-17T13:56:48.771Z | lvl=DEBUG | corr=741c455a-a316-4da2-9dd1-de78a104fcfd | trans=741c455a-a316-4da2-9dd1-de78a104fcfd | op=IoTAgentNGSI.DeviceProvisioning | srv=factory | subsrv=/robots | msg=Device provisioning request succeeded | comp=IoTAgent time=2018-09-17T13:56:48.772Z | lvl=DEBUG | corr=741c455a-a316-4da2-9dd1-de78a104fcfd | trans=741c455a-a316-4da2-9dd1-de78a104fcfd | op=IoTAgentNGSI.DomainControl | srv=factory | subsrv=/robots | msg=response-time: 41 | comp=IoTAgent time=2018-09-17T13:56:56.233Z | lvl=DEBUG | corr=005e2ca7-1145-46db-be9f-21c48478339f | trans=005e2ca7-1145-46db-be9f-21c48478339f | op=IoTAgentNGSI.GenericMiddlewares | srv=factory | subsrv=/robots | msg=Request for path [/iot/devices] from [localhost:4041] | comp=IoTAgent time=2018-09-17T13:56:56.234Z | lvl=DEBUG | corr=005e2ca7-1145-46db-be9f-21c48478339f | trans=005e2ca7-1145-46db-be9f-21c48478339f | op=IoTAgentNGSI.GenericMiddlewares | srv=factory | subsrv=/robots | msg=Body:

{ "devices": [ { "device_id": "robot3", "entity_type": "Robot", "attributes": [ { "name": "Battery", "type": "number" } ], "lazy": [ { "name": "Message", "type": "string" } ], "commands": [ { "name": "Position", "type": "location" } ], "internal_attributes": { "lwm2mResourceMapping": { "Battery": { "objectType": 7392, "objectInstance": 0, "objectResource": 1 }, "Message": { "objectType": 7392, "objectInstance": 0, "objectResource": 2 }, "Position": { "objectType": 7392, "objectInstance": 0, "objectResource": 3 } } } } ] }

{ "devices": [ { "device_id": "robot4", "entity_type": "Robot", "attributes": [ { "name": "Battery", "type": "number" } ], "lazy": [ { "name": "Message", "type": "string" } ], "commands": [ { "name": "Position", "type": "location" } ], "internal_attributes": { "lwm2mResourceMapping": { "Battery": { "objectType": 7392, "objectInstance": 0, "objectResource": 1 }, "Message": { "objectType": 7392, "objectInstance": 0, "objectResource": 2 }, "Position": { "objectType": 7392, "objectInstance": 0, "objectResource": 3 } } } } ] }

| comp=IoTAgent time=2018-09-17T13:57:25.640Z | lvl=DEBUG | corr=98628877-e5dd-4a73-9a0b-67eee2c70b60 | trans=98628877-e5dd-4a73-9a0b-67eee2c70b60 | op=IoTAgentNGSI.DeviceProvisioning | srv=factory | subsrv=/robots | msg=Handling device provisioning request. | comp=IoTAgent time=2018-09-17T13:57:25.643Z | lvl=DEBUG | corr=98628877-e5dd-4a73-9a0b-67eee2c70b60 | trans=98628877-e5dd-4a73-9a0b-67eee2c70b60 | op=IoTAgentNGSI.InMemoryGroupRegister | srv=factory | subsrv=/robots | msg=Looking for device params ["service","subservice","type"] | comp=IoTAgent time=2018-09-17T13:57:25.644Z | lvl=DEBUG | corr=98628877-e5dd-4a73-9a0b-67eee2c70b60 | trans=98628877-e5dd-4a73-9a0b-67eee2c70b60 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Device name not found, falling back to deviceId:type [Robot:robot4] | comp=IoTAgent time=2018-09-17T13:57:25.644Z | lvl=DEBUG | corr=98628877-e5dd-4a73-9a0b-67eee2c70b60 | trans=98628877-e5dd-4a73-9a0b-67eee2c70b60 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Registering device into NGSI Service: { "id": "robot4", "type": "Robot", "name": "Robot:robot4", "service": "factory", "subservice": "/robots", "active": [ { "name": "Battery", "type": "number", "object_id": "Battery" } ], "staticAttributes": [], "lazy": [ { "name": "Message", "type": "string", "object_id": "Message" } ], "commands": [ { "name": "Position", "type": "location", "object_id": "Position" } ], "internalAttributes": { "lwm2mResourceMapping": { "Battery": { "objectType": 7392, "objectInstance": 0, "objectResource": 1 }, "Message": { "objectType": 7392, "objectInstance": 0, "objectResource": 2 }, "Position": { "objectType": 7392, "objectInstance": 0, "objectResource": 3 } } }, "internalId": null, "subscriptions": [] } | comp=IoTAgent time=2018-09-17T13:57:25.645Z | lvl=DEBUG | corr=98628877-e5dd-4a73-9a0b-67eee2c70b60 | trans=98628877-e5dd-4a73-9a0b-67eee2c70b60 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Sending device registrations to Context Broker at [http://orion:1026/NGSI9/registerContext] | comp=IoTAgent time=2018-09-17T13:57:25.645Z | lvl=DEBUG | corr=98628877-e5dd-4a73-9a0b-67eee2c70b60 | trans=98628877-e5dd-4a73-9a0b-67eee2c70b60 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Using the following request:

{ "url": "http://orion:1026/NGSI9/registerContext", "method": "POST", "json": { "contextRegistrations": [ { "entities": [ { "type": "Robot", "isPattern": "false", "id": "Robot:robot4" } ], "attributes": [ { "name": "Message", "type": "string", "isDomain": "false" }, { "name": "Position", "type": "location", "isDomain": "false" } ], "providingApplication": "http://localhost:4041" } ], "duration": "P1M" }, "headers": { "fiware-service": "factory", "fiware-servicepath": "/robots" } }

| comp=IoTAgent time=2018-09-17T13:57:25.665Z | lvl=DEBUG | corr=98628877-e5dd-4a73-9a0b-67eee2c70b60 | trans=98628877-e5dd-4a73-9a0b-67eee2c70b60 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Registration success. | comp=IoTAgent time=2018-09-17T13:57:25.666Z | lvl=DEBUG | corr=98628877-e5dd-4a73-9a0b-67eee2c70b60 | trans=98628877-e5dd-4a73-9a0b-67eee2c70b60 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Creating initial entity in the Context Broker: { "url": "http://orion:1026/v1/updateContext", "method": "POST", "json": { "contextElements": [ { "type": "Robot", "isPattern": "false", "id": "Robot:robot4", "attributes": [ { "name": "Battery", "type": "number", "value": " " }, { "name": "Position_status", "type": "commandStatus", "value": "UNKNOWN" }, { "name": "Position_info", "type": "commandResult", "value": " " } ] } ], "updateAction": "APPEND" }, "headers": { "fiware-service": "factory", "fiware-servicepath": "/robots", "fiware-correlator": "98628877-e5dd-4a73-9a0b-67eee2c70b60" } } | comp=IoTAgent time=2018-09-17T13:57:25.675Z | lvl=DEBUG | corr=98628877-e5dd-4a73-9a0b-67eee2c70b60 | trans=98628877-e5dd-4a73-9a0b-67eee2c70b60 | op=IoTAgentNGSI.DeviceService | srv=factory | subsrv=/robots | msg=Initial entity created successfully. | comp=IoTAgent time=2018-09-17T13:57:25.675Z | lvl=DEBUG | corr=98628877-e5dd-4a73-9a0b-67eee2c70b60 | trans=98628877-e5dd-4a73-9a0b-67eee2c70b60 | op=IoTAgentNGSI.InMemoryDeviceRegister | srv=factory | subsrv=/robots | msg=Storing device with id [robot4] and type [Robot] | comp=IoTAgent time=2018-09-17T13:57:25.675Z | lvl=DEBUG | corr=98628877-e5dd-4a73-9a0b-67eee2c70b60 | trans=98628877-e5dd-4a73-9a0b-67eee2c70b60 | op=IoTAgentNGSI.DeviceProvisioning | srv=factory | subsrv=/robots | msg=Device provisioning request succeeded | comp=IoTAgent time=2018-09-17T13:57:25.677Z | lvl=DEBUG | corr=98628877-e5dd-4a73-9a0b-67eee2c70b60 | trans=98628877-e5dd-4a73-9a0b-67eee2c70b60 | op=IoTAgentNGSI.DomainControl | srv=factory | subsrv=/robots | msg=response-time: 43 | comp=IoTAgent time=2018-09-17T15:36:01.049Z | lvl=DEBUG | corr=ced948b6-312b-42c7-9120-7b7943eb7caf | trans=ced948b6-312b-42c7-9120-7b7943eb7caf | op=IoTAgentNGSI.GenericMiddlewares | srv=n/a | subsrv=n/a | msg=Request for path [/iot/about] from [localhost:4041] | comp=IoTAgent time=2018-09-17T15:36:01.055Z | lvl=DEBUG | corr=ced948b6-312b-42c7-9120-7b7943eb7caf | trans=ced948b6-312b-42c7-9120-7b7943eb7caf | op=IoTAgentNGSI.DomainControl | srv=n/a | subsrv=n/a | msg=response-time: 8 | comp=IoTAgent time=2018-09-18T08:45:00.918Z | lvl=DEBUG | corr=b87362b9-2a07-48eb-b1d0-fffcdebc3d01 | trans=b87362b9-2a07-48eb-b1d0-fffcdebc3d01 | op=IoTAgentNGSI.GenericMiddlewares | srv=factory | subsrv=/robots | msg=Request for path [/iot/devices] from [localhost:4041] | comp=IoTAgent time=2018-09-18T08:45:00.924Z | lvl=DEBUG | corr=b87362b9-2a07-48eb-b1d0-fffcdebc3d01 | trans=b87362b9-2a07-48eb-b1d0-fffcdebc3d01 | op=IoTAgentNGSI.GenericMiddlewares | srv=factory | subsrv=/robots | msg=Body:

{ "devices": [ { "device_id": "robot1", "entity_type": "Robot", "attributes": [ { "name": "Battery", "type": "number" } ], "lazy": [ { "name": "Message", "type": "string" } ], "commands": [ { "name": "Position", "type": "location" } ], "internal_attributes": { "lwm2mResourceMapping": { "Battery": { "objectType": 7392, "objectInstance": 0, "objectResource": 1 }, "Message": { "objectType": 7392, "objectInstance": 0, "objectResource": 2 }, "Position": { "objectType": 7392, "objectInstance": 0, "objectResource": 3 } } } } ] }

arilwan commented 6 years ago

Hi @dcalvoalonso @fgalan

Above are the contents of both configuration and log output.

dcalvoalonso commented 6 years ago

@arilwan, the problem is in your docker-compose file. You must note that LWM2M runs over CoAP, which relies on UDP as the transport protocol.

Therefore, in the ports configuration of the IoT Agent, you must specify that port 5684 must be opened for UDP.

Please find your docker-compose file with this appropriate port configuration:

version: '2'

services:

  mongo:
    expose:
      - "27017"
    image: mongo:3.4
    command: --nojournal

  orion:
    command:  -dbhost mongo
    image: fiware/orion:latest
    links:
      - mongo
    ports:
      - "1026:1026"

  lightweightm2m-iotagent:
    expose:
      - "4041"
      - "5684"
    hostname: idas
    image: telefonicaiot/lightweightm2m-iotagent
    links:
      - orion      
    ports:
      - "4041:4041"
      - "5684:5684/udp"

Please, let us know if this works for you. :)

arilwan commented 6 years ago

Hi @dcalvoalonso ,

After running the command: _docker exec -ti [AGENT_CONTAINER_ID_HERE] nodemodules/lwm2m-node-lib/bin/iotagent-lwm2m-client.js

client now can connect to server, docker.compose.yml as it is.

dcalvoalonso commented 6 years ago

Yes, because you are running the lwm2m client from the own docker container and therefore you have direct access to the lwm2m server that is part of the IoT Agent. This particular solution will work to follow the documentation but not if you try to connect a real device. In that case, you will need to follow the solution posted in https://github.com/telefonicaid/lightweightm2m-iotagent/issues/114#issuecomment-422682085.

dcalvoalonso commented 6 years ago

@arilwan , as your problem seems to be solved, can we close the issue?

arilwan commented 6 years ago

@dcalvoalonso yes, please go ahead and close it, many thanks.

telefonicaid / lightweightm2m-iotagent

Registration Error on LWM2M Client #114

Error: