John Sirois created AURORA-1587:
-----------------------------------
Summary: scheduler fails in a hard to decipher way when
-native_log_file_path is not set
Key: AURORA-1587
URL: https://issues.apache.org/jira/browse/AURORA-1587
Project: Aurora
Issue Type: Bug
Components: Scheduler, Usability
Reporter: John Sirois
As encountered by shirchen [and tracked down in
IRC|http://wilderness.apache.org/channels/?f=aurora/2016-01-15#1452898475],
scheduler starup failures due to a missing {{-native_log_file_path}} flag value
are confusing:
{noformat}
vagrant@debian-jessie:~$ sudo aurora-scheduler
-mesos_master_address=zk://localhost:2181/mesos/master -backup_dir
/var/lib/aurora/scheduler/backups -serverset_path=/aurora/scheduler
-cluster_name example -zk_endpoints=localhost:2181
-thermos_executor_path=/usr/share/aurora/bin/thermos_observer.pex -http_port
8081
Jan 15, 2016 10:00:31 PM org.apache.aurora.common.args.apt.Configuration load
INFO: Loading @CmdLine config from:
[jar:file:/usr/share/aurora/lib/commons-0.12.0-SNAPSHOT.jar!/org/apache/aurora/common/args/apt/cmdline.arg.info.txt.1,
jar:file:/usr/share/aurora/lib/aurora-0.12.0-SNAPSHOT.jar!/org/apache/aurora/common/args/apt/cmdline.arg.info.txt.2]
I0115 22:00:31.378 [main, Log:186] Logging initialized @744ms
W0115 22:00:31.558 [main, ArgScanner:318] Found argument name collisions, args
must be referenced by canonical names: [slow_query_log_threshold]
I0115 22:00:31.579 [main, ArgScanner:440]
-------------------------------------------------------------------------
I0115 22:00:31.583 [main, ArgScanner:441] Command line argument values
I0115 22:00:31.584 [main, ArgScanner:443] allow_docker_parameters
(org.apache.aurora.scheduler.app.AppModule.allow_docker_parameters): false
I0115 22:00:31.585 [main, ArgScanner:443] allowed_container_types
(org.apache.aurora.scheduler.app.AppModule.allowed_container_types): [MESOS]
I0115 22:00:31.586 [main, ArgScanner:443] async_slot_stat_update_interval
(org.apache.aurora.scheduler.stats.AsyncStatsModule.async_slot_stat_update_interval):
(1, mins)
I0115 22:00:31.587 [main, ArgScanner:443] async_task_stat_update_interval
(org.apache.aurora.scheduler.stats.AsyncStatsModule.async_task_stat_update_interval):
(1, hrs)
I0115 22:00:31.587 [main, ArgScanner:443] async_worker_threads
(org.apache.aurora.scheduler.async.AsyncModule.async_worker_threads): 8
I0115 22:00:31.588 [main, ArgScanner:443] backup_dir
(org.apache.aurora.scheduler.storage.backup.BackupModule.backup_dir):
/var/lib/aurora/scheduler/backups
I0115 22:00:31.588 [main, ArgScanner:443] backup_interval
(org.apache.aurora.scheduler.storage.backup.BackupModule.backup_interval): (1,
hrs)
I0115 22:00:31.589 [main, ArgScanner:443] cluster_name
(org.apache.aurora.scheduler.app.SchedulerMain.cluster_name): example
I0115 22:00:31.589 [main, ArgScanner:443] cron_scheduler_num_threads
(org.apache.aurora.scheduler.cron.quartz.CronModule.cron_scheduler_num_threads):
100
I0115 22:00:31.590 [main, ArgScanner:443] cron_start_initial_backoff
(org.apache.aurora.scheduler.cron.quartz.CronModule.cron_start_initial_backoff):
(1, secs)
I0115 22:00:31.591 [main, ArgScanner:443] cron_start_max_backoff
(org.apache.aurora.scheduler.cron.quartz.CronModule.cron_start_max_backoff):
(1, mins)
I0115 22:00:31.591 [main, ArgScanner:443] cron_timezone
(org.apache.aurora.scheduler.cron.quartz.CronModule.cron_timezone): GMT
I0115 22:00:31.592 [main, ArgScanner:443] custom_executor_config
(org.apache.aurora.scheduler.configuration.executor.ExecutorModule.custom_executor_config):
null
I0115 22:00:31.596 [main, ArgScanner:443] db_row_gc_interval
(org.apache.aurora.scheduler.storage.db.DbModule.db_row_gc_interval): (2, hrs)
I0115 22:00:31.597 [main, ArgScanner:443] dlog_max_entry_size
(org.apache.aurora.scheduler.storage.log.LogStorageModule.dlog_max_entry_size):
(512, KB)
I0115 22:00:31.598 [main, ArgScanner:443] dlog_shutdown_grace_period
(org.apache.aurora.scheduler.storage.log.LogStorageModule.dlog_shutdown_grace_period):
(2, secs)
I0115 22:00:31.598 [main, ArgScanner:443] dlog_snapshot_interval
(org.apache.aurora.scheduler.storage.log.LogStorageModule.dlog_snapshot_interval):
(1, hrs)
I0115 22:00:31.598 [main, ArgScanner:443] enable_cors_for
(org.apache.aurora.scheduler.http.api.ApiModule.enable_cors_for): null
I0115 22:00:31.599 [main, ArgScanner:443] enable_h2_console
(org.apache.aurora.scheduler.http.H2ConsoleModule.enable_h2_console): false
I0115 22:00:31.599 [main, ArgScanner:443] enable_preemptor
(org.apache.aurora.scheduler.preemptor.PreemptorModule.enable_preemptor): true
I0115 22:00:31.600 [main, ArgScanner:443] executor_user
(org.apache.aurora.scheduler.mesos.CommandLineDriverSettingsModule.executor_user):
root
I0115 22:00:31.600 [main, ArgScanner:443] extra_modules
(org.apache.aurora.scheduler.app.SchedulerMain.extra_modules): []
I0115 22:00:31.600 [main, ArgScanner:443] first_schedule_delay
(org.apache.aurora.scheduler.scheduling.SchedulingModule.first_schedule_delay):
(1, ms)
I0115 22:00:31.600 [main, ArgScanner:443] flapping_task_threshold
(org.apache.aurora.scheduler.scheduling.SchedulingModule.flapping_task_threshold):
(5, mins)
I0115 22:00:31.600 [main, ArgScanner:443] framework_announce_principal
(org.apache.aurora.scheduler.mesos.CommandLineDriverSettingsModule.framework_announce_principal):
false
I0115 22:00:31.600 [main, ArgScanner:443] framework_authentication_file
(org.apache.aurora.scheduler.mesos.CommandLineDriverSettingsModule.framework_authentication_file):
null
I0115 22:00:31.600 [main, ArgScanner:443] framework_failover_timeout
(org.apache.aurora.scheduler.mesos.CommandLineDriverSettingsModule.framework_failover_timeout):
(21, days)
I0115 22:00:31.600 [main, ArgScanner:443] global_container_mounts
(org.apache.aurora.scheduler.configuration.executor.ExecutorModule.global_container_mounts):
[]
I0115 22:00:31.601 [main, ArgScanner:443] history_max_per_job_threshold
(org.apache.aurora.scheduler.pruning.PruningModule.history_max_per_job_threshold):
100
I0115 22:00:31.601 [main, ArgScanner:443] history_min_retention_threshold
(org.apache.aurora.scheduler.pruning.PruningModule.history_min_retention_threshold):
(1, hrs)
I0115 22:00:31.601 [main, ArgScanner:443] history_prune_threshold
(org.apache.aurora.scheduler.pruning.PruningModule.history_prune_threshold):
(2, days)
I0115 22:00:31.601 [main, ArgScanner:443] hostname
(org.apache.aurora.scheduler.http.JettyServerModule.hostname): null
I0115 22:00:31.601 [main, ArgScanner:443] http_authentication_mechanism
(org.apache.aurora.scheduler.http.api.security.HttpSecurityModule.http_authentication_mechanism):
NONE
I0115 22:00:31.602 [main, ArgScanner:443] http_port
(org.apache.aurora.scheduler.http.JettyServerModule.http_port): 8081
I0115 22:00:31.602 [main, ArgScanner:443] initial_flapping_task_delay
(org.apache.aurora.scheduler.scheduling.SchedulingModule.initial_flapping_task_delay):
(30, secs)
I0115 22:00:31.602 [main, ArgScanner:443] initial_schedule_penalty
(org.apache.aurora.scheduler.scheduling.SchedulingModule.initial_schedule_penalty):
(1, secs)
I0115 22:00:31.602 [main, ArgScanner:443] initial_task_kill_retry_interval
(org.apache.aurora.scheduler.reconciliation.ReconciliationModule.initial_task_kill_retry_interval):
(5, secs)
I0115 22:00:31.602 [main, ArgScanner:443] job_update_history_per_job_threshold
(org.apache.aurora.scheduler.pruning.PruningModule.job_update_history_per_job_threshold):
10
I0115 22:00:31.603 [main, ArgScanner:443] job_update_history_pruning_interval
(org.apache.aurora.scheduler.pruning.PruningModule.job_update_history_pruning_interval):
(15, mins)
I0115 22:00:31.603 [main, ArgScanner:443] job_update_history_pruning_threshold
(org.apache.aurora.scheduler.pruning.PruningModule.job_update_history_pruning_threshold):
(30, days)
I0115 22:00:31.604 [main, ArgScanner:443] kerberos_debug
(org.apache.aurora.scheduler.http.api.security.Kerberos5ShiroRealmModule.kerberos_debug):
false
I0115 22:00:31.604 [main, ArgScanner:443] kerberos_server_keytab
(org.apache.aurora.scheduler.http.api.security.Kerberos5ShiroRealmModule.kerberos_server_keytab):
null
I0115 22:00:31.604 [main, ArgScanner:443] kerberos_server_principal
(org.apache.aurora.scheduler.http.api.security.Kerberos5ShiroRealmModule.kerberos_server_principal):
null
I0115 22:00:31.604 [main, ArgScanner:443] max_flapping_task_delay
(org.apache.aurora.scheduler.scheduling.SchedulingModule.max_flapping_task_delay):
(5, mins)
I0115 22:00:31.605 [main, ArgScanner:443] max_leading_duration
(org.apache.aurora.scheduler.SchedulerModule.max_leading_duration): (1, days)
I0115 22:00:31.605 [main, ArgScanner:443] max_registration_delay
(org.apache.aurora.scheduler.SchedulerModule.max_registration_delay): (1, mins)
I0115 22:00:31.605 [main, ArgScanner:443] max_reschedule_task_delay_on_startup
(org.apache.aurora.scheduler.scheduling.SchedulingModule.max_reschedule_task_delay_on_startup):
(30, secs)
I0115 22:00:31.605 [main, ArgScanner:443] max_saved_backups
(org.apache.aurora.scheduler.storage.backup.BackupModule.max_saved_backups): 48
I0115 22:00:31.606 [main, ArgScanner:443] max_schedule_attempts_per_sec
(org.apache.aurora.scheduler.scheduling.SchedulingModule.max_schedule_attempts_per_sec):
40.0
I0115 22:00:31.607 [main, ArgScanner:443] max_schedule_penalty
(org.apache.aurora.scheduler.scheduling.SchedulingModule.max_schedule_penalty):
(1, mins)
I0115 22:00:31.608 [main, ArgScanner:443] max_status_update_batch_size
(org.apache.aurora.scheduler.SchedulerModule.max_status_update_batch_size):
1000
I0115 22:00:31.609 [main, ArgScanner:443] max_tasks_per_job
(org.apache.aurora.scheduler.app.AppModule.max_tasks_per_job): 4000
I0115 22:00:31.610 [main, ArgScanner:443] max_update_instance_failures
(org.apache.aurora.scheduler.app.AppModule.max_update_instance_failures): 20000
I0115 22:00:31.610 [main, ArgScanner:443] mesos_master_address
(org.apache.aurora.scheduler.mesos.CommandLineDriverSettingsModule.mesos_master_address):
zk://localhost:2181/mesos/master
I0115 22:00:31.610 [main, ArgScanner:443] mesos_role
(org.apache.aurora.scheduler.mesos.CommandLineDriverSettingsModule.mesos_role):
null
I0115 22:00:31.611 [main, ArgScanner:443] min_offer_hold_time
(org.apache.aurora.scheduler.offers.OffersModule.min_offer_hold_time): (5,
mins)
I0115 22:00:31.612 [main, ArgScanner:443] native_log_election_retries
(org.apache.aurora.scheduler.log.mesos.MesosLogStreamModule.native_log_election_retries):
20
I0115 22:00:31.612 [main, ArgScanner:443] native_log_election_timeout
(org.apache.aurora.scheduler.log.mesos.MesosLogStreamModule.native_log_election_timeout):
(15, secs)
I0115 22:00:31.612 [main, ArgScanner:443] native_log_file_path
(org.apache.aurora.scheduler.log.mesos.MesosLogStreamModule.native_log_file_path):
null
I0115 22:00:31.613 [main, ArgScanner:443] native_log_quorum_size
(org.apache.aurora.scheduler.log.mesos.MesosLogStreamModule.native_log_quorum_size):
1
I0115 22:00:31.613 [main, ArgScanner:443] native_log_read_timeout
(org.apache.aurora.scheduler.log.mesos.MesosLogStreamModule.native_log_read_timeout):
(5, secs)
I0115 22:00:31.614 [main, ArgScanner:443] native_log_write_timeout
(org.apache.aurora.scheduler.log.mesos.MesosLogStreamModule.native_log_write_timeout):
(3, secs)
I0115 22:00:31.616 [main, ArgScanner:443] native_log_zk_group_path
(org.apache.aurora.scheduler.log.mesos.MesosLogStreamModule.native_log_zk_group_path):
null
I0115 22:00:31.617 [main, ArgScanner:443] offer_hold_jitter_window
(org.apache.aurora.scheduler.offers.OffersModule.offer_hold_jitter_window): (1,
mins)
I0115 22:00:31.617 [main, ArgScanner:443] offer_reservation_duration
(org.apache.aurora.scheduler.scheduling.SchedulingModule.offer_reservation_duration):
(3, mins)
I0115 22:00:31.617 [main, ArgScanner:443] preemption_delay
(org.apache.aurora.scheduler.preemptor.PreemptorModule.preemption_delay): (3,
mins)
I0115 22:00:31.617 [main, ArgScanner:443] preemption_slot_hold_time
(org.apache.aurora.scheduler.preemptor.PreemptorModule.preemption_slot_hold_time):
(5, mins)
I0115 22:00:31.617 [main, ArgScanner:443] preemption_slot_search_interval
(org.apache.aurora.scheduler.preemptor.PreemptorModule.preemption_slot_search_interval):
(1, mins)
I0115 22:00:31.617 [main, ArgScanner:443] receive_revocable_resources
(org.apache.aurora.scheduler.mesos.CommandLineDriverSettingsModule.receive_revocable_resources):
false
I0115 22:00:31.617 [main, ArgScanner:443] reconciliation_explicit_interval
(org.apache.aurora.scheduler.reconciliation.ReconciliationModule.reconciliation_explicit_interval):
(60, mins)
I0115 22:00:31.618 [main, ArgScanner:443] reconciliation_implicit_interval
(org.apache.aurora.scheduler.reconciliation.ReconciliationModule.reconciliation_implicit_interval):
(60, mins)
I0115 22:00:31.618 [main, ArgScanner:443] reconciliation_initial_delay
(org.apache.aurora.scheduler.reconciliation.ReconciliationModule.reconciliation_initial_delay):
(1, mins)
I0115 22:00:31.618 [main, ArgScanner:443] reconciliation_schedule_spread
(org.apache.aurora.scheduler.reconciliation.ReconciliationModule.reconciliation_schedule_spread):
(30, mins)
I0115 22:00:31.618 [main, ArgScanner:443] serverset_path
(org.apache.aurora.scheduler.app.SchedulerMain.serverset_path):
/aurora/scheduler
I0115 22:00:31.618 [main, ArgScanner:443] shiro_ini_path
(org.apache.aurora.scheduler.http.api.security.IniShiroRealmModule.shiro_ini_path):
null
I0115 22:00:31.618 [main, ArgScanner:443] shiro_realm_modules
(org.apache.aurora.scheduler.http.api.security.HttpSecurityModule.shiro_realm_modules):
[org.apache.aurora.scheduler.app.MoreModules$1@411f53a0]
I0115 22:00:31.618 [main, ArgScanner:443] sla_non_prod_metrics
(org.apache.aurora.scheduler.sla.SlaModule.sla_non_prod_metrics): []
I0115 22:00:31.618 [main, ArgScanner:443] sla_prod_metrics
(org.apache.aurora.scheduler.sla.SlaModule.sla_prod_metrics): [JOB_UPTIMES,
PLATFORM_UPTIME, MEDIANS]
I0115 22:00:31.618 [main, ArgScanner:443] sla_stat_refresh_interval
(org.apache.aurora.scheduler.sla.SlaModule.sla_stat_refresh_interval): (1,
mins)
I0115 22:00:31.618 [main, ArgScanner:443] slow_query_log_threshold
(org.apache.aurora.scheduler.storage.db.DbModule.slow_query_log_threshold):
(25, ms)
I0115 22:00:31.619 [main, ArgScanner:443] slow_query_log_threshold
(org.apache.aurora.scheduler.storage.mem.InMemStoresModule.slow_query_log_threshold):
(25, ms)
I0115 22:00:31.619 [main, ArgScanner:443] stat_retention_period
(org.apache.aurora.scheduler.stats.StatsModule.stat_retention_period): (1, hrs)
I0115 22:00:31.619 [main, ArgScanner:443] stat_sampling_interval
(org.apache.aurora.scheduler.stats.StatsModule.stat_sampling_interval): (1,
secs)
I0115 22:00:31.619 [main, ArgScanner:443] thermos_executor_cpu
(org.apache.aurora.scheduler.configuration.executor.ExecutorModule.thermos_executor_cpu):
0.25
I0115 22:00:31.619 [main, ArgScanner:443] thermos_executor_flags
(org.apache.aurora.scheduler.configuration.executor.ExecutorModule.thermos_executor_flags):
null
I0115 22:00:31.620 [main, ArgScanner:443] thermos_executor_path
(org.apache.aurora.scheduler.configuration.executor.ExecutorModule.thermos_executor_path):
/usr/share/aurora/bin/thermos_observer.pex
I0115 22:00:31.620 [main, ArgScanner:443] thermos_executor_ram
(org.apache.aurora.scheduler.configuration.executor.ExecutorModule.thermos_executor_ram):
(128, MB)
I0115 22:00:31.620 [main, ArgScanner:443] thermos_executor_resources
(org.apache.aurora.scheduler.configuration.executor.ExecutorModule.thermos_executor_resources):
[]
I0115 22:00:31.620 [main, ArgScanner:443] thermos_observer_root
(org.apache.aurora.scheduler.configuration.executor.ExecutorModule.thermos_observer_root):
/var/run/thermos
I0115 22:00:31.621 [main, ArgScanner:443] tier_config
(org.apache.aurora.scheduler.SchedulerModule.tier_config): null
I0115 22:00:31.621 [main, ArgScanner:443] transient_task_state_timeout
(org.apache.aurora.scheduler.reconciliation.ReconciliationModule.transient_task_state_timeout):
(5, mins)
I0115 22:00:31.621 [main, ArgScanner:443] use_beta_db_task_store
(org.apache.aurora.scheduler.storage.db.DbModule.use_beta_db_task_store): false
I0115 22:00:31.621 [main, ArgScanner:443] viz_job_url_prefix
(org.apache.aurora.scheduler.app.SchedulerMain.viz_job_url_prefix):
I0115 22:00:31.621 [main, ArgScanner:443] zk_chroot_path
(org.apache.aurora.scheduler.zookeeper.guice.client.flagged.FlaggedClientConfig.zk_chroot_path):
null
I0115 22:00:31.621 [main, ArgScanner:443] zk_digest_credentials
(org.apache.aurora.scheduler.zookeeper.guice.client.flagged.FlaggedClientConfig.zk_digest_credentials):
null
I0115 22:00:31.621 [main, ArgScanner:443] zk_endpoints
(org.apache.aurora.scheduler.zookeeper.guice.client.flagged.FlaggedClientConfig.zk_endpoints):
[localhost:2181]
I0115 22:00:31.621 [main, ArgScanner:443] zk_in_proc
(org.apache.aurora.scheduler.zookeeper.guice.client.flagged.FlaggedClientConfig.zk_in_proc):
false
I0115 22:00:31.622 [main, ArgScanner:443] zk_session_timeout
(org.apache.aurora.scheduler.zookeeper.guice.client.flagged.FlaggedClientConfig.zk_session_timeout):
(4, secs)
I0115 22:00:31.622 [main, ArgScanner:445]
-------------------------------------------------------------------------
I0115 22:00:31.922 [main, PreemptorModule$1:87] Preemptor Enabled.
Jan 15, 2016 10:00:32 PM com.google.inject.internal.ProxyFactory <init>
WARNING: Method [public java.lang.Iterable
org.apache.aurora.scheduler.storage.mem.MemTaskStore.fetchTasks(org.apache.aurora.scheduler.base.Query$Builder)]
is synthetic and is being intercepted by
[org.apache.aurora.common.inject.TimedInterceptor@2e11485]. This could indicate
a bug. The method may be intercepted twice, or may not be intercepted at all.
Jan 15, 2016 10:00:32 PM com.google.inject.internal.ProxyFactory <init>
WARNING: Method [public void
org.apache.aurora.scheduler.storage.log.SnapshotStoreImpl.applySnapshot(java.lang.Object)]
is synthetic and is being intercepted by
[org.apache.aurora.common.inject.TimedInterceptor@2e11485]. This could indicate
a bug. The method may be intercepted twice, or may not be intercepted at all.
Jan 15, 2016 10:00:32 PM com.google.inject.internal.ProxyFactory <init>
WARNING: Method [public java.lang.Object
org.apache.aurora.scheduler.storage.log.SnapshotStoreImpl.createSnapshot()] is
synthetic and is being intercepted by
[org.apache.aurora.common.inject.TimedInterceptor@2e11485]. This could indicate
a bug. The method may be intercepted twice, or may not be intercepted at all.
W0115 22:00:33.535 [main, ServiceDiscoveryModule:61] Running without ZooKeeper
digest credentials. ZooKeeper ACLs are disabled.
I0115 22:00:33.641 [main, BuildInfo:68] Fetching build properties from
build.properties
I0115 22:00:33.844 [main, StdSchedulerFactory:1172] Using default
implementation for ThreadExecutor
I0115 22:00:33.866 [main, SchedulerSignalerImpl:61] Initialized Scheduler
Signaller of type: class org.quartz.core.SchedulerSignalerImpl
I0115 22:00:33.867 [main, QuartzScheduler:240] Quartz Scheduler v.2.2.2
created.
I0115 22:00:33.868 [main, RAMJobStore:155] RAMJobStore initialized.
I0115 22:00:33.870 [main, QuartzScheduler:305] Scheduler meta-data: Quartz
Scheduler (v2.2.2) 'QuartzScheduler' with instanceId 'aurora-cron-1'
Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
NOT STARTED.
Currently in standby mode.
Number of jobs executed: 0
Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 100 threads.
Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support
persistence. and is not clustered.
I0115 22:00:33.873 [main, StdSchedulerFactory:1327] Quartz scheduler
'QuartzScheduler' initialized from an externally provided properties instance.
I0115 22:00:33.873 [main, StdSchedulerFactory:1331] Quartz scheduler version:
2.2.2
I0115 22:00:33.874 [main, QuartzScheduler:2311] JobFactory set to:
org.apache.aurora.scheduler.cron.quartz.AuroraCronJobFactory@5b56b654
W0115 22:00:33.878 [main, CronModule:102] Cron schedules are configured to fire
according to timezone Greenwich Mean Time but system timezone is set to
GMT+00:00
I0115 22:00:33.903 [main, BuildInfo:68] Fetching build properties from
build.properties
I0115 22:00:33.978 [TimeSeriesRepositoryImpl STARTING,
TimeSeriesRepositoryImpl:124] Build Info key: date has value Fri 2016-01-15
I0115 22:00:33.982 [RedirectMonitor STARTING, Environment:100] Client
environment:zookeeper.version=3.4.6-1569965, built on 02/20/2014 09:09 GMT
I0115 22:00:33.987 [RedirectMonitor STARTING, Environment:100] Client
environment:host.name=debian-jessie.vagrantup.com
I0115 22:00:33.987 [RedirectMonitor STARTING, Environment:100] Client
environment:java.version=1.8.0_66-internal
I0115 22:00:33.987 [RedirectMonitor STARTING, Environment:100] Client
environment:java.vendor=Oracle Corporation
I0115 22:00:33.988 [RedirectMonitor STARTING, Environment:100] Client
environment:java.home=/usr/lib/jvm/java-8-openjdk-amd64/jre
I0115 22:00:33.989 [RedirectMonitor STARTING, Environment:100] Client
environment:java.class.path=/usr/share/aurora/lib/aurora-0.12.0-SNAPSHOT.jar:/usr/share/aurora/lib/classes:/usr/share/aurora/lib/dependency-cache:/usr/share/aurora/lib/classes:/usr/share/aurora/lib/classes:/usr/share/aurora/lib/dependency-cache:/usr/share/aurora/lib/aurora-api-0.12.0-SNAPSHOT.jar:/usr/share/aurora/lib/commons-0.12.0-SNAPSHOT.jar:/usr/share/aurora/lib/commons-args-0.12.0-SNAPSHOT.jar:/usr/share/aurora/lib/aopalliance-1.0.jar:/usr/share/aurora/lib/logback-classic-1.1.3.jar:/usr/share/aurora/lib/jsr305-3.0.1.jar:/usr/share/aurora/lib/guice-3.0.jar:/usr/share/aurora/lib/guice-assistedinject-3.0.jar:/usr/share/aurora/lib/protobuf-java-2.6.1.jar:/usr/share/aurora/lib/h2-1.4.190.jar:/usr/share/aurora/lib/jackson-datatype-protobuf-0.9.3.jar:/usr/share/aurora/lib/jackson-core-2.5.1.jar:/usr/share/aurora/lib/jersey-core-1.19.jar:/usr/share/aurora/lib/jersey-json-1.19.jar:/usr/share/aurora/lib/jersey-server-1.19.jar:/usr/share/aurora/lib/jersey-servlet-1.19.jar:/usr/share/aurora/lib/jersey-guice-1.19.jar:/usr/share/aurora/lib/javax.inject-1.jar:/usr/share/aurora/lib/javax.servlet-api-3.1.0.jar:/usr/share/aurora/lib/stringtemplate-3.2.1.jar:/usr/share/aurora/lib/mesos-0.25.0.jar:/usr/share/aurora/lib/shiro-guice-1.2.4.jar:/usr/share/aurora/lib/shiro-web-1.2.4.jar:/usr/share/aurora/lib/zookeeper-3.4.6.jar:/usr/share/aurora/lib/jetty-rewrite-9.3.6.v20151106.jar:/usr/share/aurora/lib/jetty-server-9.3.6.v20151106.jar:/usr/share/aurora/lib/jetty-servlet-9.3.6.v20151106.jar:/usr/share/aurora/lib/jetty-servlets-9.3.6.v20151106.jar:/usr/share/aurora/lib/mybatis-3.3.0.jar:/usr/share/aurora/lib/mybatis-guice-3.7.jar:/usr/share/aurora/lib/quartz-2.2.2.jar:/usr/share/aurora/lib/forward-1.0.0.jar:/usr/share/aurora/lib/libthrift-0.9.1.jar:/usr/share/aurora/lib/gson-2.3.1.jar:/usr/share/aurora/lib/guava-19.0.jar:/usr/share/aurora/lib/guice-multibindings-3.0.jar:/usr/share/aurora/lib/commons-lang-2.6.jar:/usr/share/aurora/lib/joda-time-2.9.1.jar:/usr/share/aurora/lib/easymock-3.4.jar:/usr/share/aurora/lib/junit-4.12.jar:/usr/share/aurora/lib/logback-core-1.1.3.jar:/usr/share/aurora/lib/slf4j-api-1.7.7.jar:/usr/share/aurora/lib/cglib-2.2.1-v20090111.jar:/usr/share/aurora/lib/jackson-annotations-2.4.6.jar:/usr/share/aurora/lib/jackson-databind-2.4.6.jar:/usr/share/aurora/lib/annotations-3.0.0.jar:/usr/share/aurora/lib/jsr311-api-1.1.1.jar:/usr/share/aurora/lib/jettison-1.1.jar:/usr/share/aurora/lib/jaxb-impl-2.2.3-1.jar:/usr/share/aurora/lib/jackson-core-asl-1.9.2.jar:/usr/share/aurora/lib/jackson-mapper-asl-1.9.2.jar:/usr/share/aurora/lib/jackson-jaxrs-1.9.2.jar:/usr/share/aurora/lib/jackson-xc-1.9.2.jar:/usr/share/aurora/lib/guice-servlet-3.0.jar:/usr/share/aurora/lib/antlr-2.7.7.jar:/usr/share/aurora/lib/shiro-core-1.2.4.jar:/usr/share/aurora/lib/jline-0.9.94.jar:/usr/share/aurora/lib/netty-3.7.0.Final.jar:/usr/share/aurora/lib/jetty-client-9.3.6.v20151106.jar:/usr/share/aurora/lib/jetty-http-9.3.6.v20151106.jar:/usr/share/aurora/lib/jetty-io-9.3.6.v20151106.jar:/usr/share/aurora/lib/jetty-security-9.3.6.v20151106.jar:/usr/share/aurora/lib/jetty-continuation-9.3.6.v20151106.jar:/usr/share/aurora/lib/jetty-util-9.3.6.v20151106.jar:/usr/share/aurora/lib/c3p0-0.9.1.1.jar:/usr/share/aurora/lib/javapoet-1.4.0.jar:/usr/share/aurora/lib/commons-lang3-3.1.jar:/usr/share/aurora/lib/httpclient-4.2.5.jar:/usr/share/aurora/lib/httpcore-4.2.4.jar:/usr/share/aurora/lib/objenesis-2.2.jar:/usr/share/aurora/lib/hamcrest-core-1.3.jar:/usr/share/aurora/lib/asm-3.1.jar:/usr/share/aurora/lib/jaxb-api-2.2.2.jar:/usr/share/aurora/lib/commons-beanutils-1.8.3.jar:/usr/share/aurora/lib/commons-logging-1.1.1.jar:/usr/share/aurora/lib/commons-codec-1.6.jar:/usr/share/aurora/lib/stax-api-1.0-2.jar:/usr/share/aurora/lib/activation-1.1.jar
I0115 22:00:33.990 [RedirectMonitor STARTING, Environment:100] Client
environment:java.library.path=/usr/java/packages/lib/amd64:/usr/lib/x86_64-linux-gnu/jni:/lib/x86_64-linux-gnu:/usr/lib/x86_64-linux-gnu:/usr/lib/jni:/lib:/usr/lib
I0115 22:00:33.992 [TimeSeriesRepositoryImpl STARTING,
TimeSeriesRepositoryImpl:124] Build Info key: time has value 2016-01-15
21:46:25 UTC
I0115 22:00:33.992 [RedirectMonitor STARTING, Environment:100] Client
environment:java.io.tmpdir=/tmp
I0115 22:00:33.995 [RedirectMonitor STARTING, Environment:100] Client
environment:java.compiler=<NA>
I0115 22:00:33.996 [RedirectMonitor STARTING, Environment:100] Client
environment:os.name=Linux
I0115 22:00:33.997 [RedirectMonitor STARTING, Environment:100] Client
environment:os.arch=amd64
I0115 22:00:33.997 [RedirectMonitor STARTING, Environment:100] Client
environment:os.version=3.16.0-4-amd64
I0115 22:00:33.997 [RedirectMonitor STARTING, Environment:100] Client
environment:user.name=root
I0115 22:00:33.998 [RedirectMonitor STARTING, Environment:100] Client
environment:user.home=/root
I0115 22:00:33.999 [RedirectMonitor STARTING, Environment:100] Client
environment:user.dir=/home/vagrant
I0115 22:00:33.999 [RedirectMonitor STARTING, ZooKeeper:438] Initiating client
connection, connectString=localhost:2181 sessionTimeout=4000
watcher=org.apache.aurora.common.zookeeper.ZooKeeperClient$$Lambda$121/1591681696@666bfc2c
I0115 22:00:34.056 [RedirectMonitor STARTING-SendThread(localhost:2181),
ClientCnxn$SendThread:975] Opening socket connection to server
localhost/127.0.0.1:2181. Will not attempt to authenticate using SASL (unknown
error)
I0115 22:00:34.076 [RedirectMonitor STARTING-SendThread(localhost:2181),
ClientCnxn$SendThread:852] Socket connection established to
localhost/127.0.0.1:2181, initiating session
I0115 22:00:34.087 [RedirectMonitor STARTING-SendThread(localhost:2181),
ClientCnxn$SendThread:1235] Session establishment complete on server
localhost/127.0.0.1:2181, sessionid = 0x1524729c3fa001a, negotiated timeout =
4000
W0115 22:00:34.143 [RedirectMonitor STARTING,
ServerSetImpl$ServerSetWatcher:351] server set empty for path /aurora/scheduler
W0115 22:00:34.156 [RedirectMonitor STARTING,
LeaderRedirect$SchedulerMonitor:203] No schedulers in host set, will not
redirect despite not being leader.
I0115 22:00:34.193 [HttpServerLauncher STARTING, Server:345]
jetty-9.3.6.v20151106
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.scheduler.http.api.ApiBeta as a root
resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.common.net.http.handlers.AbortHandler as a
root resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.common.net.http.handlers.ContentionPrinter
as a root resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.scheduler.http.Cron as a root resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.common.net.http.handlers.HealthHandler as a
root resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.scheduler.http.Locks as a root resource
class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.scheduler.http.LogConfig as a root resource
class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.scheduler.http.Maintenance as a root
resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.scheduler.http.Mname as a root resource
class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.scheduler.http.Offers as a root resource
class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.scheduler.http.PendingTasks as a root
resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.common.net.http.handlers.QuitHandler as a
root resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.scheduler.http.Quotas as a root resource
class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.scheduler.http.Services as a root resource
class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.scheduler.http.Slaves as a root resource
class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.scheduler.http.StructDump as a root
resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.common.net.http.handlers.ThreadStackPrinter
as a root resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering
org.apache.aurora.common.net.http.handlers.TimeSeriesDataSource as a root
resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.scheduler.http.Utilization as a root
resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.common.net.http.handlers.VarsHandler as a
root resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register
INFO: Registering org.apache.aurora.common.net.http.handlers.VarsJsonHandler as
a root resource class
Jan 15, 2016 10:00:34 PM
com.sun.jersey.server.impl.application.WebApplicationImpl _initiate
INFO: Initiating Jersey application, version 'Jersey: 1.19 02/11/2015 03:25 AM'
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.scheduler.http.api.ApiBeta to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.common.net.http.handlers.AbortHandler to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.common.net.http.handlers.ContentionPrinter to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.scheduler.http.Cron to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.common.net.http.handlers.HealthHandler to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.scheduler.http.Locks to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.scheduler.http.LogConfig to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.scheduler.http.Maintenance to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.scheduler.http.Mname to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.scheduler.http.Offers to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.scheduler.http.PendingTasks to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.common.net.http.handlers.QuitHandler to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.scheduler.http.Quotas to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.scheduler.http.Services to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.scheduler.http.Slaves to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.scheduler.http.StructDump to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.common.net.http.handlers.ThreadStackPrinter to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.common.net.http.handlers.TimeSeriesDataSource
to GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.scheduler.http.Utilization to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.common.net.http.handlers.VarsHandler to
GuiceManagedComponentProvider with the scope "PerRequest"
Jan 15, 2016 10:00:35 PM
com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory
getComponentProvider
INFO: Binding org.apache.aurora.common.net.http.handlers.VarsJsonHandler to
GuiceManagedComponentProvider with the scope "PerRequest"
W0115 22:00:36.017 [HttpServerLauncher STARTING, CronModule:102] Cron schedules
are configured to fire according to timezone Greenwich Mean Time but system
timezone is set to GMT+00:00
I0115 22:00:36.061 [HttpServerLauncher STARTING, ContextHandler:775] Started
o.e.j.s.ServletContextHandler@3eff6aef{/,null,AVAILABLE}
I0115 22:00:36.078 [HttpServerLauncher STARTING, AbstractConnector:270] Started
ServerConnector@633e5fc7{HTTP/1.1,[http/1.1]}{0.0.0.0:8081}
I0115 22:00:36.079 [HttpServerLauncher STARTING, Server:397] Started @5452ms
I0115 22:00:36.084 [main, StateMachine$Builder:389] SchedulerLifecycle state
machine transition IDLE -> PREPARING_STORAGE
I0115 22:00:36.918 [main, SchedulerMain:193] Application run() exited.
I0115 22:00:36.918 [main, Lifecycle:84] Shutting down application
I0115 22:00:36.919 [main, ShutdownRegistry$ShutdownRegistryImpl:77] Executing 2
shutdown commands.
I0115 22:00:36.921 [main, StateMachine$Builder:389] SchedulerLifecycle state
machine transition PREPARING_STORAGE -> DEAD
W0115 22:00:36.924 [main, ShutdownRegistry$ShutdownRegistryImpl:87] Shutdown
action failed.
org.apache.aurora.common.util.StateMachine$IllegalStateTransitionException:
State transition from CONSTRUCTED to STOPPED is not allowed.
at
org.apache.aurora.common.util.StateMachine.transition(StateMachine.java:153)
~[commons-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.scheduler.storage.CallOrderEnforcingStorage.stop(CallOrderEnforcingStorage.java:107)
~[aurora-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.scheduler.SchedulerLifecycle$7.execute(SchedulerLifecycle.java:313)
~[aurora-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.scheduler.SchedulerLifecycle$7.execute(SchedulerLifecycle.java:287)
~[aurora-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.common.base.Closures.lambda$filter$29(Closures.java:78)
~[commons-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.common.base.Closures.lambda$combine$28(Closures.java:58)
~[commons-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.common.util.StateMachine.transition(StateMachine.java:160)
~[commons-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.scheduler.SchedulerLifecycle$3.execute(SchedulerLifecycle.java:227)
~[aurora-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.common.application.ShutdownRegistry$ShutdownRegistryImpl.execute(ShutdownRegistry.java:85)
~[commons-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.common.application.Lifecycle.shutdown(Lifecycle.java:85)
[commons-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.scheduler.app.SchedulerMain.flagConfiguredMain(SchedulerMain.java:197)
[aurora-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.scheduler.app.SchedulerMain.main(SchedulerMain.java:213)
[aurora-0.12.0-SNAPSHOT.jar:na]
E0115 22:00:36.928 [main, SchedulerMain:162] Uncaught exception from
Thread[main,5,main]:com.google.inject.internal.util.$ComputationException:
java.lang.ArrayIndexOutOfBoundsException: 18299
com.google.inject.internal.util.$ComputationException:
java.lang.ArrayIndexOutOfBoundsException: 18299
at
com.google.inject.internal.util.$MapMaker$StrategyImpl.compute(MapMaker.java:553)
~[guice-3.0.jar:na]
at
com.google.inject.internal.util.$MapMaker$StrategyImpl.compute(MapMaker.java:419)
~[guice-3.0.jar:na]
at
com.google.inject.internal.util.$CustomConcurrentHashMap$ComputingImpl.get(CustomConcurrentHashMap.java:2041)
~[guice-3.0.jar:na]
at
com.google.inject.internal.util.$StackTraceElements.forMember(StackTraceElements.java:53)
~[guice-3.0.jar:na]
at com.google.inject.internal.Errors.formatSource(Errors.java:690)
~[guice-3.0.jar:na]
at com.google.inject.internal.Errors.format(Errors.java:555)
~[guice-3.0.jar:na]
at
com.google.inject.ProvisionException.getMessage(ProvisionException.java:59)
~[guice-3.0.jar:na]
at java.lang.Throwable.getLocalizedMessage(Throwable.java:391)
~[na:1.8.0_66-internal]
at java.lang.Throwable.toString(Throwable.java:480)
~[na:1.8.0_66-internal]
at java.lang.String.valueOf(String.java:2994) ~[na:1.8.0_66-internal]
at java.lang.StringBuilder.append(StringBuilder.java:131)
~[na:1.8.0_66-internal]
at
org.apache.aurora.scheduler.SchedulerLifecycle.lambda$dieOnError$38(SchedulerLifecycle.java:358)
~[aurora-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.common.base.Closures.lambda$filter$29(Closures.java:78)
~[commons-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.common.base.Closures.lambda$combine$28(Closures.java:58)
~[commons-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.common.util.StateMachine.transition(StateMachine.java:160)
~[commons-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.scheduler.SchedulerLifecycle.prepare(SchedulerLifecycle.java:373)
~[aurora-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.scheduler.app.SchedulerMain.run(SchedulerMain.java:118)
~[aurora-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.scheduler.app.SchedulerMain.flagConfiguredMain(SchedulerMain.java:191)
~[aurora-0.12.0-SNAPSHOT.jar:na]
at
org.apache.aurora.scheduler.app.SchedulerMain.main(SchedulerMain.java:213)
~[aurora-0.12.0-SNAPSHOT.jar:na]
Caused by: java.lang.ArrayIndexOutOfBoundsException: 18299
at com.google.inject.internal.asm.$ClassReader.<init>(Unknown Source)
~[guice-3.0.jar:na]
at com.google.inject.internal.asm.$ClassReader.<init>(Unknown Source)
~[guice-3.0.jar:na]
at com.google.inject.internal.asm.$ClassReader.<init>(Unknown Source)
~[guice-3.0.jar:na]
at
com.google.inject.internal.util.$LineNumbers.<init>(LineNumbers.java:62)
~[guice-3.0.jar:na]
at
com.google.inject.internal.util.$StackTraceElements$1.apply(StackTraceElements.java:36)
~[guice-3.0.jar:na]
at
com.google.inject.internal.util.$StackTraceElements$1.apply(StackTraceElements.java:33)
~[guice-3.0.jar:na]
at
com.google.inject.internal.util.$MapMaker$StrategyImpl.compute(MapMaker.java:549)
~[guice-3.0.jar:na]
... 18 common frames omitted
{noformat}
This flag is defaulted to
[null|https://github.com/apache/aurora/blob/master/src/main/java/org/apache/aurora/scheduler/log/mesos/MesosLogStreamModule.java#L56-L59]
but no sanity checks are used [on
access|https://github.com/apache/aurora/blob/master/src/main/java/org/apache/aurora/scheduler/log/mesos/MesosLogStreamModule.java#L117-L129].
This leads to the confusing error presentation.
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
