Found this in the log this morning (Rachio error)

cuboy29 · June 23, 2019, 3:11pm

Isn't this a bit excessive for an error 500 response? It's communicating fine again now so not sure what happened there.

app:12312019-06-23 07:12:30.240 am error_httpGet Response Exception | Status: 500 | Response: Apache Tomcat/8.0.30 - Error reportH1 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-size:22px;} H2 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-size:16px;} H3 {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;font-size:14px;} BODY {font-family:Tahoma,Arial,sans-serif;color:black;background-color:white;} B {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#525D76;} P {font-family:Tahoma,Arial,sans-serif;background:white;color:black;font-size:12px;}A {color : black;}A.name {color : black;}.line {height: 1px; background-color: #525D76; border: none;}HTTP Status 500 - could not inspect JDBC autocommit modetype Exception reportmessagecould not inspect JDBC autocommit modedescriptionThe server encountered an internal error that prevented it from fulfilling this request.exceptionorg.hibernate.exception.JDBCConnectionException: could not inspect JDBC autocommit mode org.hibernate.exception.internal.SQLStateConversionDelegate.convert(SQLStateConversionDelegate.java:132) org.hibernate.exception.internal.StandardSQLExceptionConverter.convert(StandardSQLExceptionConverter.java:49) org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:126) org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:112) org.hibernate.engine.jdbc.internal.LogicalConnectionImpl.isAutoCommit(LogicalConnectionImpl.java:325) org.hibernate.engine.transaction.internal.TransactionCoordinatorImpl.afterNonTransactionalQuery(TransactionCoordinatorImpl.java:199) org.hibernate.internal.SessionImpl.afterOperation(SessionImpl.java:503) org.hibernate.internal.SessionImpl.list(SessionImpl.java:1726) org.hibernate.internal.CriteriaImpl.list(CriteriaImpl.java:380) org.hibernate.internal.CriteriaImpl.uniqueResult(CriteriaImpl.java:402) com.rachio.persistence.dao.PropertyDao.findByName(PropertyDao.java:17) com.rachio.api.authentication.V3OpenAPIBridge.is3xApiLive(V3OpenAPIBridge.java:126) com.rachio.api.authentication.V3OpenAPIBridge.validateAccessToken(V3OpenAPIBridge.java:130) com.rachio.api.authentication.RestAuthenticationFilter.validateTokenAndGetAPIKeyValue(RestAuthenticationFilter.java:141) com.rachio.api.authentication.RestAuthenticationFilter.attemptAuthentication(RestAuthenticationFilter.java:82) org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:217) org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330) org.springframework.security.web.header.HeaderWriterFilter.doFilterInternal(HeaderWriterFilter.java:64) org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330) org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter.doFilterInternal(WebAsyncManagerIntegrationFilter.java:53) org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330) org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:91) org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330) org.springframework.security.web.FilterChainProxy.doFilterInternal(FilterChainProxy.java:213) org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:176) org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:346) org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:262) com.rachio.api.filter.CorsFilter.handleNonCORS(CorsFilter.java:433) com.rachio.api.filter.CorsFilter.doFilter(CorsFilter.java:172) org.springframework.orm.jpa.support.OpenEntityManagerInViewFilter.doFilterInternal(OpenEntityManagerInViewFilter.java:178) org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) com.rachio.api.filter.RestAuditFilter.doFilter(RestAuditFilter.java:106) root causeorg.postgresql.util.PSQLException: This connection has been closed. org.postgresql.jdbc2.AbstractJdbc2Connection.checkClosed(AbstractJdbc2Connection.java:842) org.postgresql.jdbc2.AbstractJdbc2Connection.getAutoCommit(AbstractJdbc2Connection.java:803) sun.reflect.GeneratedMethodAccessor85.invoke(Unknown Source) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) java.lang.reflect.Method.invoke(Method.java:498) org.apache.tomcat.jdbc.pool.ProxyConnection.invoke(ProxyConnection.java:126) org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:108) org.apache.tomcat.jdbc.pool.DisposableConnectionFacade.invoke(DisposableConnectionFacade.java:81) com.sun.proxy.$Proxy94.getAutoCommit(Unknown Source) org.hibernate.engine.jdbc.internal.LogicalConnectionImpl.isAutoCommit(LogicalConnectionImpl.java:322) org.hibernate.engine.transaction.internal.TransactionCoordinatorImpl.afterNonTransactionalQuery(TransactionCoordinatorImpl.java:199) org.hibernate.internal.SessionImpl.afterOperation(SessionImpl.java:503) org.hibernate.internal.SessionImpl.list(SessionImpl.java:1726) org.hibernate.internal.CriteriaImpl.list(CriteriaImpl.java:380) org.hibernate.internal.CriteriaImpl.uniqueResult(CriteriaImpl.java:402) com.rachio.persistence.dao.PropertyDao.findByName(PropertyDao.java:17) com.rachio.api.authentication.V3OpenAPIBridge.is3xApiLive(V3OpenAPIBridge.java:126) com.rachio.api.authentication.V3OpenAPIBridge.validateAccessToken(V3OpenAPIBridge.java:130) com.rachio.api.authentication.RestAuthenticationFilter.validateTokenAndGetAPIKeyValue(RestAuthenticationFilter.java:141) com.rachio.api.authentication.RestAuthenticationFilter.attemptAuthentication(RestAuthenticationFilter.java:82) org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:217) org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330) org.springframework.security.web.header.HeaderWriterFilter.doFilterInternal(HeaderWriterFilter.java:64) org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330) org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter.doFilterInternal(WebAsyncManagerIntegrationFilter.java:53) org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330) org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:91) org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330) org.springframework.security.web.FilterChainProxy.doFilterInternal(FilterChainProxy.java:213) org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:176) org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:346) org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:262) com.rachio.api.filter.CorsFilter.handleNonCORS(CorsFilter.java:433) com.rachio.api.filter.CorsFilter.doFilter(CorsFilter.java:172) org.springframework.orm.jpa.support.OpenEntityManagerInViewFilter.doFilterInternal(OpenEntityManagerInViewFilter.java:178) org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) com.rachio.api.filter.RestAuditFilter.doFilter(RestAuditFilter.java:106)

djgutheinz · June 23, 2019, 4:35pm

Same here. Started this AM. Unit works OK through the Rachio App. However, Hubitat can not log in.
Dave

Update: Did a reinstall and it worked OK, so problem is not pervasive. I would also try the Rachio APP and make sure all is OK there (I had problems there yesterday).

You may want to try the manage login in the Hubitat Application.

Dave

stephack · June 23, 2019, 5:14pm

I had an error last week and had to reinstall my Rachio integration to correct it as well. Not the same error as it was much shorter. It seemed like an authentication error is I remember correctly. If I get some time today, I will dig into my saved logs and post the error.

cuboy29 · June 23, 2019, 5:27pm

I didn't have to do anything to get mine working again
I went into the app and it was showing everything still OK. Must have been an issue with Rachio cloud.

djgutheinz · June 23, 2019, 5:32pm

As far as the error being excessive. I believe that the error is unparsed on the 500 error. Takes no cycles and assures capture of the information to correct, if required.

stephack · June 23, 2019, 5:58pm

@bobbyD

So I tried to open my past log archive via Excel and it's generating errors...probably because it couldnt't properly parse all the data listed in the OP...I'm getting the same errors today btw. I am able to open the text files to view all the logs manually. Here' the history of my Rachio errors:

It seems that my errors started on the afternoon of the 13th and went on for days. See below for reoccurring error.

error||app||922||Rachio Integration||2019-06-16 07:17:08.906||_httpGet Response Exception | Status: 401 | Response: [errors:[[message:The client is not authorized.]]]
info||app||922||Rachio Integration||2019-06-16 07:17:08.925||Updating (3) child devices...
warn||app||922||Rachio Integration||2019-06-16 07:17:08.943||pollChildren cannot update children because it is missing the required parameters...
warn||app||922||Rachio Integration||2019-06-16 07:17:08.944||pollChildren cannot update children because it is missing the required parameters...
warn||app||922||Rachio Integration||2019-06-16 07:17:08.946||pollChildren cannot update children because it is missing the required parameters...

On the 16 there was a slightly different error.

error||app||922||Rachio Integration||2019-06-16 21:11:13.624||_httpGet Response Exception | Status: 401 | Response: [errors:[[message:The client is not authorized.]]]
error||app||922||Rachio Integration||2019-06-16 21:11:13.629||No UserId was found. Exiting
error||app||922||Rachio Integration||2019-06-16 21:11:18.316||webhookHttp Error:[errors:[[message:The client is not authorized.]]]
error||app||922||Rachio Integration||2019-06-16 21:11:18.316||webhookHttp Error:[errors:[[message:The client is not authorized.]]]
error||app||922||Rachio Integration||2019-06-16 21:11:18.316||webhookHttp Error:[errors:[[message:The client is not authorized.]]]
error||app||3203||Rachio Integration||2019-06-16 21:13:18.240||webhookHttp Error:null
error||app||3203||Rachio Integration||2019-06-16 21:13:18.240||webhookHttp Error:null

On the 18th there was another variance. I don't remeber when I removed and recreated the Rachio integration but it was probably on the 16th or the 18th.

error||app||3203||Rachio Integration||2019-06-18 20:26:10.369||_httpGet exception: api.rach.io: Name or service not known

Today I'm getting the 500 error seen in the OP.