Cannot create PoolableConnectionFactory

3,292 views

Skip to first unread message

Matthew McLarty

unread,

Aug 9, 2013, 11:02:46 AM8/9/13

to dot...@googlegroups.com

I'm doing a new install of 2.3 and I know I must be doing something wrong here but I cannot figure out what...

When I run startup.sh I'm seeing this error in the logs.

[09/08/13 10:33:24:469 EDT] ERROR db.DbConnectionFactory: ---------- DBConnectionFactory: error : org.apache.tomcat.dbcp.dbcp.SQLNestedException: Cannot create PoolableConnectionFactory (Communications link failure

I've confirmed that the mysql user has access to the database

[root@localhost dotserver]# mysql -u dotcms -p

Enter password:

Welcome to the MySQL monitor. Commands end with ; or \g.

Your MySQL connection id is 12

Server version: 5.5.33 MySQL Community Server (GPL) by Remi

Oracle is a registered trademark of Oracle Corporation and/or its

affiliates. Other names may be trademarks of their respective

owners.

Type 'help;' or '\h' for help. Type '\c' to clear the current input statement.

mysql> use dotcms

Database changed

mysql> select 1

-> ;

+---+

| 1 |

+---+

| 1 |

+---+

1 row in set (0.00 sec)

I'm using the same information in my ROOT.xml file that I used to confirm that the user has access.

<Resource name="jdbc/dotCMSPool" auth="Container"

type="javax.sql.DataSource" driverClassName="com.mysql.jdbc.Driver"

url="jdbc:mysql://localhost/dotcms?characterEncoding=UTF-8"

username="dotcms" password="*******" maxActive="60" maxIdle="10" maxWait="60000"

removeAbandoned="true" removeAbandonedTimeout="60" logAbandoned="true"

validationQuery="SELECT 1" testOnBorrow="true"/>

Is there something I'm missing?

Jorge Urdaneta

unread,

Aug 9, 2013, 11:07:28 AM8/9/13

to dot...@googlegroups.com

I have seen it before. By default my.conf can have something like skip-network

It has the unix socket that your mysql shell is using but it doesn't open the port 3306

--
You received this message because you are subscribed to the Google Groups "dotCMS User Group" group.
To unsubscribe from this group and stop receiving emails from it, send an email to dotcms+un...@googlegroups.com.
To post to this group, send email to dot...@googlegroups.com.
Visit this group at http://groups.google.com/group/dotcms.
For more options, visit https://groups.google.com/groups/opt_out.

Matthew McLarty

unread,

Aug 9, 2013, 11:18:45 AM8/9/13

to dot...@googlegroups.com

Thanks, Jorge. I checked my.cnf and added the port and bind address and restarted mysqld service but I still get the same error. Any other ideas? :)

mysqld]

datadir=/var/lib/mysql

socket=/var/lib/mysql/mysql.sock

port = 3306

bind-address = 127.0.0.1

[client]

port = 3306

Matthew McLarty

unread,

Aug 9, 2013, 11:24:03 AM8/9/13

to dot...@googlegroups.com

I added rules to iptables as well, just in case. Still no go. :(

iptables -I INPUT -p tcp --dport 3306 -m state --state NEW,ESTABLISHED -j ACCEPT

iptables -I OUTPUT -p tcp --sport 3306 -m state --state ESTABLISHED -j ACCEPT

Am I using the wrong ROOT.xml file? Is there a different one from /tomcat/conf/Catalina/localhost/ROOT.xml?

Jorge Urdaneta

unread,

Aug 9, 2013, 11:26:01 AM8/9/13

to dot...@googlegroups.com

Try using the mysql shell with tcp/ip

mysql -h localhost -u dotcms -p

Matthew McLarty

unread,

Aug 9, 2013, 11:29:06 AM8/9/13

to dot...@googlegroups.com

That seems to work but I still can't seem to startup dotcms.

[root@localhost dotserver]# mysql -h localhost -u dotcms -p

Enter password:

Welcome to the MySQL monitor. Commands end with ; or \g.

Your MySQL connection id is 2

Server version: 5.5.33 MySQL Community Server (GPL) by Remi

Oracle is a registered trademark of Oracle Corporation and/or its

affiliates. Other names may be trademarks of their respective

owners.

Type 'help;' or '\h' for help. Type '\c' to clear the current input statement.

mysql> use dotcms

Database changed

mysql> select 1

-> ;

+---+

| 1 |

+---+

| 1 |

+---+

1 row in set (0.00 sec)

Jorge Urdaneta

unread,

Aug 9, 2013, 11:37:06 AM8/9/13

to dot...@googlegroups.com

make sure the mysql entry in ROOT.xml is the only uncommented.

Note that we ship with postgresql section uncommented and other commented

Matthew McLarty

unread,

Aug 9, 2013, 11:44:24 AM8/9/13

to dot...@googlegroups.com

I removed everything but the mysql section, just for good measure. Same problem.

<Resource name="jdbc/dotCMSPool" auth="Container"

type="javax.sql.DataSource" driverClassName="com.mysql.jdbc.Driver"

url="jdbc:mysql://localhost/dotcms?characterEncoding=UTF-8"

username="root" password="*******************" maxActive="60" maxIdle="10" maxWait="60000"

removeAbandoned="true" removeAbandonedTimeout="60" logAbandoned="true"

validationQuery="SELECT 1" testOnBorrow="true"/>

</Context>

Will the entire dotcms.log file help maybe?

[08/08/13 17:05:18:697 EDT] INFO core.AprLifecycleListener: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib

[08/08/13 17:05:19:062 EDT] INFO http11.Http11Protocol: Initializing Coyote HTTP/1.1 on http-80

[08/08/13 17:05:19:062 EDT] INFO startup.Catalina: Initialization processed in 816 ms

[08/08/13 17:05:19:128 EDT] INFO core.StandardService: Starting service Catalina

[08/08/13 17:05:19:129 EDT] INFO core.StandardEngine: Starting Servlet Engine: Apache Tomcat/6.0.26

[08/08/13 17:05:19:136 EDT] INFO startup.HostConfig: Deploying configuration descriptor ROOT.xml

[08/08/13 17:05:21:494 EDT] INFO util.AsciiArt:

[08/08/13 17:05:21:506 EDT] INFO util.AsciiArt:

[08/08/13 17:05:21:506 EDT] INFO util.AsciiArt: OOOO 7777777 7777 7777 77777777

[08/08/13 17:05:21:507 EDT] INFO util.AsciiArt: OOOO OO 777777777 77777 77777 77777777

[08/08/13 17:05:21:507 EDT] INFO util.AsciiArt: OOOO OOO 77777 777777 77777 7777

[08/08/13 17:05:21:507 EDT] INFO util.AsciiArt: OOOOOOOOOO OOOOOOOO OOOOOOOOO 7777 7777777 777777 7777

[08/08/13 17:05:21:507 EDT] INFO util.AsciiArt: OOOO OOOOO OOOO OOOO OOOO 7777 7777777 7777777 777777

[08/08/13 17:05:21:507 EDT] INFO util.AsciiArt: OOOO OOOO OOO OOOO OOOO 7777 77777777 777 7777 777777

[08/08/13 17:05:21:507 EDT] INFO util.AsciiArt: OOOO OOOO OOOO OOOO OOOO 7777 777 777 777 7777 7777

[08/08/13 17:05:21:507 EDT] INFO util.AsciiArt: OOOO OOOO OOO OOOO OOOO 77777 777 777777 7777 7777

[08/08/13 17:05:21:507 EDT] INFO util.AsciiArt: OOOO OOOO OOOO OOO OOOO 77777 777 7777 7777 7777

[08/08/13 17:05:21:507 EDT] INFO util.AsciiArt: OOOOOOOOOO OOOOOOOO OOOOO 777777777 777 7777 7777 777777777

[08/08/13 17:05:21:508 EDT] INFO util.AsciiArt:

[08/08/13 17:05:21:508 EDT] INFO util.AsciiArt: Content Management System

[08/08/13 17:05:21:508 EDT] INFO util.AsciiArt:

[08/08/13 17:05:22:508 EDT] INFO util.Config: Initializing properties reader.

[08/08/13 17:05:22:510 EDT] INFO util.Config: Loading dotCMS Properties...

[08/08/13 17:05:22:560 EDT] INFO util.Config: Assigned default refresh interval: 5 minutes.

[08/08/13 17:05:22:561 EDT] INFO util.Config: dotCMS Properties Loaded

[08/08/13 17:05:22:566 EDT] INFO util.OSGIUtil: Felix dir: /opt/dotcms/dotserver/tomcat/webapps/../../dotCMS/WEB-INF/felix

[08/08/13 17:05:22:579 EDT] INFO util.OSGIUtil: Loading property felix.auto.deploy.action=install,start

[08/08/13 17:05:22:580 EDT] INFO util.OSGIUtil: Loading property felix.fileinstall.start.level=2

[08/08/13 17:05:22:580 EDT] INFO util.OSGIUtil: Loading property org.osgi.framework.startlevel.beginning=2

[08/08/13 17:05:22:581 EDT] INFO util.OSGIUtil: Loading property org.osgi.framework.storage.clean=onFirstInit

[08/08/13 17:05:22:581 EDT] INFO util.OSGIUtil: Loading property felix.log.level=4

[08/08/13 17:05:22:611 EDT] INFO util.OSGIUtil: Loading Factory org.apache.felix.framework.FrameworkFactory

[08/08/13 17:05:23:660 EDT] INFO util.OSGIUtil: osgi felix framework started

[08/08/13 17:05:23:667 EDT] INFO server.CMISServiceFactoryImpl: Initializing in-memory repository...

[08/08/13 17:05:23:703 EDT] INFO server.CMISServiceFactoryImpl: ...initialized in-memory repository.

[08/08/13 17:05:23:703 EDT] INFO impl.CmisRepositoryContextListener: Initialized Services Factory: com.dotcms.enterprise.cmis.server.CMISServiceFactoryImpl

[08/08/13 17:05:23:774 EDT] INFO util.SystemProperties: Loading file:/opt/dotcms/dotserver/dotCMS/WEB-INF/classes/system.properties

[08/08/13 17:05:23:775 EDT] INFO util.SystemProperties: Loading file:/opt/dotcms/dotserver/dotCMS/WEB-INF/classes/system-ext.properties

[08/08/13 17:05:23:820 EDT] INFO util.PropsLoader: Loading file:/opt/dotcms/dotserver/dotCMS/WEB-INF/classes/portal.properties

[08/08/13 17:05:23:821 EDT] INFO util.PropsLoader: Loading file:/opt/dotcms/dotserver/dotCMS/WEB-INF/classes/portal-ext.properties

[08/08/13 17:05:24:348 EDT] INFO business.CacheLocator: loading cache administrator: com.dotmarketing.business.DotGuavaCacheAdministratorImpl

[08/08/13 17:05:24:358 EDT] INFO business.ESDistributedJournalFactoryImpl: Server IDs configured: [1]

[08/08/13 17:05:24:358 EDT] INFO util.ConfigUtils: Using configured hostname: 1

[08/08/13 17:05:25:052 EDT] ERROR db.DbConnectionFactory: ---------- DBConnectionFactory: error : org.apache.tomcat.dbcp.dbcp.SQLNestedException: Cannot create PoolableConnectionFactory (Communications link failure

The last packet sent successfully to the server was 0 milliseconds ago. The driver has not received any packets from the server.)

[08/08/13 17:05:25:053 EDT] FATAL business.CacheLocator: Unable to load Cache Admin:com.dotmarketing.business.DotGuavaCacheAdministratorImpl

[08/08/13 17:05:25:082 EDT] ERROR db.DbConnectionFactory: ---------- DBConnectionFactory: error : org.apache.tomcat.dbcp.dbcp.SQLNestedException: Cannot create PoolableConnectionFactory (Communications link failure

The last packet sent successfully to the server was 0 milliseconds ago. The driver has not received any packets from the server.)

[08/08/13 17:05:25:083 EDT] ERROR [localhost].[/]: Exception starting filter URLMapFilter

com.dotmarketing.exception.DotRuntimeException: org.apache.tomcat.dbcp.dbcp.SQLNestedException: Cannot create PoolableConnectionFactory (Communications link failure

The last packet sent successfully to the server was 0 milliseconds ago. The driver has not received any packets from the server.)

at com.dotmarketing.db.DbConnectionFactory.getConnection(DbConnectionFactory.java:101)

at com.dotmarketing.db.DbConnectionFactory.getDBType(DbConnectionFactory.java:258)

at com.dotmarketing.business.PermissionBitFactoryImpl.<init>(PermissionBitFactoryImpl.java:117)

at com.dotmarketing.business.FactoryIndex.create(FactoryLocator.java:247)

at com.dotmarketing.business.FactoryLocator.createService(FactoryLocator.java:201)

at com.dotmarketing.business.FactoryLocator.createService(FactoryLocator.java:57)

at com.dotmarketing.business.Locator.getServiceInstance(Locator.java:41)

at com.dotmarketing.business.FactoryLocator.getInstance(FactoryLocator.java:191)

at com.dotmarketing.business.FactoryLocator.getPermissionFactory(FactoryLocator.java:72)

at com.dotmarketing.business.APIIndex.create(APILocator.java:378)

at com.dotmarketing.business.APILocator.createService(APILocator.java:317)

at com.dotmarketing.business.APILocator.createService(APILocator.java:89)

at com.dotmarketing.business.Locator.getServiceInstance(Locator.java:41)

at com.dotmarketing.business.APILocator.getInstance(APILocator.java:305)

at com.dotmarketing.business.APILocator.getPermissionAPI(APILocator.java:104)

at com.dotcms.content.elasticsearch.business.ESContentletAPIImpl.<init>(ESContentletAPIImpl.java:151)

at com.dotmarketing.business.APIIndex.create(APILocator.java:383)

at com.dotmarketing.business.APILocator.createService(APILocator.java:317)

at com.dotmarketing.business.APILocator.createService(APILocator.java:89)

at com.dotmarketing.business.Locator.getServiceInstance(Locator.java:41)

at com.dotmarketing.business.APILocator.getInstance(APILocator.java:305)

at com.dotmarketing.business.APILocator.getContentletAPIImpl(APILocator.java:148)

at com.dotmarketing.portlets.contentlet.business.ContentletAPIInterceptor.<init>(ContentletAPIInterceptor.java:52)

at com.dotmarketing.business.APIIndex.create(APILocator.java:384)

at com.dotmarketing.business.APILocator.createService(APILocator.java:317)

at com.dotmarketing.business.APILocator.createService(APILocator.java:89)

at com.dotmarketing.business.Locator.getServiceInstance(Locator.java:41)

at com.dotmarketing.business.APILocator.getInstance(APILocator.java:305)

at com.dotmarketing.business.APILocator.getContentletAPI(APILocator.java:157)

at com.dotmarketing.cms.urlmap.filters.URLMapFilter.init(URLMapFilter.java:325)

at org.apache.catalina.core.ApplicationFilterConfig.getFilter(ApplicationFilterConfig.java:295)

at org.apache.catalina.core.ApplicationFilterConfig.setFilterDef(ApplicationFilterConfig.java:422)

at org.apache.catalina.core.ApplicationFilterConfig.<init>(ApplicationFilterConfig.java:115)

at org.apache.catalina.core.StandardContext.filterStart(StandardContext.java:3838)

at org.apache.catalina.core.StandardContext.start(StandardContext.java:4488)

at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)

at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771)

at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:546)

at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:637)

at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:563)

at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:498)

at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1277)

at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:321)

at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)

at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)

at org.apache.catalina.core.StandardHost.start(StandardHost.java:785)

at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)

at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)

at org.apache.catalina.core.StandardService.start(StandardService.java:519)

at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)

at org.apache.catalina.startup.Catalina.start(Catalina.java:581)

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)

at java.lang.reflect.Method.invoke(Method.java:601)

at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289)

at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414)

[08/08/13 17:05:25:119 EDT] INFO [localhost].[/]: org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: loaded (conf ok)

[08/08/13 17:05:25:128 EDT] ERROR db.DbConnectionFactory: ---------- DBConnectionFactory: error : org.apache.tomcat.dbcp.dbcp.SQLNestedException: Cannot create PoolableConnectionFactory (Communications link failure

The last packet sent successfully to the server was 0 milliseconds ago. The driver has not received any packets from the server.)

[08/08/13 17:05:25:128 EDT] ERROR [localhost].[/]: Exception starting filter CMSFilter