Steven Marcus created CAMEL-8460:
------------------------------------
Summary: routes start, shutdown then restart
Key: CAMEL-8460
URL: https://issues.apache.org/jira/browse/CAMEL-8460
Project: Camel
Issue Type: Bug
Components: camel-spring-boot
Affects Versions: 2.15.0
Reporter: Steven Marcus
The latest snapshot fixes the issue with my routes not being found.
But there is still some undesirable behaviour with routes being restarted
during the context refresh. Here's the console log that shows shutdown handlers
being run during app startup... tia
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v1.2.2.RELEASE)
2015-03-09 08:40:55.162 INFO 81935 --- [ main]
mediaroom.MRServicesApp : Starting MRServicesApp on
MBP13.local with PID 81935
(/Users/srnm/Documents/workspace-buffdubs/MRServices/target/classes started by
srnm in /Users/srnm/Documents/workspace-buffdubs/MRServices)
2015-03-09 08:40:55.165 DEBUG 81935 --- [ main]
mediaroom.MRServicesApp : Running with Spring Boot
v1.2.2.RELEASE, Spring v4.1.5.RELEASE
2015-03-09 08:41:00.443 INFO 81935 --- [ main]
mediaroom.MRServicesApp : Running with Spring profile(s) :
[roomSync, transcodeController, transcodeWorker, downloadWorker, uploadWorker]
2015-03-09 08:41:01.804 INFO 81935 --- [ main]
m.service.SimpleS3TransferService : created for AP_SOUTHEAST_2
2015-03-09 08:41:02.037 INFO 81935 --- [ main]
mediaroom.service.TranscodeController : excluding: Set()
2015-03-09 08:41:02.039 INFO 81935 --- [ main]
mediaroom.service.TranscodeController : zencoder enabled for all rooms
2015-03-09 08:41:02.089 INFO 81935 --- [ main]
mediaroom.service.TranscodeController : clipThreshold: 600
featureThreshold: 4200
[AppClassLoader@14dad5dc] info AspectJ Weaver Version 1.8.5 built on Thursday
Jan 29, 2015 at 01:03:58 GMT
[AppClassLoader@14dad5dc] info register classloader
sun.misc.Launcher$AppClassLoader@14dad5dc
[AppClassLoader@14dad5dc] info using configuration
/Users/srnm/Documents/workspace-buffdubs/MRServices/src/main/resources/META-INF/aop.xml
[AppClassLoader@14dad5dc] info register aspect
com.amazonaws.services.simpleworkflow.flow.aspectj.AsynchronousAspect
[AppClassLoader@14dad5dc] info register aspect
com.amazonaws.services.simpleworkflow.flow.aspectj.ExponentialRetryAspect
[AppClassLoader@14dad5dc] warning javax.* types are not being woven because the
weaver option '-Xset:weaveJavaxPackages=true' has not been specified
2015-03-09 08:41:03.406 INFO 81935 --- [ main]
o.a.c.i.converter.DefaultTypeConverter : Loaded 183 type converters
2015-03-09 08:41:04.345 DEBUG 81935 --- [ main]
o.a.camel.spring.boot.RoutesCollector : Post-processing CamelContext bean:
camel-1
2015-03-09 08:41:04.346 DEBUG 81935 --- [ main]
o.a.camel.spring.boot.RoutesCollector : Injecting following route into the
CamelContext: Routes: []
2015-03-09 08:41:04.394 INFO 81935 --- [ main]
o.a.camel.component.log.LogComponent : Using custom Logger:
Logger[camelLogger]
2015-03-09 08:41:04.630 DEBUG 81935 --- [ main]
o.a.camel.spring.boot.RoutesCollector : Injecting following route into the
CamelContext: Routes: []
2015-03-09 08:41:04.632 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Apache Camel 2.15-SNAPSHOT
(CamelContext: camel-1) is starting
2015-03-09 08:41:04.633 INFO 81935 --- [ main]
o.a.c.m.ManagedManagementStrategy : JMX is enabled
2015-03-09 08:41:04.932 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : AllowUseOriginalMessage is enabled.
If access to the original message is not needed, then its recommended to turn
this option off as it may improve performance.
2015-03-09 08:41:04.932 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : StreamCaching is not in use. If
using streams then its recommended to enable stream caching. See more details
at http://camel.apache.org/stream-caching.html
2015-03-09 08:41:05.089 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: roomSyncDLQ started and
consuming from: Endpoint[direct://roomSyncDLQ]
2015-03-09 08:41:05.090 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: syncAllRooms started and
consuming from: Endpoint[timer://roomSyncTimer?delay=3s&period=30s]
2015-03-09 08:41:05.092 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: writeRoomSync started and
consuming from: Endpoint[direct://writeRoomSync]
2015-03-09 08:41:05.095 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: updateRoomRepo started and
consuming from: Endpoint[direct://updateRoomRepo]
2015-03-09 08:41:05.096 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: transcodeWorkflowDLQ started
and consuming from: Endpoint[direct://transcodeWorkflowDLQ]
2015-03-09 08:41:05.098 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: transcodeWorkflowStart
started and consuming from: Endpoint[direct://transcodeWorkflowStart]
2015-03-09 08:41:05.106 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Total 6 routes, of which 6 is
started.
2015-03-09 08:41:05.108 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Apache Camel 2.15-SNAPSHOT
(CamelContext: camel-1) started in 0.474 seconds
2015-03-09 08:41:05.355 INFO 81935 --- [ main]
c.a.s.s.flow.worker.GenericWorker : start: GenericActivityWorker
[super=GenericActivityWorker[service=com.amazonaws.services.simpleworkflow.AmazonSimpleWorkflowClient@5fb5ad40,
domain=test, taskListToPoll=downloadWorker, [email protected],
backoffInitialInterval=100, backoffMaximumInterval=60000,
backoffCoefficient=2.0], taskExecutorThreadPoolSize=1]
2015-03-09 08:41:06.303 INFO 81935 --- [ main]
c.a.s.s.flow.worker.GenericWorker : start: GenericActivityWorker
[super=GenericActivityWorker[service=com.amazonaws.services.simpleworkflow.AmazonSimpleWorkflowClient@5fb5ad40,
domain=test, taskListToPoll=transcodeWorker, [email protected],
backoffInitialInterval=100, backoffMaximumInterval=60000,
backoffCoefficient=2.0], taskExecutorThreadPoolSize=2]
2015-03-09 08:41:06.411 INFO 81935 --- [ main]
c.a.s.s.flow.worker.GenericWorker : start: GenericActivityWorker
[super=GenericActivityWorker[service=com.amazonaws.services.simpleworkflow.AmazonSimpleWorkflowClient@5fb5ad40,
domain=test, taskListToPoll=transcodeWorkerFast, [email protected],
backoffInitialInterval=100, backoffMaximumInterval=60000,
backoffCoefficient=2.0], taskExecutorThreadPoolSize=100]
2015-03-09 08:41:06.552 INFO 81935 --- [ main]
c.a.s.s.flow.worker.GenericWorker : start:
GenericWorkflowWorker[super=GenericWorkflowWorker[service=com.amazonaws.services.simpleworkflow.AmazonSimpleWorkflowClient@5fb5ad40,
domain=test, taskListToPoll=tasklist-transcodeController,
[email protected], backoffInitialInterval=100,
backoffMaximumInterval=60000, backoffCoefficient=2.0],
workflowDefinitionFactoryFactory=com.amazonaws.services.simpleworkflow.flow.spring.SpringWorkflowDefinitionFactoryFactory@510da778]
2015-03-09 08:41:06.661 INFO 81935 --- [ main]
c.a.s.s.flow.worker.GenericWorker : start: GenericActivityWorker
[super=GenericActivityWorker[service=com.amazonaws.services.simpleworkflow.AmazonSimpleWorkflowClient@5fb5ad40,
domain=test, taskListToPoll=uploadWorker, [email protected],
backoffInitialInterval=100, backoffMaximumInterval=60000,
backoffCoefficient=2.0], taskExecutorThreadPoolSize=1]
2015-03-09 08:41:06.766 INFO 81935 --- [ main]
c.a.s.s.flow.worker.GenericWorker : start: GenericActivityWorker
[super=GenericActivityWorker[service=com.amazonaws.services.simpleworkflow.AmazonSimpleWorkflowClient@5fb5ad40,
domain=test, taskListToPoll=uploadWorkerFast, [email protected],
backoffInitialInterval=100, backoffMaximumInterval=60000,
backoffCoefficient=2.0], taskExecutorThreadPoolSize=100]
2015-03-09 08:41:06.911 INFO 81935 --- [ main]
c.a.s.s.flow.worker.GenericWorker : start: GenericActivityWorker
[super=GenericActivityWorker[service=com.amazonaws.services.simpleworkflow.AmazonSimpleWorkflowClient@5fb5ad40,
domain=test, taskListToPoll=tasklist-zencoderWorker,
[email protected], backoffInitialInterval=100,
backoffMaximumInterval=60000, backoffCoefficient=2.0],
taskExecutorThreadPoolSize=20]
2015-03-09 08:41:07.020 DEBUG 81935 --- [ main]
o.a.camel.spring.boot.RoutesCollector : Post-processing CamelContext bean:
camel-1
2015-03-09 08:41:07.022 DEBUG 81935 --- [ main]
o.a.camel.spring.boot.RoutesCollector : Injecting following route into the
CamelContext: Routes: [Route(roomSyncDLQ)[[From[direct://roomSyncDLQ]] ->
[To[browse:roomSyncDLQ]]],
Route(syncAllRooms)[[From[timer://roomSyncTimer?delay=3s&period=30s]] ->
[process[Processor@0x64910b2d], Split[{body} -> [process[Processor@0x1247c72e],
process[Processor@0x6fef75c6], Multicast[[To[direct://writeRoomSync],
To[direct://updateRoomRepo], To[direct://transcodeWorkflowStart]]]]]]],
Route(writeRoomSync)[[From[direct://writeRoomSync]] ->
[Choice[[When[simple{${body.isNoChange}} -> [To[log://?level=OFF]]]]
Otherwise[[process[Processor@0x5f61e002],
Marshal[org.apache.camel.model.DataFormatDefinition@334540a0],
To[file:///Users/srnm/MR-reports/RoomSync?fileExist=Fail&fileName=%24%7Bfile%3Aonlyname.noext%7D.json&tempFileName=%24%7Bfile%3Aonlyname.noext%7D_%24%7Bfile%3Aname.ext%7D.inprogress]]]]]],
Route(updateRoomRepo)[[From[direct://updateRoomRepo]] -> [Split[{ognl(body,
asUpdatesJava)} -> [process[Processor@0x1f179f51],
To[browse:updateRoomRepoComplete]]]]]]
2015-03-09 08:41:07.023 INFO 81935 --- [ main]
o.a.camel.impl.DefaultShutdownStrategy : Starting to graceful shutdown 1
routes (timeout 300 seconds)
2015-03-09 08:41:07.029 INFO 81935 --- [ - ShutdownTask]
o.a.camel.impl.DefaultShutdownStrategy : Route: roomSyncDLQ shutdown
complete, was consuming from: Endpoint[direct://roomSyncDLQ]
2015-03-09 08:41:07.029 INFO 81935 --- [ main]
o.a.camel.impl.DefaultShutdownStrategy : Graceful shutdown of 1 routes
completed in 0 seconds
2015-03-09 08:41:07.031 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: roomSyncDLQ is stopped, was
consuming from: Endpoint[direct://roomSyncDLQ]
2015-03-09 08:41:07.032 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: roomSyncDLQ is shutdown and
removed, was consuming from: Endpoint[direct://roomSyncDLQ]
2015-03-09 08:41:07.034 INFO 81935 --- [ main]
o.a.camel.impl.DefaultShutdownStrategy : Starting to graceful shutdown 1
routes (timeout 300 seconds)
2015-03-09 08:41:07.035 INFO 81935 --- [ - ShutdownTask]
o.a.camel.impl.DefaultShutdownStrategy : Route: syncAllRooms shutdown
complete, was consuming from:
Endpoint[timer://roomSyncTimer?delay=3s&period=30s]
2015-03-09 08:41:07.035 INFO 81935 --- [ main]
o.a.camel.impl.DefaultShutdownStrategy : Graceful shutdown of 1 routes
completed in 0 seconds
2015-03-09 08:41:07.037 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: syncAllRooms is stopped, was
consuming from: Endpoint[timer://roomSyncTimer?delay=3s&period=30s]
2015-03-09 08:41:07.038 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: syncAllRooms is shutdown and
removed, was consuming from: Endpoint[timer://roomSyncTimer?delay=3s&period=30s]
2015-03-09 08:41:07.039 INFO 81935 --- [ main]
o.a.camel.impl.DefaultShutdownStrategy : Starting to graceful shutdown 1
routes (timeout 300 seconds)
2015-03-09 08:41:07.039 INFO 81935 --- [ - ShutdownTask]
o.a.camel.impl.DefaultShutdownStrategy : Route: writeRoomSync shutdown
complete, was consuming from: Endpoint[direct://writeRoomSync]
2015-03-09 08:41:07.039 INFO 81935 --- [ main]
o.a.camel.impl.DefaultShutdownStrategy : Graceful shutdown of 1 routes
completed in 0 seconds
2015-03-09 08:41:07.040 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: writeRoomSync is stopped, was
consuming from: Endpoint[direct://writeRoomSync]
2015-03-09 08:41:07.041 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: writeRoomSync is shutdown and
removed, was consuming from: Endpoint[direct://writeRoomSync]
2015-03-09 08:41:07.042 INFO 81935 --- [ main]
o.a.camel.impl.DefaultShutdownStrategy : Starting to graceful shutdown 1
routes (timeout 300 seconds)
2015-03-09 08:41:07.042 INFO 81935 --- [ - ShutdownTask]
o.a.camel.impl.DefaultShutdownStrategy : Route: updateRoomRepo shutdown
complete, was consuming from: Endpoint[direct://updateRoomRepo]
2015-03-09 08:41:07.043 INFO 81935 --- [ main]
o.a.camel.impl.DefaultShutdownStrategy : Graceful shutdown of 1 routes
completed in 0 seconds
2015-03-09 08:41:07.044 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: updateRoomRepo is stopped,
was consuming from: Endpoint[direct://updateRoomRepo]
2015-03-09 08:41:07.045 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: updateRoomRepo is shutdown
and removed, was consuming from: Endpoint[direct://updateRoomRepo]
2015-03-09 08:41:07.057 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: roomSyncDLQ started and
consuming from: Endpoint[direct://roomSyncDLQ]
2015-03-09 08:41:07.091 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: syncAllRooms started and
consuming from: Endpoint[timer://roomSyncTimer?delay=3s&period=30s]
2015-03-09 08:41:07.113 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: writeRoomSync started and
consuming from: Endpoint[direct://writeRoomSync]
2015-03-09 08:41:07.129 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: updateRoomRepo started and
consuming from: Endpoint[direct://updateRoomRepo]
2015-03-09 08:41:07.129 DEBUG 81935 --- [ main]
o.a.camel.spring.boot.RoutesCollector : Injecting following route into the
CamelContext: Routes:
[Route(transcodeWorkflowDLQ)[[From[direct://transcodeWorkflowDLQ]] ->
[To[browse:transcodeWorkflowDLQ]]],
Route(transcodeWorkflowStart)[[From[direct://transcodeWorkflowStart]] ->
[process[Processor@0x4a6a6a69]]]]
2015-03-09 08:41:07.130 INFO 81935 --- [ main]
o.a.camel.impl.DefaultShutdownStrategy : Starting to graceful shutdown 1
routes (timeout 300 seconds)
2015-03-09 08:41:07.130 INFO 81935 --- [ - ShutdownTask]
o.a.camel.impl.DefaultShutdownStrategy : Route: transcodeWorkflowDLQ shutdown
complete, was consuming from: Endpoint[direct://transcodeWorkflowDLQ]
2015-03-09 08:41:07.130 INFO 81935 --- [ main]
o.a.camel.impl.DefaultShutdownStrategy : Graceful shutdown of 1 routes
completed in 0 seconds
2015-03-09 08:41:07.131 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: transcodeWorkflowDLQ is
stopped, was consuming from: Endpoint[direct://transcodeWorkflowDLQ]
2015-03-09 08:41:07.132 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: transcodeWorkflowDLQ is
shutdown and removed, was consuming from:
Endpoint[direct://transcodeWorkflowDLQ]
2015-03-09 08:41:07.132 INFO 81935 --- [ main]
o.a.camel.impl.DefaultShutdownStrategy : Starting to graceful shutdown 1
routes (timeout 300 seconds)
2015-03-09 08:41:07.132 INFO 81935 --- [ - ShutdownTask]
o.a.camel.impl.DefaultShutdownStrategy : Route: transcodeWorkflowStart
shutdown complete, was consuming from: Endpoint[direct://transcodeWorkflowStart]
2015-03-09 08:41:07.133 INFO 81935 --- [ main]
o.a.camel.impl.DefaultShutdownStrategy : Graceful shutdown of 1 routes
completed in 0 seconds
2015-03-09 08:41:07.133 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: transcodeWorkflowStart is
stopped, was consuming from: Endpoint[direct://transcodeWorkflowStart]
2015-03-09 08:41:07.134 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: transcodeWorkflowStart is
shutdown and removed, was consuming from:
Endpoint[direct://transcodeWorkflowStart]
2015-03-09 08:41:07.144 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: transcodeWorkflowDLQ started
and consuming from: Endpoint[direct://transcodeWorkflowDLQ]
2015-03-09 08:41:07.150 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Route: transcodeWorkflowStart
started and consuming from: Endpoint[direct://transcodeWorkflowStart]
2015-03-09 08:41:07.151 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Apache Camel 2.15-SNAPSHOT
(CamelContext: camel-1) is starting
2015-03-09 08:41:07.151 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Total 6 routes, of which 6 is
started.
2015-03-09 08:41:07.151 INFO 81935 --- [ main]
o.a.camel.spring.SpringCamelContext : Apache Camel 2.15-SNAPSHOT
(CamelContext: camel-1) started in 0.000 seconds
2015-03-09 08:41:07.164 INFO 81935 --- [ main]
mediaroom.MRServicesApp : Started MRServicesApp in 12.586
seconds (JVM running for 13.145)
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
