[LPS-48194] Sharding:Portal is unable to start caused by NoSuchUserException during the verify process (VerifyGroup) - Liferay Issues

XML

Word

Printable

Details

Type: Regression Bug
Status: Closed
Resolution: Won't Fix
Affects Version/s: 6.2.X EE
Fix Version/s: 7.0.0 M3
Component/s: App Management, Core Infrastructure, Core Infrastructure > Sharding, Fault Tolerance, Fault Tolerance > Clustering Framework
Labels:
- QA-B
- QA-F
- ee-ts

Story Points:
3
Fix Priority:
4
Git Pull Request:
https://github.com/brianchandotcom/liferay-portal/pull/22255

Description

1- Setup the following environment:

portal-ext.properties

	### MySQL
	jdbc.default.driverClassName=com.mysql.jdbc.Driver
	jdbc.default.url=jdbc:mysql://localhost:3306/lportal-default?useUnicode=true&characterEncoding=UTF-8&useFastDateParsing=false
	jdbc.default.username=root
	jdbc.default.password=root

	#### Sharding
	jdbc.one.driverClassName=com.mysql.jdbc.Driver
	jdbc.one.url=jdbc:mysql://localhost:3306/lportal-shard-one?useUnicode=true&characterEncoding=UTF-8&useFastDateParsing=false
	jdbc.one.username=root
	jdbc.one.password=root
	
	jdbc.two.driverClassName=com.mysql.jdbc.Driver
	jdbc.two.url=jdbc:mysql://localhost:3306/lportal-shard-two?useUnicode=true&characterEncoding=UTF-8&useFastDateParsing=false
	jdbc.two.username=root
	jdbc.two.password=root

	shard.selector=com.liferay.portal.dao.shard.ManualShardSelector
    spring.configs=\
        META-INF/base-spring.xml,\
        \
        META-INF/hibernate-spring.xml,\
        META-INF/infrastructure-spring.xml,\
        META-INF/management-spring.xml,\
        \
        META-INF/util-spring.xml,\
        \
        META-INF/jpa-spring.xml,\
        \
        META-INF/executor-spring.xml,\
        \
        META-INF/audit-spring.xml,\
        META-INF/cluster-spring.xml,\
        META-INF/editor-spring.xml,\
        META-INF/jcr-spring.xml,\
        META-INF/ldap-spring.xml,\
        META-INF/messaging-core-spring.xml,\
        META-INF/messaging-misc-spring.xml,\
        META-INF/mobile-device-spring.xml,\
        META-INF/notifications-spring.xml,\
        META-INF/poller-spring.xml,\
        META-INF/rules-spring.xml,\
        META-INF/scheduler-spring.xml,\
        META-INF/search-spring.xml,\
        META-INF/workflow-spring.xml,\
        \
        META-INF/counter-spring.xml,\
        META-INF/mail-spring.xml,\
        META-INF/portal-spring.xml,\
        META-INF/portlet-container-spring.xml,\
        META-INF/staging-spring.xml,\
        META-INF/virtual-layouts-spring.xml,\
        \
        META-INF/monitoring-spring.xml,\
        \
        #META-INF/dynamic-data-source-spring.xml,\
        META-INF/shard-data-source-spring.xml,\
        #META-INF/memcached-spring.xml,\
        \
        classpath*:META-INF/ext-spring.xml

2- Also set on master

portal-ext.properties#node2

	module.framework.properties.osgi.console=11312

3- Start node1
4- Add a new Portal Instance: "shard1.com, shard1.com, shard1.com, one"
Instance gets created:

node1.log

INFO: Server startup in 180777 ms
12:02:38,696 INFO  [elasticsearch[Gauntlet][clusterService#updateTask][T#1]][metadata:118] [Gauntlet] [11025] creating index, cause [api], shards [1]/[0], mappings [LiferayDocumentType, _default_, KeywordQueryDocumentType, SpellCheckDocumentType]
12:02:39,003 INFO  [elasticsearch[Gauntlet][clusterService#updateTask][T#1]][metadata:118] [Gauntlet] [11025] update_mapping [LiferayDocumentType] (dynamic)
12:02:39,717 INFO  [elasticsearch[Gauntlet][clusterService#updateTask][T#1]][metadata:118] [Gauntlet] [11025] update_mapping [LiferayDocumentType] (dynamic)
12:03:12,777 INFO  [elasticsearch[Gauntlet][clusterService#updateTask][T#1]][metadata:118] [Gauntlet] [11177] creating index, cause [api], shards [1]/[0], mappings [LiferayDocumentType, _default_, KeywordQueryDocumentType, SpellCheckDocumentType]
12:03:13,411 INFO  [elasticsearch[Gauntlet][clusterService#updateTask][T#1]][metadata:118] [Gauntlet] [11177] update_mapping [LiferayDocumentType] (dynamic)
12:03:14,346 INFO  [elasticsearch[Gauntlet][clusterService#updateTask][T#1]][metadata:118] [Gauntlet] [11177] update_mapping [LiferayDocumentType] (dynamic)
12:03:17,577 INFO  [elasticsearch[Gauntlet][clusterService#updateTask][T#1]][metadata:118] [Gauntlet] [11177] update_mapping [LiferayDocumentType] (dynamic)

5- Start node2
Error: node2 is unable to start:

node2.log

tibusz@tibusz-E6520:~/liferay/bundles/master-2/tomcat-7.0.42/bin$ ./catalina.sh jpda run
Using CATALINA_BASE:   /home/tibusz/liferay/bundles/master-2/tomcat-7.0.42
Using CATALINA_HOME:   /home/tibusz/liferay/bundles/master-2/tomcat-7.0.42
Using CATALINA_TMPDIR: /home/tibusz/liferay/bundles/master-2/tomcat-7.0.42/temp
Using JRE_HOME:        /usr/lib/jvm/java7/jre
Using CLASSPATH:       /home/tibusz/liferay/bundles/master-2/tomcat-7.0.42/bin/bootstrap.jar:/home/tibusz/liferay/bundles/master-2/tomcat-7.0.42/bin/tomcat-juli.jar
Listening for transport dt_socket at address: 9000
Oct 24, 2014 12:08:29 PM org.apache.catalina.core.AprLifecycleListener init
INFO: 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
Oct 24, 2014 12:08:29 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-9080"]
Oct 24, 2014 12:08:29 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-bio-9009"]
Oct 24, 2014 12:08:29 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 495 ms
Oct 24, 2014 12:08:29 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Oct 24, 2014 12:08:29 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.42
Oct 24, 2014 12:08:29 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor /home/tibusz/liferay/bundles/master-2/tomcat-7.0.42/conf/Catalina/localhost/ROOT.xml
Oct 24, 2014 12:08:40 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Loading jar:file:/home/tibusz/liferay/bundles/master-2/tomcat-7.0.42/webapps/ROOT/WEB-INF/lib/portal-impl.jar!/system.properties
Loading jar:file:/home/tibusz/liferay/bundles/master-2/tomcat-7.0.42/webapps/ROOT/WEB-INF/lib/portal-impl.jar!/system.properties
Loading jar:file:/home/tibusz/liferay/bundles/master-2/tomcat-7.0.42/webapps/ROOT/WEB-INF/lib/portal-impl.jar!/portal.properties
Loading file:/home/tibusz/liferay/bundles/master-2/portal-ext.properties
12:08:42,214 INFO  [localhost-startStop-1][ChainActivator:211] Blueprint API detected; enabling Blueprint Container functionality
12:08:42,229 INFO  [localhost-startStop-1][LoggingActivator:211] Starting [org.eclipse.gemini.blueprint.extender] bundle v.[2.0.0.M02]
12:08:42,323 WARN  [localhost-startStop-1][NamespacePlugins:221] Bundle Liferay Portal Spring Extender (com.liferay.portal.spring.extender) cannot see class [org.springframework.beans.factory.xml.NamespaceHandlerResolver]; ignoring it as a namespace resolver
12:08:42,327 WARN  [localhost-startStop-1][NamespacePlugins:221] Bundle Liferay Portal Spring Extender (com.liferay.portal.spring.extender) cannot see class [org.springframework.beans.factory.xml.NamespaceHandlerResolver]; ignoring it as a namespace resolver
12:08:42,330 INFO  [localhost-startStop-1][ExtenderConfiguration:211] Detected extender custom configurations at {bundleentry://54.fwk600608929/META-INF/spring/extender/config.xml}
12:08:42,353 INFO  [localhost-startStop-1][OsgiBundleXmlApplicationContext:211] Refreshing OsgiBundleXmlApplicationContext(bundle=org.eclipse.gemini.blueprint.extender, config=bundleentry://54.fwk600608929/META-INF/spring/extender/config.xml): startup date [Fri Oct 24 12:08:42 GMT 2014]; root of context hierarchy
12:08:42,384 INFO  [localhost-startStop-1][OsgiBundleXmlApplicationContext:211] Application Context service already unpublished
12:08:42,541 INFO  [localhost-startStop-1][OsgiBundleXmlApplicationContext:211] Publishing application context as OSGi service with properties {org.eclipse.gemini.blueprint.context.service.name=org.eclipse.gemini.blueprint.extender, org.springframework.context.service.name=org.eclipse.gemini.blueprint.extender, Bundle-SymbolicName=org.eclipse.gemini.blueprint.extender, Bundle-Version=2.0.0.M02}
12:08:42,548 INFO  [localhost-startStop-1][ExtenderConfiguration$1:211] Initializing Timer
12:08:42,975 INFO  [EclipseGeminiBlueprintExtenderThread-1][DependencyServiceManager:211] Adding OSGi service dependency for importer [&liferayDataSource] matching OSGi filter [(&(objectClass=javax.sql.DataSource)(&(bean.id=liferayDataSourceWrapper)(original.bean=true)))]
12:08:42,976 INFO  [EclipseGeminiBlueprintExtenderThread-1][DependencyServiceManager:211] Adding OSGi service dependency for importer [&com.liferay.portal.kernel.util.InfrastructureUtil] matching OSGi filter [(&(objectClass=com.liferay.portal.kernel.util.InfrastructureUtil)(original.bean=true))]
12:08:42,977 INFO  [EclipseGeminiBlueprintExtenderThread-1][DependencyServiceManager:211] Adding OSGi service dependency for importer [&javax.servlet.ServletContext] matching OSGi filter [(&(objectClass=javax.servlet.ServletContext)(original.bean=true))]
12:08:42,977 INFO  [EclipseGeminiBlueprintExtenderThread-1][DependencyServiceManager:211] ServiceBuilderApplicationContext(bundle=com.liferay.polls.service, config=META-INF/spring/parent/*.xml,META-INF/*-spring.xml) is waiting for unsatisfied dependencies [[&liferayDataSource, &javax.servlet.ServletContext, &com.liferay.portal.kernel.util.InfrastructureUtil]]
12:08:43,467 INFO  [localhost-startStop-1][node:118] [Forge] version[1.3.4], pid[14070], build[a70f3cc/2014-09-30T09:07:17Z]
12:08:43,467 INFO  [localhost-startStop-1][node:118] [Forge] initializing ...
12:08:43,470 INFO  [localhost-startStop-1][plugins:118] [Forge] loaded [], sites []
12:08:44,528 INFO  [localhost-startStop-1][node:118] [Forge] initialized
12:08:44,529 INFO  [localhost-startStop-1][node:118] [Forge] starting ...
12:08:44,534 INFO  [localhost-startStop-1][transport:118] [Forge] bound_address {local[1]}, publish_address {local[1]}
12:08:44,539 INFO  [localhost-startStop-1][discovery:118] [Forge] LiferayElasticSearch/KiDetIzlTiWrz26tNTvfPg
12:08:44,541 INFO  [elasticsearch[Forge][clusterService#updateTask][T#1]][service:118] [Forge] new_master [Forge][KiDetIzlTiWrz26tNTvfPg][tibusz-E6520][local[1]]{local=true}, reason: local-disco-initial_connect(master)
12:08:44,581 INFO  [elasticsearch[Forge][clusterService#updateTask][T#1]][gateway:118] [Forge] recovered [0] indices into cluster_state
12:08:44,600 INFO  [localhost-startStop-1][http:118] [Forge] bound_address {inet[/0.0.0.0:9201]}, publish_address {inet[/192.168.211.31:9201]}
12:08:44,600 INFO  [localhost-startStop-1][node:118] [Forge] started
Oct 24, 2014 12:08:44 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
12:08:48,317 INFO  [localhost-startStop-1][DialectDetector:71] Determine dialect for MySQL 5
12:08:48,342 INFO  [localhost-startStop-1][DialectDetector:136] Found dialect org.hibernate.dialect.MySQLDialect
12:08:50,166 INFO  [localhost-startStop-1][ShardDataSourceTargetSource:87] Sharding configured with 3 data sources
12:08:53,634 INFO  [localhost-startStop-1][LiferayCacheManagerPeerProviderFactory:76] portalPropertyKey ehcache.multi.vm.config.location.peerProviderProperties has value peerDiscovery=automatic,multicastGroupAddress=239.255.0.5,multicastGroupPort=23305,timeToLive=1
12:09:05,379 WARN  [localhost-startStop-1][ResourceActionsImpl:618] Cannot load META-INF/resource-actions/default.xml
12:09:06,688 INFO  [localhost-startStop-1][ClusterBase:149] Autodetecting JGroups outgoing IP address and interface for www.google.com:80
12:09:06,725 INFO  [localhost-startStop-1][ClusterBase:165] Setting JGroups outgoing IP address to 192.168.211.31 and interface to eth0

-------------------------------------------------------------------
GMS: address=tibusz-E6520-37891, cluster=LIFERAY-CONTROL-CHANNEL, physical address=192.168.211.31:55870
-------------------------------------------------------------------
12:09:07,035 INFO  [localhost-startStop-1][BaseReceiver:64] Accepted view [tibusz-E6520-46967|3] [tibusz-E6520-46967, tibusz-E6520-37891]
12:09:07,045 INFO  [localhost-startStop-1][ClusterBase:93] Create a new channel with properties UDP(bind_addr=192.168.211.31;oob_thread_pool_keep_alive_time=30000;timer_keep_alive_time=5000;external_port=0;oob_thread_pool_enabled=true;max_bundle_size=64000;diagnostics_ttl=8;physical_addr_max_fetch_attempts=10;receive_on_all_interfaces=false;mcast_port=23301;thread_pool_min_threads=2;thread_pool_keep_alive_time=30000;thread_pool_max_threads=10;enable_diagnostics=true;ucast_send_buf_size=100000;ip_ttl=8;oob_thread_pool_queue_max_size=500;enable_bundling=true;thread_pool_queue_enabled=true;suppress_time_different_cluster_warnings=60000;timer_rejection_policy=run;diagnostics_port=7500;oob_thread_pool_max_threads=10;wheel_size=200;disable_loopback=false;logical_addr_cache_max_size=500;ip_mcast=true;tick_time=50;logical_addr_cache_expiration=120000;thread_pool_rejection_policy=Discard;suppress_time_different_version_warnings=60000;oob_thread_pool_min_threads=2;who_has_cache_timeout=2000;port_range=50;stats=true;mcast_send_buf_size=100000;id=21;mcast_recv_buf_size=500000;diagnostics_addr=224.0.75.75;bind_port=0;tos=8;oob_thread_pool_rejection_policy=discard;loopback=true;oob_thread_pool_queue_enabled=true;name=UDP;enable_unicast_bundling=true;thread_pool_enabled=true;log_discard_msgs_version=true;thread_naming_pattern=cl;ucast_recv_buf_size=64000;timer_max_threads=10;timer_queue_max_size=500;discard_incompatible_packets=true;ergonomics=true;bundler_capacity=200000;max_bundle_timeout=20;mcast_group_addr=239.255.0.1;bind_interface_str=;timer_min_threads=4;log_discard_msgs=true;thread_pool_queue_max_size=500;bundler_type=new;timer_type=old)_:PING(id=6;return_entire_cache=false;num_initial_members=20;break_on_coord_rsp=true;force_sending_discovery_rsps=true;stagger_timeout=0;stats=true;name=PING;ergonomics=true;timeout=2000;num_initial_srv_members=0)_:MERGE3(id=54;max_participants_in_merge=100;stats=true;name=MERGE3;min_interval=10000;ergonomics=true;max_interval=30000)_:FD_SOCK(bind_addr=192.168.211.31;external_port=0;port_range=50;stats=true;suspect_msg_interval=5000;client_bind_port=0;ergonomics=true;num_tries=3;id=3;get_cache_timeout=1000;sock_conn_timeout=1000;bind_interface_str=;name=FD_SOCK;keep_alive=true;start_port=0)_:FD_ALL(id=29;interval=3000;stats=true;name=FD_ALL;ergonomics=true;msg_counts_as_heartbeat=false;timeout=10000;timeout_check_interval=2000)_:VERIFY_SUSPECT(id=13;bind_addr=192.168.211.31;bind_interface_str=;stats=true;name=VERIFY_SUSPECT;num_msgs=1;ergonomics=true;use_mcast_rsps=false;use_icmp=false;timeout=1500)_:pbcast.NAKACK2(use_mcast_xmit_req=false;use_mcast_xmit=false;suppress_time_non_member_warnings=60000;max_msg_batch_size=500;xmit_from_random_member=false;stats=true;xmit_table_max_compaction_time=30000;log_not_found_msgs=true;ergonomics=true;discard_delivered_msgs=true;print_stability_history_on_failed_xmit=false;id=57;become_server_queue_size=50;max_rebroadcast_timeout=2000;xmit_table_msgs_per_row=2000;xmit_table_num_rows=100;name=NAKACK2;log_discard_msgs=true;xmit_table_resize_factor=1.2;xmit_interval=1000)_:UNICAST2(max_retransmit_time=60000;max_bytes=10000000;max_msg_batch_size=500;stats=true;xmit_table_max_compaction_time=60000;exponential_backoff=300;max_stable_msgs=5;log_not_found_msgs=true;ergonomics=true;stable_interval=60000;id=40;conn_expiry_timeout=60000;xmit_table_msgs_per_row=2000;xmit_table_automatic_purging=true;xmit_table_num_rows=100;name=UNICAST2;timeout=400,800,1600,3200;xmit_interval=1000;xmit_table_resize_factor=1.2;use_range_based_retransmitter=true)_:pbcast.STABLE(id=16;desired_avg_gossip=50000;max_bytes=8000000;stats=true;cap=0.1;name=STABLE;ergonomics=true;stability_delay=1000)_:pbcast.GMS(print_local_addr=true;stats=true;max_bundling_time=50;log_collect_msgs=true;resume_task_timeout=20000;log_view_warnings=true;num_prev_views=20;ergonomics=true;use_flush_if_present=true;print_physical_addrs=true;merge_timeout=5000;id=14;num_prev_mbrs=50;leave_timeout=1000;view_bundling=true;name=GMS;join_timeout=3000;handle_concurrent_startup=true;view_ack_collection_timeout=2000;max_join_attempts=0)_:UFC(id=45;max_block_time=5000;max_credits=2000000;stats=true;ignore_synchronous_response=true;min_credits=800000;name=UFC;min_threshold=0.4;ergonomics=true)_:MFC(id=44;max_block_time=5000;max_credits=2000000;stats=true;ignore_synchronous_response=true;min_credits=800000;name=MFC;min_threshold=0.4;ergonomics=true)_:FRAG2(id=5;frag_size=61440;stats=true;name=FRAG2;ergonomics=true)_:RSVP(id=55;stats=true;name=RSVP;resend_interval=2000;throw_exception_on_timeout=true;ergonomics=true;ack_on_delivery=true;timeout=10000)_ [Sanitized]

-------------------------------------------------------------------
GMS: address=tibusz-E6520-15422, cluster=LIFERAY-TRANSPORT-CHANNEL-0, physical address=192.168.211.31:43279
-------------------------------------------------------------------
12:09:07,160 INFO  [localhost-startStop-1][BaseReceiver:64] Accepted view [tibusz-E6520-8851|3] [tibusz-E6520-8851, tibusz-E6520-15422]
12:09:07,176 INFO  [localhost-startStop-1][ClusterBase:93] Create a new channel with properties UDP(bind_addr=192.168.211.31;oob_thread_pool_keep_alive_time=30000;timer_keep_alive_time=5000;external_port=0;oob_thread_pool_enabled=true;max_bundle_size=64000;diagnostics_ttl=8;physical_addr_max_fetch_attempts=10;receive_on_all_interfaces=false;mcast_port=23302;thread_pool_min_threads=2;thread_pool_keep_alive_time=30000;thread_pool_max_threads=10;enable_diagnostics=true;ucast_send_buf_size=100000;ip_ttl=8;oob_thread_pool_queue_max_size=500;enable_bundling=true;thread_pool_queue_enabled=true;suppress_time_different_cluster_warnings=60000;timer_rejection_policy=run;diagnostics_port=7500;oob_thread_pool_max_threads=10;wheel_size=200;disable_loopback=false;logical_addr_cache_max_size=500;ip_mcast=true;tick_time=50;logical_addr_cache_expiration=120000;thread_pool_rejection_policy=Discard;suppress_time_different_version_warnings=60000;oob_thread_pool_min_threads=2;who_has_cache_timeout=2000;port_range=50;stats=true;mcast_send_buf_size=100000;id=21;mcast_recv_buf_size=500000;diagnostics_addr=224.0.75.75;bind_port=0;tos=8;oob_thread_pool_rejection_policy=discard;loopback=true;oob_thread_pool_queue_enabled=true;name=UDP;enable_unicast_bundling=true;thread_pool_enabled=true;log_discard_msgs_version=true;thread_naming_pattern=cl;ucast_recv_buf_size=64000;timer_max_threads=10;timer_queue_max_size=500;discard_incompatible_packets=true;ergonomics=true;bundler_capacity=200000;max_bundle_timeout=20;mcast_group_addr=239.255.0.2;bind_interface_str=;timer_min_threads=4;log_discard_msgs=true;thread_pool_queue_max_size=500;bundler_type=new;timer_type=old)_:PING(id=6;return_entire_cache=false;num_initial_members=20;break_on_coord_rsp=true;force_sending_discovery_rsps=true;stagger_timeout=0;stats=true;name=PING;ergonomics=true;timeout=2000;num_initial_srv_members=0)_:MERGE3(id=54;max_participants_in_merge=100;stats=true;name=MERGE3;min_interval=10000;ergonomics=true;max_interval=30000)_:FD_SOCK(bind_addr=192.168.211.31;external_port=0;port_range=50;stats=true;suspect_msg_interval=5000;client_bind_port=0;ergonomics=true;num_tries=3;id=3;get_cache_timeout=1000;sock_conn_timeout=1000;bind_interface_str=;name=FD_SOCK;keep_alive=true;start_port=0)_:FD_ALL(id=29;interval=3000;stats=true;name=FD_ALL;ergonomics=true;msg_counts_as_heartbeat=false;timeout=10000;timeout_check_interval=2000)_:VERIFY_SUSPECT(id=13;bind_addr=192.168.211.31;bind_interface_str=;stats=true;name=VERIFY_SUSPECT;num_msgs=1;ergonomics=true;use_mcast_rsps=false;use_icmp=false;timeout=1500)_:pbcast.NAKACK2(use_mcast_xmit_req=false;use_mcast_xmit=false;suppress_time_non_member_warnings=60000;max_msg_batch_size=500;xmit_from_random_member=false;stats=true;xmit_table_max_compaction_time=30000;log_not_found_msgs=true;ergonomics=true;discard_delivered_msgs=true;print_stability_history_on_failed_xmit=false;id=57;become_server_queue_size=50;max_rebroadcast_timeout=2000;xmit_table_msgs_per_row=2000;xmit_table_num_rows=100;name=NAKACK2;log_discard_msgs=true;xmit_table_resize_factor=1.2;xmit_interval=1000)_:UNICAST2(max_retransmit_time=60000;max_bytes=10000000;max_msg_batch_size=500;stats=true;xmit_table_max_compaction_time=60000;exponential_backoff=300;max_stable_msgs=5;log_not_found_msgs=true;ergonomics=true;stable_interval=60000;id=40;conn_expiry_timeout=60000;xmit_table_msgs_per_row=2000;xmit_table_automatic_purging=true;xmit_table_num_rows=100;name=UNICAST2;timeout=400,800,1600,3200;xmit_interval=1000;xmit_table_resize_factor=1.2;use_range_based_retransmitter=true)_:pbcast.STABLE(id=16;desired_avg_gossip=50000;max_bytes=8000000;stats=true;cap=0.1;name=STABLE;ergonomics=true;stability_delay=1000)_:pbcast.GMS(print_local_addr=true;stats=true;max_bundling_time=50;log_collect_msgs=true;resume_task_timeout=20000;log_view_warnings=true;num_prev_views=20;ergonomics=true;use_flush_if_present=true;print_physical_addrs=true;merge_timeout=5000;id=14;num_prev_mbrs=50;leave_timeout=1000;view_bundling=true;name=GMS;join_timeout=3000;handle_concurrent_startup=true;view_ack_collection_timeout=2000;max_join_attempts=0)_:UFC(id=45;max_block_time=5000;max_credits=2000000;stats=true;ignore_synchronous_response=true;min_credits=800000;name=UFC;min_threshold=0.4;ergonomics=true)_:MFC(id=44;max_block_time=5000;max_credits=2000000;stats=true;ignore_synchronous_response=true;min_credits=800000;name=MFC;min_threshold=0.4;ergonomics=true)_:FRAG2(id=5;frag_size=61440;stats=true;name=FRAG2;ergonomics=true)_:RSVP(id=55;stats=true;name=RSVP;resend_interval=2000;throw_exception_on_timeout=true;ergonomics=true;ack_on_delivery=true;timeout=10000)_ [Sanitized]
Starting Liferay Portal Community Edition 7.0.0 CE M2 (Wilberforce / Build 7000 / October 15, 2014)
12:09:14,426 INFO  [localhost-startStop-1][BaseDB:483] Database does not support case sensitive queries
12:09:14,432 INFO  [localhost-startStop-1][BaseDB:483] Database does not support case sensitive queries
12:09:14,437 INFO  [localhost-startStop-1][BaseDB:483] Database does not support case sensitive queries
12:09:14,814 INFO  [Incoming-1,LIFERAY-CONTROL-CHANNEL,tibusz-E6520-37891][DebuggingClusterEventListenerImpl:57] Cluster event JOIN_Cluster node {bindInetAddress=/192.168.211.31, clusterNodeId=b3acf489-5d8d-d202-1e39-098415cf8eea, portalInetSocketAddress=/127.0.0.1:8080} [Sanitized]
12:09:14,964 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyProcessSuite
12:09:14,965 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyProperties
12:09:15,058 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyDB2
12:09:15,059 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyMySQL
12:09:15,090 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyOracle
12:09:15,091 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifySQLServer
12:09:15,091 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyUUID
12:09:15,110 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyPermission
12:09:15,213 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyGroup
12:09:15,368 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyRole
12:09:15,512 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyAsset
12:09:15,516 INFO  [localhost-startStop-1][UpgradeProcess:163] Upgrading com.liferay.portal.upgrade.util.UpgradeAssetPublisherManualEntries
12:09:15,517 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyAuditedModel
12:09:15,528 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyBlogs
12:09:15,534 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyBookmarks
12:09:15,569 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyCalendar
12:09:15,574 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyDocumentLibrary
12:09:15,641 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyDynamicDataMapping
12:09:16,570 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyGroupedModel
12:09:16,578 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyJournal
12:09:16,626 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyLayout
12:09:16,632 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyMessageBoards
12:09:16,705 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyOrganization
12:09:16,735 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyResourcePermissions
12:09:16,753 INFO  [localhost-startStop-1][VerifyResourcePermissions:109] Processed 0 of 1 resource permissions for company = 10305 and model com.liferay.portlet.asset.model.AssetVocabulary
12:09:16,758 INFO  [localhost-startStop-1][VerifyResourcePermissions:109] Processed 0 of 15 resource permissions for company = 10305 and model com.liferay.portlet.dynamicdatamapping.model.DDMStructure
12:09:16,780 INFO  [localhost-startStop-1][VerifyResourcePermissions:109] Processed 0 of 11 resource permissions for company = 10305 and model com.liferay.portlet.dynamicdatamapping.model.DDMTemplate
12:09:16,799 INFO  [localhost-startStop-1][VerifyResourcePermissions:109] Processed 0 of 12 resource permissions for company = 10305 and model com.liferay.portal.model.Layout
12:09:16,901 INFO  [localhost-startStop-1][VerifyResourcePermissions:109] Processed 0 of 12 resource permissions for company = 10305 and model com.liferay.portlet.messageboards.model.MBMessage
12:09:16,920 INFO  [localhost-startStop-1][VerifyResourcePermissions:109] Processed 0 of 1 resource permissions for company = 10305 and model com.liferay.portal.model.PasswordPolicy
12:09:16,928 INFO  [localhost-startStop-1][VerifyResourcePermissions:109] Processed 0 of 2 resource permissions for company = 10305 and model com.liferay.portal.model.User
12:09:16,982 INFO  [localhost-startStop-1][VerifyResourcePermissions:109] Processed 0 of 22 resource permissions for company = 10305 and model com.liferay.portal.model.Layout
12:09:17,088 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifySocial
12:09:17,100 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifySystemEvent
12:09:17,105 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyUser
12:09:17,111 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyWiki
12:09:17,122 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyWorkflow
12:09:17,123 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyProcessSuite
12:09:17,124 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyProperties
12:09:17,125 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyDB2
12:09:17,125 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyMySQL
12:09:17,139 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyOracle
12:09:17,140 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifySQLServer
12:09:17,140 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyUUID
12:09:17,148 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyPermission
12:09:17,165 INFO  [localhost-startStop-1][VerifyProcess:55] Verifying com.liferay.portal.verify.VerifyGroup
12:09:17,197 ERROR [localhost-startStop-1][MainServlet:205] com.liferay.portal.kernel.events.ActionException: com.liferay.portal.verify.VerifyException: com.liferay.portal.verify.VerifyException: com.liferay.portal.NoSuchUserException: No User exists with the key {companyId=11025, defaultUser=true}
com.liferay.portal.kernel.events.ActionException: com.liferay.portal.verify.VerifyException: com.liferay.portal.verify.VerifyException: com.liferay.portal.NoSuchUserException: No User exists with the key {companyId=11025, defaultUser=true}
	at com.liferay.portal.events.StartupAction.run(StartupAction.java:75)
	at com.liferay.portal.servlet.MainServlet.processStartupEvents(MainServlet.java:1238)
	at com.liferay.portal.servlet.MainServlet.init(MainServlet.java:202)
	at javax.servlet.GenericServlet.init(GenericServlet.java:160)
	at org.apache.catalina.core.StandardWrapper.initServlet(StandardWrapper.java:1280)
	at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1193)
	at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:1088)
	at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:5176)
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5460)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:901)
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877)
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:633)
	at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:656)
	at org.apache.catalina.startup.HostConfig$DeployDescriptor.run(HostConfig.java:1635)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:745)
Caused by: com.liferay.portal.verify.VerifyException: com.liferay.portal.verify.VerifyException: com.liferay.portal.NoSuchUserException: No User exists with the key {companyId=11025, defaultUser=true}
	at com.liferay.portal.verify.VerifyProcess.verify(VerifyProcess.java:61)
	at com.liferay.portal.verify.VerifyProcessUtil._verifyProcess(VerifyProcessUtil.java:111)
	at com.liferay.portal.verify.VerifyProcessUtil._verifyProcess(VerifyProcessUtil.java:76)
	at com.liferay.portal.verify.VerifyProcessUtil.verifyProcess(VerifyProcessUtil.java:46)
	at com.liferay.portal.events.StartupHelper.verifyProcess(StartupHelper.java:167)
	at com.liferay.portal.events.StartupHelper$$FastClassBySpringCGLIB$$d389986b.invoke(<generated>)
	at org.springframework.cglib.proxy.MethodProxy.invoke(MethodProxy.java:204)
	at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.invokeJoinpoint(CglibAopProxy.java:700)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
	at com.liferay.portal.dao.shard.advice.ShardIterativelyAdvice.invoke(ShardIterativelyAdvice.java:52)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
	at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
	at org.springframework.aop.framework.CglibAopProxy$DynamicAdvisedInterceptor.intercept(CglibAopProxy.java:633)
	at com.liferay.portal.events.StartupHelper$$EnhancerBySpringCGLIB$$197ebd2.verifyProcess(<generated>)
	at com.liferay.portal.events.StartupHelperUtil.verifyProcess(StartupHelperUtil.java:79)
	at com.liferay.portal.tools.DBUpgrader.verify(DBUpgrader.java:240)
	at com.liferay.portal.events.StartupAction.doRun(StartupAction.java:208)
	at com.liferay.portal.events.StartupAction.run(StartupAction.java:69)
	... 19 more
Caused by: com.liferay.portal.verify.VerifyException: com.liferay.portal.NoSuchUserException: No User exists with the key {companyId=11025, defaultUser=true}
	at com.liferay.portal.verify.VerifyProcess.verify(VerifyProcess.java:61)
	at com.liferay.portal.verify.VerifyProcess.verify(VerifyProcess.java:66)
	at com.liferay.portal.verify.VerifyProcessSuite.doVerify(VerifyProcessSuite.java:34)
	at com.liferay.portal.verify.VerifyProcess.verify(VerifyProcess.java:58)
	... 37 more
Caused by: com.liferay.portal.NoSuchUserException: No User exists with the key {companyId=11025, defaultUser=true}
	at com.liferay.portal.service.persistence.impl.UserPersistenceImpl.findByC_DU(UserPersistenceImpl.java:3927)
	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:606)
	at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
	at com.liferay.portal.dao.shard.advice.ShardPersistenceAdvice.invoke(ShardPersistenceAdvice.java:104)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
	at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
	at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
	at com.sun.proxy.$Proxy53.findByC_DU(Unknown Source)
	at com.liferay.portal.service.impl.UserLocalServiceImpl.loadGetDefaultUser(UserLocalServiceImpl.java:3001)
	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:606)
	at com.liferay.portal.spring.aop.ServiceBeanMethodInvocation.proceed(ServiceBeanMethodInvocation.java:149)
	at com.liferay.portal.spring.aop.ServiceBeanAopProxy.invoke(ServiceBeanAopProxy.java:174)
	at com.sun.proxy.$Proxy78.loadGetDefaultUser(Unknown Source)
	at com.liferay.portal.service.impl.UserLocalServiceImpl.getDefaultUser(UserLocalServiceImpl.java:2108)
	at com.liferay.portal.service.impl.UserLocalServiceImpl.getDefaultUserId(UserLocalServiceImpl.java:2127)
	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:606)
	at com.liferay.portal.spring.aop.ServiceBeanMethodInvocation.proceed(ServiceBeanMethodInvocation.java:149)
	at com.liferay.portal.spring.aop.ServiceBeanAopProxy.invoke(ServiceBeanAopProxy.java:174)
	at com.sun.proxy.$Proxy78.getDefaultUserId(Unknown Source)
	at com.liferay.portal.service.impl.GroupLocalServiceImpl.checkCompanyGroup(GroupLocalServiceImpl.java:590)
	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:606)
	at com.liferay.portal.spring.aop.ServiceBeanMethodInvocation.proceed(ServiceBeanMethodInvocation.java:149)
	at com.liferay.portal.spring.aop.ServiceBeanAopProxy.invoke(ServiceBeanAopProxy.java:174)
	at com.sun.proxy.$Proxy86.checkCompanyGroup(Unknown Source)
	at com.liferay.portal.service.GroupLocalServiceUtil.checkCompanyGroup(GroupLocalServiceUtil.java:299)
	at com.liferay.portal.verify.VerifyGroup.verifyCompanyGroups(VerifyGroup.java:130)
	at com.liferay.portal.verify.VerifyGroup.doVerify(VerifyGroup.java:63)
	at com.liferay.portal.verify.VerifyProcess.verify(VerifyProcess.java:58)
	... 40 more
Stopping the server due to unexpected startup errors
tibusz@tibusz-E6520:~/liferay/bundles/master-2/tomcat-7.0.42/bin$

Attachments

Options
- Sort By Name
- Sort By Date
- Ascending
- Descending
- Thumbnails
- List
- Download All

Attachments

portal-ext.properties
02/Jul/14 1:36 AM
2 kB
Linda Sui

Issue Links

is caused by

LPS-47099 Second node startup fails because it's unable to initialize scheduler

Closed

is duplicated by

LPS-48664 NoSuchUserException when adding a portal instance

Closed

is related to

LPS-49876 Unable to deactive plugins for non default portal instances in sharding

Closed

Testing discovered

LPS-46585 Sometimes images are not accessible in a sharded environment

Closed

LPS-49874 Unable to create new portal instances with sharding enabled

Closed

Activity

People

Assignee:: Linda Sui

Reporter:: Linda Sui

Participants of an Issue:: Linda Sui, Shuyang Zhou, Tammy Fong, Tibor Lipusz

Recent user:: Esther Sanz

Votes:: 0 Vote for this issue

Watchers:: 3 Start watching this issue

Dates

Due:: 30/Oct/14

Created:: 02/Jul/14 1:36 AM

Updated:: 05/Jan/17 7:27 PM

Resolved:: 05/Jan/17 7:27 PM

Days since last comment:: 4 years, 8 weeks, 4 days ago

Packages

Version	Package
7.0.0 M3