aurora-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Zameer Manji (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (AURORA-1587) scheduler fails in a hard to decipher way when -native_log_file_path is not set
Date Fri, 15 Jan 2016 23:46:39 GMT

    [ https://issues.apache.org/jira/browse/AURORA-1587?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15102709#comment-15102709
] 

Zameer Manji commented on AURORA-1587:
--------------------------------------

I think this is also confusing because of the guice mangling as a result of not being on guice
4.0. Linking this to AURORA-1380

> 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)

Mime
View raw message