docs/sections/services/pm-mapper/troubleshooting.rst

   1 .. This work is licensed under a Creative Commons Attribution 4.0 International License.
   2 .. http://creativecommons.org/licenses/by/4.0
   3
   4
   5 Troubleshooting
   6 ===============
   7
   8 **NOTE**
   9
  10 According to **ONAP** logging policy, **PM Mapper** logs contain all required markers as well as service and client specific Mapped Diagnostic Context (later referred as MDC)
  11
  12 Default console log pattern:
  13
  14 ::
  15
  16         | %date{&quot;yyyy-MM-dd'T'HH:mm:ss.SSSXXX&quot;, UTC}\t| %thread\t| %highlight(%-5level)\t| %msg\t| %marker\t| %rootException\t| %mdc\t| %thread
  17
  18 A sample, fully qualified message implementing this pattern:
  19
  20 ::
  21
  22         | 2018-12-18T13:12:44.369Z       | p.dcae | DEBUG        | Client connection request received    | ENTRY         |       | RequestID=d7762b18-854c-4b8c-84aa-95762c6f8e62, InstanceID=9b9799ca-33a5-4f61-ba33-5c7bf7e72d07, InvocationID=b13d34ba-e1cd-4816-acda-706415308107, PartnerName=C=PL, ST=DL, L=Wroclaw, O=Nokia, OU=MANO, CN=dcaegen2-hvves-client, StatusCode=INPROGRESS, ClientIPAddress=192.168.0.9, ServerFQDN=a4ca8f96c7e5       | reactor-tcp-nio-2
  23
  24
  25 For simplicity, all log messages in this section are shortened to contain only:
  26     * logger name
  27     * log level
  28     * message
  29
  30 Error and warning logs contain also:
  31     * exception message
  32     * stack trace
  33
  34 **Do not rely on exact log messages or their presence, as they are often subject to change.**
  35
  36 Configuration errors
  37 --------------------
  38
  39 **Config binding service not responding**
  40
  41 ::
  42
  43
  44         2019-02-19T17:25:17.499Z        main    INFO    org.onap.dcaegen2.services.pmmapper.config.ConfigHandler                Fetching pm-mapper configuration from Configbinding Service             ENTRY
  45         2019-02-19T17:25:17.502Z        main    INFO    org.onap.dcaegen2.services.pmmapper.utils.RequestSender         ee5ff670-accd-4c30-8689-0a1d12491b51            INVOKE [ SYNCHRONOUS ]
  46         2019-02-19T17:25:17.509Z        main    INFO    org.onap.dcaegen2.services.pmmapper.utils.RequestSender         Sending:\n{X-ONAP-PartnerName=[pm-mapper], X-ONAP-InvocationID=[ee5ff670-accd-4c30-8689-0a1d12491b51], X-ONAP-RequestID=[2778e346-590a-4ade-8f45-358d1adf048b]}
  47         2019-02-19T17:25:18.515Z        main    INFO    org.onap.dcaegen2.services.pmmapper.utils.RequestSender         Sending:\n{X-ONAP-PartnerName=[pm-mapper], X-ONAP-InvocationID=[ee5ff670-accd-4c30-8689-0a1d12491b51], X-ONAP-RequestID=[2778e346-590a-4ade-8f45-358d1adf048b]}
  48         2019-02-19T17:25:19.516Z        main    INFO    org.onap.dcaegen2.services.pmmapper.utils.RequestSender         Sending:\n{X-ONAP-PartnerName=[pm-mapper], X-ONAP-InvocationID=[ee5ff670-accd-4c30-8689-0a1d12491b51], X-ONAP-RequestID=[2778e346-590a-4ade-8f45-358d1adf048b]}
  49         2019-02-19T17:25:20.518Z        main    INFO    org.onap.dcaegen2.services.pmmapper.utils.RequestSender         Sending:\n{X-ONAP-PartnerName=[pm-mapper], X-ONAP-InvocationID=[ee5ff670-accd-4c30-8689-0a1d12491b51], X-ONAP-RequestID=[2778e346-590a-4ade-8f45-358d1adf048b]}
  50         2019-02-19T17:25:21.519Z        main    INFO    org.onap.dcaegen2.services.pmmapper.utils.RequestSender         Sending:\n{X-ONAP-PartnerName=[pm-mapper], X-ONAP-InvocationID=[ee5ff670-accd-4c30-8689-0a1d12491b51], X-ONAP-RequestID=[2778e346-590a-4ade-8f45-358d1adf048b]}
  51         2019-02-19T17:25:21.520Z        main    INFO    org.onap.dcaegen2.services.pmmapper.config.ConfigHandler                Received pm-mapper configuration from ConfigBinding Service:\n          EXIT
  52         Exception in thread "main" org.onap.dcaegen2.services.pmmapper.exceptions.CBSServerError: Error connecting to Configbinding Service:
  53         at org.onap.dcaegen2.services.pmmapper.config.ConfigHandler.getMapperConfig(ConfigHandler.java:78)
  54         at org.onap.dcaegen2.services.pmmapper.App.main(App.java:58)
  55         caused by: java.net.ConnectException: Connection refused (Connection refused)
  56         at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
  57         at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
  58         at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
  59         at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
  60         at sun.net.www.protocol.http.HttpURLConnection$10.run(HttpURLConnection.java:1944)
  61         at sun.net.www.protocol.http.HttpURLConnection$10.run(HttpURLConnection.java:1939)
  62         at java.security.AccessController.doPrivileged(Native Method)
  63         at sun.net.www.protocol.http.HttpURLConnection.getChainedException(HttpURLConnection.java:1938)
  64         at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1508)
  65         at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1492)
  66         at java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:480)
  67         at java.net.HttpURLConnection.getResponseMessage(HttpURLConnection.java:546)
  68         at org.onap.dcaegen2.services.pmmapper.utils.RequestSender.send(RequestSender.java:80)
  69         at org.onap.dcaegen2.services.pmmapper.config.ConfigHandler.getMapperConfig(ConfigHandler.java:76)
  70         ... 1 more
  71         Caused by: java.net.ConnectException: Connection refused (Connection refused)
  72         at java.net.PlainSocketImpl.socketConnect(Native Method)
  73         at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350)
  74         at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
  75         at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
  76         at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
  77         at java.net.Socket.connect(Socket.java:589)
  78         t java.net.Socket.connect(Socket.java:538)
  79         at sun.net.NetworkClient.doConnect(NetworkClient.java:180)
  80         at sun.net.www.http.HttpClient.openServer(HttpClient.java:463)
  81         at sun.net.www.http.HttpClient.openServer(HttpClient.java:558)
  82         at sun.net.www.http.HttpClient.<init>(HttpClient.java:242)
  83         at sun.net.www.http.HttpClient.New(HttpClient.java:339)
  84         at sun.net.www.http.HttpClient.New(HttpClient.java:357)
  85         at sun.net.www.protocol.http.HttpURLConnection.getNewHttpClient(HttpURLConnection.java:1220)
  86         at sun.net.www.protocol.http.HttpURLConnection.plainConnect0(HttpURLConnection.java:1156)
  87         at sun.net.www.protocol.http.HttpURLConnection.plainConnect(HttpURLConnection.java:1050)
  88         at sun.net.www.protocol.http.HttpURLConnection.connect(HttpURLConnection.java:984)
  89         at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1564)
  90         at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1492)
  91         at org.onap.dcaegen2.services.pmmapper.utils.RequestSender.send(RequestSender.java:66)
  92
  93
  94 Make sure Config Binding Service is up and running and the **ip + port** combination is correct.
  95
  96 ====
  97
  98 **Missing configuration on Consul**
  99
 100 ::
 101
 102
 103         2019-02-19T17:36:32.664Z        main    INFO    org.onap.dcaegen2.services.pmmapper.config.ConfigHandler                Fetching pm-mapper configuration from Configbinding Service             ENTRY
 104         2019-02-19T17:36:32.666Z        main    INFO    org.onap.dcaegen2.services.pmmapper.utils.RequestSender         9fa1b84f-05ce-4e27-bba9-4ea477c1baa7            INVOKE [ SYNCHRONOUS ]
 105         2019-02-19T17:36:32.671Z        main    INFO    org.onap.dcaegen2.services.pmmapper.utils.RequestSender         Sending:\n{X-ONAP-PartnerName=[pm-mapper], X-ONAP-InvocationID=[9fa1b84f-05ce-4e27-bba9-4ea477c1baa7], X-ONAP-RequestID=[6e861d17-3f4b-4a2e-9ea8-a31bb9dbb7e8]}
 106         2019-02-19T17:36:32.696Z        main    INFO    org.onap.dcaegen2.services.pmmapper.utils.RequestSender         Received:\n{"pm-mapper-filter": "{ \"filters\":[]}", "3GPP.schema.file": "{\"3GPP_Schema\":\"./etc/3GPP_relaxed_schema.xsd\"}", "streams_subscribes": {"dmaap_subscriber": {"type": "data_router", "aaf_username": null, "aaf_password": null, "dmaap_infooooo": {"location": "csit-pmmapper", "delivery_url": "3gpppmmapper", "username": "username", "password": "password", "subscriber_id": "subsriber_id"}}}, "streams_publishes": {"pm_mapper_handle_out": {"type": "message_router", "aaf_password": null, "dmaap_info": {"topic_url": "https://message-router:3904/events/org.onap.dmaap.onapCSIT.pm_mapper", "client_role": "org.onap.dmaap.client.pub", "location": "csit-pmmapper", "client_id": null}, "aaf_username": null}}, "buscontroller_feed_subscription_endpoint": "http://dmaap-bc:8080/webapi/dr_subs", "services_calls": {}}
 107         2019-02-19T17:36:32.696Z        main    INFO    org.onap.dcaegen2.services.pmmapper.config.ConfigHandler                Received pm-mapper configuration from ConfigBinding Service:\n{"pm-mapper-filter": "{ \"filters\":[]}", "3GPP.schema.file": "{\"3GPP_Schema\":\"./etc/3GPP_relaxed_schema.xsd\"}", "streams_subscribes": {"dmaap_subscriber": {"type": "data_router", "aaf_username": null, "aaf_password": null, "dmaap_infooooo": {"location": "csit-pmmapper", "delivery_url": "3gpppmmapper", "username": "username", "password": "password", "subscriber_id": "subsriber_id"}}}, "streams_publishes": {"pm_mapper_handle_out": {"type": "message_router", "aaf_password": null, "dmaap_info": {"topic_url": "https://message-router:3904/events/org.onap.dmaap.onapCSIT.pm_mapper", "client_role": "org.onap.dmaap.client.pub", "location": "csit-pmmapper", "client_id": null}, "aaf_username": null}}, "buscontroller_feed_subscription_endpoint": "http://dmaap-bc:8080/webapi/dr_subs", "services_calls": {}}          EXIT
 108         Exception in thread "main" org.onap.dcaegen2.services.pmmapper.exceptions.MapperConfigException: Error parsing mapper configuration:
 109         {}{"pm-mapper-filter": "{ \"filters\":[]}", "3GPP.schema.file": "{\"3GPP_Schema\":\"./etc/3GPP_relaxed_schema.xsd\"}", "streams_subscribes": {"dmaap_subscriber": {"type": "data_router", "aaf_username": null, "aaf_password": null, "dmaap_infooooo": {"location": "csit-pmmapper", "delivery_url": "3gpppmmapper", "username": "username", "password": "password", "subscriber_id": "subsriber_id"}}}, "streams_publishes": {"pm_mapper_handle_out": {"type": "message_router", "aaf_password": null, "dmaap_info": {"topic_url": "https://message-router:3904/events/org.onap.dmaap.onapCSIT.pm_mapper", "client_role": "org.onap.dmaap.client.pub", "location": "csit-pmmapper", "client_id": null}, "aaf_username": null}}, "buscontroller_feed_subscription_endpoint": "http://dmaap-bc:8080/webapi/dr_subs", "services_calls": {}}
 110         at org.onap.dcaegen2.services.pmmapper.config.ConfigHandler.convertMapperConfigToObject(ConfigHandler.java:94)
 111         at org.onap.dcaegen2.services.pmmapper.config.ConfigHandler.getMapperConfig(ConfigHandler.java:83)
 112         at org.onap.dcaegen2.services.pmmapper.App.main(App.java:58)
 113         Caused by: com.google.gson.JsonParseException: Failed to check fields.
 114         at org.onap.dcaegen2.services.pmmapper.utils.RequiredFieldDeserializer.deserialize(RequiredFieldDeserializer.java:49)
 115         at com.google.gson.internal.bind.TreeTypeAdapter.read(TreeTypeAdapter.java:69)
 116         at com.google.gson.Gson.fromJson(Gson.java:927)
 117         at com.google.gson.Gson.fromJson(Gson.java:892)
 118         at com.google.gson.Gson.fromJson(Gson.java:841)
 119         at com.google.gson.Gson.fromJson(Gson.java:813)
 120         at org.onap.dcaegen2.services.pmmapper.config.ConfigHandler.convertMapperConfigToObject(ConfigHandler.java:92)
 121         ... 2 more
 122         Caused by: com.google.gson.JsonParseException: Field: 'busControllerFeedId', is required but not found.
 123         at org.onap.dcaegen2.services.pmmapper.utils.RequiredFieldDeserializer.deserialize(RequiredFieldDeserializer.java:46)
 124
 125
 126
 127 **PM Mapper** logs this information when connected to Consul, but cannot find a valid JSON configuration.
 128
 129