Digital Developer Conference: a FREE half-day online conference focused on AI & Cloud – North America: Nov 2 – India: Nov 9 – Europe: Nov 14 – Asia Nov 23 Register now

Refine your search by using the following advanced search options.

Criteria	Usage
Questions with keyword1 or keyword2	keyword1 keyword2
Questions with a mandatory word, e.g. keyword2	keyword1 +keyword2
Questions excluding a word, e.g. keyword2	keyword1 -keyword2
Questions with keyword(s) and a specific tag	keyword1 [tag1]
Questions with keyword(s) and either of two or more specific tags	keyword1 [tag1] [tag2]
To search for all posts by a user or all posts with a specific tag, start typing and choose from the suggestion list. Do not use a plus or minus sign with a tag, e.g., +[tag1].

Ask a question

500 Error: Failed to establish a backside connection Error while using mqlight service

Question by rohitihare (16) | Jul 29, 2015 at 09:37 AM connection ibmcloud error 500 mqlight backside

Hi,

I am using mqlight service with my application. the code was running fine yesterday. but today the response is hanging after establishing connection with mqlight. an after some time it throws 500 error: Failed to establish a backside connection Error while using mqlight service

cf logs are as follows

2015-07-29T18:14:32.04+0530 [App/0] OUT [INFO ] CWWKF0008I: Feature update completed in 5.544 seconds. 2015-07-29T18:14:32.04+0530 [App/0] OUT [AUDIT ] CWWKF0011I: The server defaultServer is ready to run a smarter planet. 2015-07-29T18:14:53.56+0530 [App/0] OUT [INFO ] SESN8501I: The session manager did not find a persistent storage location; HttpSession objects will be stored in the local application server's memory. 2015-07-29T18:14:53.56+0530 [App/0] OUT [INFO ] SESN0176I: A new session context will be created for application key default_host/ 2015-07-29T18:14:53.58+0530 [App/0] OUT [INFO ] SESN0172I: The session manager is using the Java default SecureRandom implementation for session ID generation. 2015-07-29T18:14:53.61+0530 [App/0] OUT [INFO ] JSPG8502I: The value of the JSP attribute jdkSourceLevel is "15". 2015-07-29T18:14:53.90+0530 [App/0] OUT [INFO ] The following JAX-RS application has been processed: com.ibm.ws.jaxrs.webcontainer.JAXRSDefaultApplicationSubclas sProxy 2015-07-29T18:14:53.90+0530 [App/0] OUT [INFO ] The server has registered the JAX-RS resource class com.ibm.cloudoe.samples.HelloResource with @Path(/hello). 2015-07-29T18:14:53.90+0530 [App/0] OUT [INFO ] There are no custom JAX-RS providers defined in the application. 2015-07-29T18:14:54.46+0530 [App/0] OUT [INFO ] SRVE0242I: [myapp] [/] [javax.ws.rs.core.Application]: Initialization successful. 2015-07-29T18:14:54.47+0530 [App/0] OUT [INFO ] SRVE0242I: [myapp] [/] [WorkStarter]: Initialization successful. 2015-07-29T18:14:54.59+0530 [App/0] ERR [err] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". 2015-07-29T18:14:54.59+0530 [App/0] ERR [err] SLF4J: Defaulting to no-operation (NOP) logger implementation 2015-07-29T18:14:54.59+0530 [App/0] ERR [err] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. 2015-07-29T18:14:54.60+0530 [App/0] OUT chaaalaaa 2015-07-29T18:19:53.34+0530 [RTR/2] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:12:44:53 +0000] "POST /api/hello/send HTTP/1.1" 502 27 0 "-" "Mozilla/5.0 (Win dows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.134:50789 x_forwarded_for:"-" vcap_request_id:b3bfebe9-fb5e-475e- 48f3-485d7f2c63c3 response_time:300.001550706 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:36:20.31+0530 [App/0] OUT chaaalaaa 2015-07-29T18:36:51.85+0530 [API/8] OUT Updated app with guid 6f1f438a-28f2-4075-80b0-929ff9bc5903 ({"state"=>"STOPPED"}) 2015-07-29T18:36:53.45+0530 [API/6] OUT Updated app with guid 6f1f438a-28f2-4075-80b0-929ff9bc5903 ({"state"=>"STARTED"}) 2015-07-29T18:36:53.45+0530 [DEA/16] OUT Starting app instance (index 0) with guid 6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:37:02.14+0530 [RTR/3] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:13:06:20 +0000] "POST /api/hello/send HTTP/1.1" 502 27 0 "-" "Mozilla/5.0 (Win dows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.134:55744 x_forwarded_for:"-" vcap_request_id:b5bf7df0-ad2c-4cb0- 62bb-2ddbb449b71b response_time:41.832869355 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:37:03.13+0530 [DEA/66] OUT Stopping app instance (index 0) with guid 6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:37:03.13+0530 [DEA/66] OUT Stopped app instance (index 0) with guid 6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:37:07.33+0530 [App/0] OUT Launching defaultServer (WebSphere Application Server 2015.6.0.0/wlp-1.0.9.cl50620150610-1749) on IBM J9 VM, version pxa 6470_27sr3ifx-20150617_01 (SR3+IV72914+IV73934 SR3+IV72914+IV73934) (en_US) 2015-07-29T18:37:07.52+0530 [App/0] OUT [AUDIT ] CWWKE0001I: The server defaultServer has been launched. 2015-07-29T18:37:09.55+0530 [App/0] OUT [AUDIT ] CWWKG0028A: Processing included configuration resource: /home/vcap/app/wlp/usr/servers/defaultServer/runtime-vars .xml 2015-07-29T18:37:12.03+0530 [App/0] OUT [INFO ] CWWKE0002I: The kernel started after 5.819 seconds 2015-07-29T18:37:12.33+0530 [App/0] OUT [INFO ] CWWKF0007I: Feature update started. 2015-07-29T18:37:14.79+0530 [App/0] OUT [INFO ] CWWKO0219I: TCP Channel defaultHttpEndpoint has been started and is now listening for requests on host * (IPv6) port 61140. 2015-07-29T18:37:15.46+0530 [App/0] OUT [INFO ] CWSCX0122I: Register management Bean provider: com.ibm.ws.cloudoe.management.client.provider.dump.JavaDumpBeanPro vider@65bce416. 2015-07-29T18:37:15.47+0530 [App/0] OUT [INFO ] CWSCX0122I: Register management Bean provider: com.ibm.ws.cloudoe.management.client.provider.logging.LibertyLoggi ngBeanProvider@1a62e59. 2015-07-29T18:37:15.66+0530 [App/0] OUT [INFO ] SRVE0169I: Loading Web Module: com.ibm.ws.cloudoe.management.client.liberty.connector. 2015-07-29T18:37:15.66+0530 [App/0] OUT [INFO ] SRVE0250I: Web Module com.ibm.ws.cloudoe.management.client.liberty.connector has been bound to default_host. 2015-07-29T18:37:15.67+0530 [App/0] OUT [AUDIT ] CWWKT0016I: Web application available (default_host): http://18rvsr8s9f3:61140/IBMMGMTRest/ 2015-07-29T18:37:17.05+0530 [App/0] OUT [INFO ] WMQ messaging : '7.5.0.2-p750-002-130627'. 2015-07-29T18:37:17.52+0530 [App/0] OUT [INFO ] CWWKZ0018I: Starting application myapp. 2015-07-29T18:37:18.56+0530 [App/0] OUT [INFO ] SRVE0169I: Loading Web Module: Hello Java. 2015-07-29T18:37:18.56+0530 [App/0] OUT [INFO ] SRVE0250I: Web Module Hello Java has been bound to default_host. 2015-07-29T18:37:18.56+0530 [App/0] OUT [AUDIT ] CWWKT0016I: Web application available (default_host): http://18rvsr8s9f3:61140/ 2015-07-29T18:37:18.57+0530 [App/0] OUT [AUDIT ] CWWKZ0001I: Application myapp started in 1.043 seconds. 2015-07-29T18:37:18.63+0530 [App/0] OUT [AUDIT ] CWWKF0012I: The server installed the following features: [json-1.0, jpa-2.0, beanValidation-1.0, icap:managementC onnector-1.0, jdbc-4.0, managedBeans-1.0, cloudAutowiring-1.0, jsf-2.0, jsp-2.2, servlet-3.0, jaxrs-1.1, jndi-1.0, wmqJmsClient-1.1, appState-1.0, ejbLite-3.1, cdi-1.0].

2015-07-29T18:37:18.63+0530 [App/0] OUT [INFO ] CWWKF0008I: Feature update completed in 6.596 seconds. 2015-07-29T18:37:18.63+0530 [App/0] OUT [AUDIT ] CWWKF0011I: The server defaultServer is ready to run a smarter planet. 2015-07-29T18:38:49.15+0530 [App/0] OUT [INFO ] SESN8501I: The session manager did not find a persistent storage location; HttpSession objects will be stored in the local application server's memory. 2015-07-29T18:38:49.15+0530 [App/0] OUT [INFO ] SESN0176I: A new session context will be created for application key default_host/ 2015-07-29T18:38:49.17+0530 [App/0] OUT [INFO ] SESN0172I: The session manager is using the Java default SecureRandom implementation for session ID generation. 2015-07-29T18:38:49.23+0530 [App/0] OUT [INFO ] JSPG8502I: The value of the JSP attribute jdkSourceLevel is "15". 2015-07-29T18:38:49.52+0530 [App/0] OUT [INFO ] The following JAX-RS application has been processed: com.ibm.ws.jaxrs.webcontainer.JAXRSDefaultApplicationSubclas sProxy 2015-07-29T18:38:49.52+0530 [App/0] OUT [INFO ] The server has registered the JAX-RS resource class com.ibm.cloudoe.samples.HelloResource with @Path(/hello). 2015-07-29T18:38:49.53+0530 [App/0] OUT [INFO ] There are no custom JAX-RS providers defined in the application. 2015-07-29T18:38:50.11+0530 [App/0] OUT [INFO ] SRVE0242I: [myapp] [/] [javax.ws.rs.core.Application]: Initialization successful. 2015-07-29T18:38:50.14+0530 [App/0] OUT [INFO ] SRVE0242I: [myapp] [/] [WorkStarter]: Initialization successful. 2015-07-29T18:38:50.26+0530 [RTR/1] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:13:08:48 +0000] "GET / HTTP/1.1" 200 0 762 "-" "Mozilla/5.0 (Windows NT 6.1; W OW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.132:56051 x_forwarded_for:"-" vcap_request_id:f1886276-03f7-43b2-6298-b44e25edd cfe response_time:1.578340609 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:38:50.56+0530 [RTR/3] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:13:08:50 +0000] "GET /style.css HTTP/1.1" 200 0 1157 "http://chatapp8513.eu-gb .mybluemix.net/" "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.132:39067 x_forwarded_for:"-" v cap_request_id:217dcac2-53b8-49de-5de0-2fd47006b7c6 response_time:0.008490497 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:38:50.76+0530 [RTR/2] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:13:08:50 +0000] "GET /images/newapp-icon.png HTTP/1.1" 200 0 13899 "http://cha tapp8513.eu-gb.mybluemix.net/" "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.132:36600 x_forwa rded_for:"-" vcap_request_id:ab352227-4195-418b-7274-73e3389c288c response_time:0.009246052 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:38:51.15+0530 [RTR/0] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:13:08:51 +0000] "GET /api/hello HTTP/1.1" 405 0 0 "http://chatapp8513.eu-gb.my bluemix.net/" "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.134:14318 x_forwarded_for:"-" vcap _request_id:1a8e127e-58c8-42a7-69ce-90f1137c5a6a response_time:0.066636190 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:38:59.35+0530 [App/0] ERR [err] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". 2015-07-29T18:38:59.35+0530 [App/0] ERR [err] SLF4J: Defaulting to no-operation (NOP) logger implementation 2015-07-29T18:38:59.35+0530 [App/0] ERR [err] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. 2015-07-29T18:38:59.36+0530 [App/0] OUT chaaalaaa 2015-07-29T18:39:07.93+0530 [RTR/3] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:13:09:07 +0000] "GET /api/hello HTTP/1.1" 405 0 0 "http://chatapp8513.eu-gb.my bluemix.net/" "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.132:38566 x_forwarded_for:"-" vcap _request_id:ed89fd85-9fbb-4768-7e76-1cd29f60ea92 response_time:0.004896422 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:39:09.22+0530 [RTR/1] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:13:09:09 +0000] "GET /api/hello HTTP/1.1" 405 0 0 "http://chatapp8513.eu-gb.my bluemix.net/" "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.132:56051 x_forwarded_for:"-" vcap _request_id:a0089d4e-485b-4b12-6581-25828929593e response_time:0.004537760 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:39:09.90+0530 [RTR/0] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:13:09:09 +0000] "GET /api/hello HTTP/1.1" 405 0 0 "http://chatapp8513.eu-gb.my bluemix.net/" "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.132:38205 x_forwarded_for:"-" vcap _request_id:9fc24b8f-c3c9-445d-73db-df833a083399 response_time:0.007050119 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:39:10.12+0530 [RTR/2] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:13:09:10 +0000] "GET /api/hello HTTP/1.1" 405 0 0 "http://chatapp8513.eu-gb.my bluemix.net/" "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.132:36409 x_forwarded_for:"-" vcap _request_id:db04b6c2-6f8a-4c45-633a-00f6a4e484c4 response_time:0.005154477 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:39:10.33+0530 [RTR/0] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:13:09:10 +0000] "GET /api/hello HTTP/1.1" 405 0 0 "http://chatapp8513.eu-gb.my bluemix.net/" "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.132:38205 x_forwarded_for:"-" vcap _request_id:df6aeda8-497c-4266-54e1-dd81f064a942 response_time:0.004529329 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:39:10.91+0530 [RTR/0] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:13:09:10 +0000] "GET /api/hello HTTP/1.1" 405 0 0 "http://chatapp8513.eu-gb.my bluemix.net/" "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.132:38327 x_forwarded_for:"-" vcap _request_id:790c0c54-7e1a-4672-5a9e-f0fd49f0da6a response_time:0.005226950 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T18:43:59.34+0530 [RTR/1] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:13:08:59 +0000] "POST /api/hello/send HTTP/1.1" 502 27 0 "-" "Mozilla/5.0 (Win dows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.134:58348 x_forwarded_for:"-" vcap_request_id:57b4539b-c8a1-4445- 42c1-ea194a07cd2e response_time:300.001497892 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T19:06:47.84+0530 [App/0] OUT chaaalaaa 2015-07-29T19:11:47.84+0530 [RTR/3] OUT chatapp8513.eu-gb.mybluemix.net - [29/07/2015:13:36:47 +0000] "POST /api/hello/send HTTP/1.1" 502 27 0 "-" "Mozilla/5.0 (Win dows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.107 Safari/537.36" 5.10.124.133:49439 x_forwarded_for:"-" vcap_request_id:2338fb54-0df8-42c9- 6b10-28555a4a0b08 response_time:300.001990802 app_id:6f1f438a-28f2-4075-80b0-929ff9bc5903 2015-07-29T19:13:47.51+0530 [App/0] OUT chaaalaaa

0 Show 6

ValerieLampkin (2820) ♦ Jul 29, 2015 at 07:34 AM 0

Can you provide more info from your "cf logs --recent" output?

rohitihare (16) ValerieLampkin (2820) ♦ Jul 29, 2015 at 08:50 AM 1

@valerielampkin edited the question and added logs

Simon G (206) rohitihare (16) Jul 29, 2015 at 09:57 AM 0

Hi @rohitihare,

Your logs don't show an obvious problem, apart from the 502 HTTP code returning.

There are no reported problems/outages in the MQ Light service for London, so to progress we'd need to know your connectionLookupURI from VCAP_SERVICES for the service to query the service instance you're connecting to. (Easiest place to get this from is in the Bluemix web dashboard, going to your application, and showing the credentials from the service link.

Be careful not to post the username/password here. If you'd prefer, you can send them to us directly at MQLIGHTS "at" uk.ibm.com (both @valerielampkin and myself will be able to see it)

Show more comments

rohitihare (16) Jul 30, 2015 at 03:22 AM 0

@valerielampkin @simon-g , code was working on day before yesterday... also i have tried running code from another bluemix account of mine with new mq light service credentials, but the problem still persists... the execution stuck at the following line of code MessageProducer producer = jmsSess.createProducer(createQueue);

ValerieLampkin (2820) ♦ rohitihare (16) Jul 31, 2015 at 02:27 PM 0

Would you be able to send us your code, for us to see if anything else looks unusual?

Gathering trace from the application server will also help here, could you enable trace by editing your server.xml file to have the following:

After redeploying your app, recreate the failure, then gather the trace.log file from the server logs directory, zip it up and send it to us.

500 Error: Failed to establish a backside connection Error while using mqlight service

0 answers

Follow this question

500 Error: Failed to establish a backside connection Error while using mqlight service

0 answers

Follow this question

Related questions