mesos-builds mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject Build failed in Jenkins: Mesos-Reviewbot #16866
Date Thu, 26 Jan 2017 20:10:30 GMT
See <https://builds.apache.org/job/Mesos-Reviewbot/16866/>

------------------------------------------
[...truncated 137398 lines...]
I0126 20:10:24.237138 30177 executor.cpp:401] Connected with the agent
I0126 20:10:24.237817 30171 executor.cpp:302] Sending SUBSCRIBE call to http://172.17.0.4:41449/slave(498)/api/v1/executor
I0126 20:10:24.239826 30175 process.cpp:3697] Handling HTTP event for process 'slave(498)'
with path: '/slave(498)/api/v1/executor'
I0126 20:10:24.241112 30175 http.cpp:303] HTTP POST for /slave(498)/api/v1/executor from 172.17.0.4:50173
I0126 20:10:24.241425 30175 slave.cpp:3109] Received Subscribe request for HTTP executor 'default'
of framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
I0126 20:10:24.243742 30175 slave.cpp:2300] Sending queued task group task group containing
tasks [ 8c50f5e6-5719-4f9d-9b87-87770abd727c, 93547bc5-33eb-446d-b098-b1bea6f370e7 ] to executor
'default' of framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000 (via HTTP)
I0126 20:10:24.246196 30180 executor.cpp:716] Enqueuing event SUBSCRIBED received from http://172.17.0.4:41449/slave(498)/api/v1/executor
I0126 20:10:24.248723 30180 executor.cpp:716] Enqueuing event LAUNCH_GROUP received from http://172.17.0.4:41449/slave(498)/api/v1/executor
I0126 20:10:24.251953 30183 master.cpp:1378] Framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
(default) disconnected
I0126 20:10:24.251987 30183 master.cpp:3078] Deactivating framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
(default)
I0126 20:10:24.252060 30183 master.cpp:3055] Disconnecting framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
(default)
I0126 20:10:24.252100 30183 master.cpp:1393] Giving framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
(default) 0ns to failover
I0126 20:10:24.252480 30183 hierarchical.cpp:386] Deactivated framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
I0126 20:10:24.253293 30174 master.cpp:6682] Framework failover timeout, removing framework
cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000 (default)
I0126 20:10:24.253324 30174 master.cpp:7555] Removing framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
(default)
I0126 20:10:24.253743 30181 slave.cpp:2598] Asked to shut down framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
by master@172.17.0.4:41449
I0126 20:10:24.253793 30181 slave.cpp:2623] Shutting down framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
I0126 20:10:24.253847 30181 slave.cpp:5005] Shutting down executor 'default' of framework
cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000 (via HTTP)
I0126 20:10:24.253748 30174 master.cpp:8138] Updating the state of task 93547bc5-33eb-446d-b098-b1bea6f370e7
of framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000 (latest state: TASK_KILLED, status
update state: TASK_KILLED)
I0126 20:10:24.254644 30174 master.cpp:8232] Removing task 93547bc5-33eb-446d-b098-b1bea6f370e7
with resources cpus(*):0.1; mem(*):32; disk(*):32 of framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
on agent cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0 at slave(498)@172.17.0.4:41449 (ea8ba3ca7e49)
I0126 20:10:24.254676 30182 hierarchical.cpp:1011] Recovered cpus(*):0.1; mem(*):32; disk(*):32
(total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):0.2;
mem(*):64; disk(*):64) on agent cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0 from framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
I0126 20:10:24.255168 30174 master.cpp:8138] Updating the state of task 8c50f5e6-5719-4f9d-9b87-87770abd727c
of framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000 (latest state: TASK_KILLED, status
update state: TASK_KILLED)
I0126 20:10:24.255635 30169 hierarchical.cpp:1011] Recovered cpus(*):0.1; mem(*):32; disk(*):32
(total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):0.1;
mem(*):32; disk(*):32) on agent cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0 from framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
I0126 20:10:24.256153 30169 executor.cpp:716] Enqueuing event SHUTDOWN received from http://172.17.0.4:41449/slave(498)/api/v1/executor
I0126 20:10:24.257215 30174 master.cpp:8232] Removing task 8c50f5e6-5719-4f9d-9b87-87770abd727c
with resources cpus(*):0.1; mem(*):32; disk(*):32 of framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
on agent cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0 at slave(498)@172.17.0.4:41449 (ea8ba3ca7e49)
I0126 20:10:24.257697 30174 master.cpp:8261] Removing executor 'default' with resources cpus(*):0.1;
mem(*):32; disk(*):32 of framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000 on agent cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0
at slave(498)@172.17.0.4:41449 (ea8ba3ca7e49)
I0126 20:10:24.258252 30180 hierarchical.cpp:1011] Recovered cpus(*):0.1; mem(*):32; disk(*):32
(total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent
cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0 from framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
I0126 20:10:24.258985 30183 hierarchical.cpp:337] Removed framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
I0126 20:10:24.261154 30184 slave.cpp:4691] Executor 'default' of framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
exited with status 0
I0126 20:10:24.261232 30184 slave.cpp:4791] Cleaning up executor 'default' of framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
(via HTTP)
I0126 20:10:24.261559 30184 slave.cpp:4879] Cleaning up framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
W0126 20:10:24.262280 30178 master.cpp:6132] Ignoring unknown exited executor 'default' of
framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000 on agent cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0
at slave(498)@172.17.0.4:41449 (ea8ba3ca7e49)
I0126 20:10:24.262300 30184 gc.cpp:55] Scheduling '/tmp/SlaveTest_RunTaskGroup_K8rB7p/slaves/cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0/frameworks/cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000/executors/default/runs/1e2cba66-af6f-4e10-8889-88eed612c95d'
for gc 6.99999697457185days in the future
I0126 20:10:24.262344 30178 status_update_manager.cpp:285] Closing status update streams for
framework cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000
I0126 20:10:24.262459 30184 gc.cpp:55] Scheduling '/tmp/SlaveTest_RunTaskGroup_K8rB7p/slaves/cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0/frameworks/cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000/executors/default'
for gc 6.99999697320593days in the future
I0126 20:10:24.262542 30184 gc.cpp:55] Scheduling '/tmp/SlaveTest_RunTaskGroup_K8rB7p/slaves/cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0/frameworks/cc5a1ff8-d4c5-445b-a940-87c340f0a364-0000'
for gc 6.99999697160889days in the future
I0126 20:10:24.263296 30178 slave.cpp:801] Agent terminating
I0126 20:10:24.263531 30178 master.cpp:1261] Agent cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0
at slave(498)@172.17.0.4:41449 (ea8ba3ca7e49) disconnected
I0126 20:10:24.263607 30178 master.cpp:3115] Disconnecting agent cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0
at slave(498)@172.17.0.4:41449 (ea8ba3ca7e49)
I0126 20:10:24.263669 30178 master.cpp:3134] Deactivating agent cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0
at slave(498)@172.17.0.4:41449 (ea8ba3ca7e49)
I0126 20:10:24.263850 30178 hierarchical.cpp:577] Agent cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0
deactivated
I0126 20:10:24.268180 30150 master.cpp:1100] Master terminating
I0126 20:10:24.268926 30182 hierarchical.cpp:510] Removed agent cc5a1ff8-d4c5-445b-a940-87c340f0a364-S0
[       OK ] SlaveTest.RunTaskGroup (106 ms)
[ RUN      ] SlaveTest.KillTaskGroupBetweenRunTaskParts
I0126 20:10:24.276473 30150 cluster.cpp:160] Creating default 'local' authorizer
I0126 20:10:24.280118 30181 master.cpp:383] Master 716243b9-cdb5-4a7d-b10b-116e8b611563 (ea8ba3ca7e49)
started on 172.17.0.4:41449
I0126 20:10:24.280308 30181 master.cpp:385] Flags at startup: --acls="" --agent_ping_timeout="15secs"
--agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF"
--authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true"
--authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5"
--authorizers="local" --credentials="/tmp/SGp74D/credentials" --framework_sorter="drf" --help="false"
--hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic"
--initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
--max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000"
--max_unreachable_tasks_per_framework="1000" --quiet="false" --recovery_agent_removal_limit="100%"
--registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks"
--registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false"
--root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.2.0/_inst/share/mesos/webui"
--work_dir="/tmp/SGp74D/master" --zk_session_timeout="10secs"
I0126 20:10:24.280663 30181 master.cpp:435] Master only allowing authenticated frameworks
to register
I0126 20:10:24.280727 30181 master.cpp:449] Master only allowing authenticated agents to register
I0126 20:10:24.280747 30181 master.cpp:462] Master only allowing authenticated HTTP frameworks
to register
I0126 20:10:24.280764 30181 credentials.hpp:37] Loading credentials for authentication from
'/tmp/SGp74D/credentials'
I0126 20:10:24.281090 30181 master.cpp:507] Using default 'crammd5' authenticator
I0126 20:10:24.281246 30181 http.cpp:922] Using default 'basic' HTTP authenticator for realm
'mesos-master-readonly'
I0126 20:10:24.281412 30181 http.cpp:922] Using default 'basic' HTTP authenticator for realm
'mesos-master-readwrite'
I0126 20:10:24.281669 30181 http.cpp:922] Using default 'basic' HTTP authenticator for realm
'mesos-master-scheduler'
I0126 20:10:24.281816 30181 master.cpp:587] Authorization enabled
I0126 20:10:24.282063 30177 hierarchical.cpp:151] Initialized hierarchical allocator process
I0126 20:10:24.282126 30177 whitelist_watcher.cpp:77] No whitelist given
I0126 20:10:24.284348 30175 master.cpp:2121] Elected as the leading master!
I0126 20:10:24.284490 30175 master.cpp:1643] Recovering from registrar
I0126 20:10:24.284657 30169 registrar.cpp:329] Recovering registrar
I0126 20:10:24.285960 30169 registrar.cpp:362] Successfully fetched the registry (0B) in 916992ns
I0126 20:10:24.286070 30169 registrar.cpp:461] Applied 1 operations in 24030ns; attempting
to update the registry
I0126 20:10:24.287216 30169 registrar.cpp:506] Successfully updated the registry in 1.090048ms
I0126 20:10:24.287367 30169 registrar.cpp:392] Successfully recovered registrar
I0126 20:10:24.287811 30169 master.cpp:1759] Recovered 0 agents from the registry (129B);
allowing 10mins for agents to re-register
I0126 20:10:24.288025 30169 hierarchical.cpp:178] Skipping recovery of hierarchical allocator:
nothing to recover
I0126 20:10:24.294956 30176 slave.cpp:209] Mesos agent started on @172.17.0.4:41449
I0126 20:10:24.294980 30176 slave.cpp:210] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"
--appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true"
--authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false"
--cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_KO9rsL/credential"
--default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true"
--docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock"
--docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_KO9rsL/fetch" --fetcher_cache_size="2GB"
--frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false"
--hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_KO9rsL/http_credentials"
--http_heartbeat_interval="30secs" --image_provisioner_backend="copy" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.0/_build/src"
--logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs"
--perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false"
--recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]"
--revocable_cpu_low_priority="true" --runtime_dir="/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_KO9rsL"
--sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true"
--systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_EVbMLc"
I0126 20:10:24.295532 30176 credentials.hpp:86] Loading credential for authentication from
'/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_KO9rsL/credential'
I0126 20:10:24.295717 30176 slave.cpp:352] Agent using credential for: test-principal
I0126 20:10:24.295745 30176 credentials.hpp:37] Loading credentials for authentication from
'/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_KO9rsL/http_credentials'
I0126 20:10:24.295836 30150 scheduler.cpp:184] Version: 1.2.0
I0126 20:10:24.296056 30176 http.cpp:922] Using default 'basic' HTTP authenticator for realm
'mesos-agent-readonly'
I0126 20:10:24.296288 30176 http.cpp:922] Using default 'basic' HTTP authenticator for realm
'mesos-agent-readwrite'
I0126 20:10:24.296789 30172 scheduler.cpp:470] New master detected at master@172.17.0.4:41449
I0126 20:10:24.296826 30172 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection)
attempt with the master
I0126 20:10:24.297610 30176 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000]
I0126 20:10:24.297705 30176 slave.cpp:547] Agent attributes: [  ]
I0126 20:10:24.297721 30176 slave.cpp:552] Agent hostname: ea8ba3ca7e49
I0126 20:10:24.297937 30172 status_update_manager.cpp:177] Pausing sending status updates
I0126 20:10:24.299406 30176 state.cpp:60] Recovering state from '/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_EVbMLc/meta'
I0126 20:10:24.299911 30176 status_update_manager.cpp:203] Recovering status update manager
I0126 20:10:24.301638 30178 scheduler.cpp:361] Connected with the master at http://172.17.0.4:41449/master/api/v1/scheduler
I0126 20:10:24.302858 30176 slave.cpp:5422] Finished recovery
I0126 20:10:24.303102 30178 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.4:41449/master/api/v1/scheduler
I0126 20:10:24.304105 30176 slave.cpp:5596] Querying resource estimator for oversubscribable
resources
I0126 20:10:24.304476 30184 slave.cpp:929] New master detected at master@172.17.0.4:41449
I0126 20:10:24.304591 30184 slave.cpp:964] Detecting new master
I0126 20:10:24.304700 30172 status_update_manager.cpp:177] Pausing sending status updates
I0126 20:10:24.305043 30178 process.cpp:3697] Handling HTTP event for process 'master' with
path: '/master/api/v1/scheduler'
I0126 20:10:24.307143 30181 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:50176
I0126 20:10:24.307332 30181 master.cpp:2420] Received subscription request for HTTP framework
'default'
I0126 20:10:24.307399 30181 master.cpp:2157] Authorizing framework principal 'test-principal'
to receive offers for role '*'
I0126 20:10:24.307889 30181 master.cpp:2534] Subscribing framework 'default' with checkpointing
disabled and capabilities [  ]
I0126 20:10:24.308635 30181 hierarchical.cpp:271] Added framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.308709 30181 hierarchical.cpp:1677] No allocations performed
I0126 20:10:24.308738 30181 hierarchical.cpp:1772] No inverse offers to send out!
I0126 20:10:24.308791 30181 hierarchical.cpp:1279] Performed allocation for 0 agents in 129317ns
I0126 20:10:24.308986 30181 master.hpp:2101] Sending heartbeat to 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.311672 30179 slave.cpp:991] Authenticating with master master@172.17.0.4:41449
I0126 20:10:24.311746 30179 slave.cpp:1002] Using default CRAM-MD5 authenticatee
I0126 20:10:24.311985 30179 authenticatee.cpp:121] Creating new client SASL connection
I0126 20:10:24.312249 30179 master.cpp:7001] Authenticating (558)@172.17.0.4:41449
I0126 20:10:24.312414 30179 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1023)@172.17.0.4:41449
I0126 20:10:24.312698 30182 authenticator.cpp:98] Creating new server SASL connection
I0126 20:10:24.312928 30182 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
I0126 20:10:24.312958 30182 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
I0126 20:10:24.313040 30182 authenticator.cpp:204] Received SASL authentication start
I0126 20:10:24.313099 30182 authenticator.cpp:326] Authentication requires more steps
I0126 20:10:24.313176 30182 authenticatee.cpp:259] Received SASL authentication step
I0126 20:10:24.313266 30182 authenticator.cpp:232] Received SASL authentication step
I0126 20:10:24.313297 30182 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'ea8ba3ca7e49' server FQDN: 'ea8ba3ca7e49' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0126 20:10:24.313313 30182 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0126 20:10:24.313347 30182 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0126 20:10:24.313367 30182 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'ea8ba3ca7e49' server FQDN: 'ea8ba3ca7e49' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0126 20:10:24.313382 30182 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I0126 20:10:24.313391 30182 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I0126 20:10:24.313408 30182 authenticator.cpp:318] Authentication success
I0126 20:10:24.313518 30182 authenticatee.cpp:299] Authentication success
I0126 20:10:24.313740 30182 slave.cpp:1086] Successfully authenticated with master master@172.17.0.4:41449
I0126 20:10:24.313901 30182 slave.cpp:1508] Will retry registration in 16.472882ms if necessary
I0126 20:10:24.314122 30182 master.cpp:5207] Queuing up registration request from (558)@172.17.0.4:41449
because authentication is still in progress
I0126 20:10:24.314522 30179 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1023)@172.17.0.4:41449
I0126 20:10:24.314684 30179 master.cpp:5207] Queuing up registration request from (558)@172.17.0.4:41449
because authentication is still in progress
I0126 20:10:24.314919 30179 master.cpp:7031] Successfully authenticated principal 'test-principal'
at (558)@172.17.0.4:41449
I0126 20:10:24.315052 30179 master.cpp:5299] Registering agent at (558)@172.17.0.4:41449 (ea8ba3ca7e49)
with id 716243b9-cdb5-4a7d-b10b-116e8b611563-S0
I0126 20:10:24.315544 30179 registrar.cpp:461] Applied 1 operations in 57445ns; attempting
to update the registry
I0126 20:10:24.317189 30182 registrar.cpp:506] Successfully updated the registry in 1.58464ms
I0126 20:10:24.318117 30178 master.cpp:5370] Registered agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0
at (558)@172.17.0.4:41449 (ea8ba3ca7e49) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0126 20:10:24.318351 30172 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.4:41449/master/api/v1/scheduler
I0126 20:10:24.318806 30184 hierarchical.cpp:478] Added agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0
(ea8ba3ca7e49) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated:
{})
I0126 20:10:24.318441 30169 slave.cpp:1132] Registered with master master@172.17.0.4:41449;
given agent ID 716243b9-cdb5-4a7d-b10b-116e8b611563-S0
I0126 20:10:24.319618 30172 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.4:41449/master/api/v1/scheduler
I0126 20:10:24.319650 30169 fetcher.cpp:90] Clearing fetcher cache
I0126 20:10:24.320075 30171 status_update_manager.cpp:184] Resuming sending status updates
I0126 20:10:24.320312 30184 hierarchical.cpp:1772] No inverse offers to send out!
I0126 20:10:24.320392 30184 hierarchical.cpp:1302] Performed allocation for agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0
in 1.535261ms
I0126 20:10:24.320880 30183 master.cpp:6830] Sending 1 offers to framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
(default)
I0126 20:10:24.321194 30169 slave.cpp:1160] Checkpointing SlaveInfo to '/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_EVbMLc/meta/slaves/716243b9-cdb5-4a7d-b10b-116e8b611563-S0/slave.info'
I0126 20:10:24.321621 30169 slave.cpp:4286] Received ping from slave-observer(476)@172.17.0.4:41449
I0126 20:10:24.322808 30169 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.4:41449/master/api/v1/scheduler
I0126 20:10:24.325832 30173 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.4:41449/master/api/v1/scheduler
I0126 20:10:24.328438 30180 process.cpp:3697] Handling HTTP event for process 'master' with
path: '/master/api/v1/scheduler'
I0126 20:10:24.330780 30173 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:50175
I0126 20:10:24.332000 30173 master.cpp:3728] Processing ACCEPT call for offers: [ 716243b9-cdb5-4a7d-b10b-116e8b611563-O0
] on agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0 at (558)@172.17.0.4:41449 (ea8ba3ca7e49)
for framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000 (default)
I0126 20:10:24.332252 30173 master.cpp:3316] Authorizing framework principal 'test-principal'
to launch task 5134e169-2f5e-4430-8781-cdd10444b349
I0126 20:10:24.332782 30173 master.cpp:3316] Authorizing framework principal 'test-principal'
to launch task 8cbaed64-a867-4de1-b33c-b27113825f12
I0126 20:10:24.338587 30173 master.cpp:8882] Adding task 5134e169-2f5e-4430-8781-cdd10444b349
with resources cpus(*):0.1; mem(*):32; disk(*):32 on agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0
at (558)@172.17.0.4:41449 (ea8ba3ca7e49)
I0126 20:10:24.339114 30173 master.cpp:8882] Adding task 8cbaed64-a867-4de1-b33c-b27113825f12
with resources cpus(*):0.1; mem(*):32; disk(*):32 on agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0
at (558)@172.17.0.4:41449 (ea8ba3ca7e49)
I0126 20:10:24.339392 30173 master.cpp:4583] Launching task group { 5134e169-2f5e-4430-8781-cdd10444b349,
8cbaed64-a867-4de1-b33c-b27113825f12 } of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
(default) with resources cpus(*):0.2; mem(*):64; disk(*):64 on agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0
at (558)@172.17.0.4:41449 (ea8ba3ca7e49)
I0126 20:10:24.340045 30171 slave.cpp:1576] Got assigned task group containing tasks [ 5134e169-2f5e-4430-8781-cdd10444b349,
8cbaed64-a867-4de1-b33c-b27113825f12 ] for framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.340777 30173 hierarchical.cpp:1011] Recovered cpus(*):1.7; mem(*):928; disk(*):928;
ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000],
allocated: cpus(*):0.3; mem(*):96; disk(*):96) on agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0
from framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.341073 30173 hierarchical.cpp:1048] Framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
filtered agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0 for 5secs
I0126 20:10:24.342150 30177 scheduler.cpp:243] Sending KILL call to http://172.17.0.4:41449/master/api/v1/scheduler
I0126 20:10:24.343685 30173 process.cpp:3697] Handling HTTP event for process 'master' with
path: '/master/api/v1/scheduler'
I0126 20:10:24.382985 30175 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:50175
I0126 20:10:24.383766 30175 master.cpp:4834] Processing KILL call for task '5134e169-2f5e-4430-8781-cdd10444b349'
of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000 (default)
I0126 20:10:24.384122 30175 master.cpp:4907] Telling agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0
at (558)@172.17.0.4:41449 (ea8ba3ca7e49) to kill task 5134e169-2f5e-4430-8781-cdd10444b349
of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000 (default)
I0126 20:10:24.384672 30169 slave.cpp:2368] Asked to kill task 5134e169-2f5e-4430-8781-cdd10444b349
of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
W0126 20:10:24.384929 30169 slave.cpp:2409] Killing task 5134e169-2f5e-4430-8781-cdd10444b349
of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000 before it was launched
I0126 20:10:24.386000 30150 slave.cpp:1736] Launching task group containing tasks [ 5134e169-2f5e-4430-8781-cdd10444b349,
8cbaed64-a867-4de1-b33c-b27113825f12 ] for framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
W0126 20:10:24.386327 30150 slave.cpp:1776] Ignoring running task group containing tasks [
5134e169-2f5e-4430-8781-cdd10444b349, 8cbaed64-a867-4de1-b33c-b27113825f12 ] of framework
716243b9-cdb5-4a7d-b10b-116e8b611563-0000 because it has been killed in the meantime
I0126 20:10:24.386693 30150 slave.cpp:3756] Handling status update TASK_KILLED (UUID: 55406bff-d6e1-4762-aea2-b2a8d860c2df)
for task 5134e169-2f5e-4430-8781-cdd10444b349 of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
from @0.0.0.0:0
W0126 20:10:24.387312 30150 slave.cpp:3852] Could not find the executor for status update
TASK_KILLED (UUID: 55406bff-d6e1-4762-aea2-b2a8d860c2df) for task 5134e169-2f5e-4430-8781-cdd10444b349
of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.388023 30180 status_update_manager.cpp:323] Received status update TASK_KILLED
(UUID: 55406bff-d6e1-4762-aea2-b2a8d860c2df) for task 5134e169-2f5e-4430-8781-cdd10444b349
of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.388109 30180 status_update_manager.cpp:500] Creating StatusUpdate stream for
task 5134e169-2f5e-4430-8781-cdd10444b349 of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.388165 30150 slave.cpp:3756] Handling status update TASK_KILLED (UUID: 766ba77a-df9a-40e8-b2c1-12d01ff8a747)
for task 8cbaed64-a867-4de1-b33c-b27113825f12 of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
from @0.0.0.0:0
W0126 20:10:24.388393 30150 slave.cpp:3852] Could not find the executor for status update
TASK_KILLED (UUID: 766ba77a-df9a-40e8-b2c1-12d01ff8a747) for task 8cbaed64-a867-4de1-b33c-b27113825f12
of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.388741 30180 status_update_manager.cpp:377] Forwarding update TASK_KILLED (UUID:
55406bff-d6e1-4762-aea2-b2a8d860c2df) for task 5134e169-2f5e-4430-8781-cdd10444b349 of framework
716243b9-cdb5-4a7d-b10b-116e8b611563-0000 to the agent
I0126 20:10:24.388864 30150 slave.cpp:4879] Cleaning up framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
E0126 20:10:24.388996 30150 slave.cpp:5576] Failed to find the mtime of '/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_EVbMLc/slaves/716243b9-cdb5-4a7d-b10b-116e8b611563-S0/frameworks/716243b9-cdb5-4a7d-b10b-116e8b611563-0000':
Error invoking stat for '/tmp/SlaveTest_KillTaskGroupBetweenRunTaskParts_EVbMLc/slaves/716243b9-cdb5-4a7d-b10b-116e8b611563-S0/frameworks/716243b9-cdb5-4a7d-b10b-116e8b611563-0000':
No such file or directory
I0126 20:10:24.389086 30170 slave.cpp:4196] Forwarding the update TASK_KILLED (UUID: 55406bff-d6e1-4762-aea2-b2a8d860c2df)
for task 5134e169-2f5e-4430-8781-cdd10444b349 of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
to master@172.17.0.4:41449
I0126 20:10:24.389118 30180 status_update_manager.cpp:323] Received status update TASK_KILLED
(UUID: 766ba77a-df9a-40e8-b2c1-12d01ff8a747) for task 8cbaed64-a867-4de1-b33c-b27113825f12
of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.389168 30180 status_update_manager.cpp:500] Creating StatusUpdate stream for
task 8cbaed64-a867-4de1-b33c-b27113825f12 of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.389492 30180 status_update_manager.cpp:377] Forwarding update TASK_KILLED (UUID:
766ba77a-df9a-40e8-b2c1-12d01ff8a747) for task 8cbaed64-a867-4de1-b33c-b27113825f12 of framework
716243b9-cdb5-4a7d-b10b-116e8b611563-0000 to the agent
I0126 20:10:24.389520 30169 master.cpp:6008] Status update TASK_KILLED (UUID: 55406bff-d6e1-4762-aea2-b2a8d860c2df)
for task 5134e169-2f5e-4430-8781-cdd10444b349 of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
from agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0 at (558)@172.17.0.4:41449 (ea8ba3ca7e49)
I0126 20:10:24.389598 30169 master.cpp:6076] Forwarding status update TASK_KILLED (UUID: 55406bff-d6e1-4762-aea2-b2a8d860c2df)
for task 5134e169-2f5e-4430-8781-cdd10444b349 of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.389782 30180 status_update_manager.cpp:285] Closing status update streams for
framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.389835 30180 status_update_manager.cpp:531] Cleaning up status update stream
for task 8cbaed64-a867-4de1-b33c-b27113825f12 of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.389976 30169 master.cpp:8138] Updating the state of task 5134e169-2f5e-4430-8781-cdd10444b349
of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000 (latest state: TASK_KILLED, status
update state: TASK_KILLED)
I0126 20:10:24.390069 30180 status_update_manager.cpp:531] Cleaning up status update stream
for task 5134e169-2f5e-4430-8781-cdd10444b349 of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.391000 30169 hierarchical.cpp:1011] Recovered cpus(*):0.1; mem(*):32; disk(*):32
(total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):0.2;
mem(*):64; disk(*):64) on agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0 from framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.391144 30170 slave.cpp:4090] Status update manager successfully handled status
update TASK_KILLED (UUID: 55406bff-d6e1-4762-aea2-b2a8d860c2df) for task 5134e169-2f5e-4430-8781-cdd10444b349
of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.391252 30170 slave.cpp:4196] Forwarding the update TASK_KILLED (UUID: 766ba77a-df9a-40e8-b2c1-12d01ff8a747)
for task 8cbaed64-a867-4de1-b33c-b27113825f12 of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
to master@172.17.0.4:41449
I0126 20:10:24.391408 30170 slave.cpp:4090] Status update manager successfully handled status
update TASK_KILLED (UUID: 766ba77a-df9a-40e8-b2c1-12d01ff8a747) for task 8cbaed64-a867-4de1-b33c-b27113825f12
of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.391521 30174 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.4:41449/master/api/v1/scheduler
I0126 20:10:24.391535 30177 master.cpp:6008] Status update TASK_KILLED (UUID: 766ba77a-df9a-40e8-b2c1-12d01ff8a747)
for task 8cbaed64-a867-4de1-b33c-b27113825f12 of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
from agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0 at (558)@172.17.0.4:41449 (ea8ba3ca7e49)
I0126 20:10:24.391584 30177 master.cpp:6076] Forwarding status update TASK_KILLED (UUID: 766ba77a-df9a-40e8-b2c1-12d01ff8a747)
for task 8cbaed64-a867-4de1-b33c-b27113825f12 of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.391836 30177 master.cpp:8138] Updating the state of task 8cbaed64-a867-4de1-b33c-b27113825f12
of framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000 (latest state: TASK_KILLED, status
update state: TASK_KILLED)
I0126 20:10:24.392592 30178 hierarchical.cpp:1011] Recovered cpus(*):0.1; mem(*):32; disk(*):32
(total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):0.1;
mem(*):32; disk(*):32) on agent 716243b9-cdb5-4a7d-b10b-116e8b611563-S0 from framework 716243b9-cdb5-4a7d-b10b-116e8b611563-0000
I0126 20:10:24.393805 30171 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.4:41449/master/api/v1/scheduler
I0126 20:10:24.397018 30179 slave.cpp:801] Agent terminating
*** Aborted at 1485461424 (unix time) try "date -d @1485461424" if you are using GNU date
***
PC: @     0x2b563e422cd8 mesos::FrameworkInfo::checkpoint()
*** SIGSEGV (@0xa0) received by PID 30150 (TID 0x2b564b1b5700) from PID 160; stack trace:
***
    @     0x2b56771bb197 (unknown)
    @     0x2b56771bf479 (unknown)
    @     0x2b564193a330 (unknown)
    @     0x2b563e422cd8 mesos::FrameworkInfo::checkpoint()
    @     0x2b563e919a15 mesos::internal::slave::Slave::finalize()
    @     0x2b563f69e933 process::ProcessBase::visit()
    @     0x2b563f6a6e84 process::TerminateEvent::visit()
    @     0x2b5637a55a7c process::ProcessBase::serve()
    @     0x2b563f6987ee process::ProcessManager::resume()
    @     0x2b563f695032 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
    @     0x2b563f6a64ba _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
    @     0x2b563f6a6411 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
    @     0x2b563f6a63aa _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
    @     0x2b56411bba60 (unknown)
    @     0x2b5641932184 start_thread
    @     0x2b5641c4237d (unknown)
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-1.2.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.2.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.2.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.2.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1485458479-625
Untagged: mesos-1485458479-625:latest
Deleted: sha256:212e6babe1a5b4104dda2ec01b08a518b6d03d52305e10113d0724677e5fe82c
Deleted: sha256:396e294ff44cc8dfb097661628ac7590f099883f97e1dd2a1d0b214d30011d2f
Deleted: sha256:a90fefc5df1dacb16282e9a67085e41a33c642ab38cc3b4b3817e001d83dc4ec
Deleted: sha256:5cf04b43bb5619db76fdf26c939e5ecc6a50bb31271586baf6a86dc3a7e84853
Deleted: sha256:eed9ddc005fd3e0ac0dc2c818fa28c35b9847f122030fa19d11454e7469bd03b
Deleted: sha256:e0c85f7f9595670e3d14960185375aa56c9ceca0d7bd8f345e6ebf1fb45ad463
Deleted: sha256:23199a4b2fa7f69f570ce103b91247a646115bbce12ba443c5825c66cfc9f6dd
Deleted: sha256:2f8d3cb5dbfb8f97b2d840896ca7d8320c8429bd481f51182be628cd0520c0fa
Deleted: sha256:2602de8be6103e827605d1d4878591c1d0651576ea498b6aa0736928362328a0
Deleted: sha256:cc665fa323bc70dd9313c7e395f7ce4d095fe213282a45bcfca7ce55e9ede505
Deleted: sha256:0e86c0d28df84c0395644ca071d442529e088f2a24fe174e6e8a13224b8bd1e3
Deleted: sha256:24af47e9f5a4775028721730a58e2c4f47a6fffa7899ec5c960df8f9c5a38825
Deleted: sha256:7559792744573aa7e209a2b61fa42553d5ba7c6c2ec2629f7c27ebfbdacef53b
Deleted: sha256:ab1816783d8c9abb856f64bb83c649f04f4310eaea17c2c1fa41cd35aff41dea
Deleted: sha256:9a3d49d71e1003e16d6d332f1f5773786e1c7eeef3e06f86363eb6cd94b9da70
Deleted: sha256:692206c61a903cc701b4c39a08024552a06ef561927ec9b2496380f286631a2b
Deleted: sha256:bd866322a8c633e16783f15a43200b8ba2b5594c79c8c88def6b3453abd380ef
Deleted: sha256:c977565a0e7b052c42eecd513e9c5f509ab53e576f2a3a74eeb07a806294d210
Deleted: sha256:fb2dec4ee906406609a40bd1153640d2031df42bc87ece6757bd1ff0ceea862c
Deleted: sha256:8b6c721f6e68209c90b1337f0e9dd994677d9ff0fb19f9441457085995cc4feb
Deleted: sha256:6d8c14fec00a49c62b07a1b9247cb9cfa0875d3da187be0ec5f61ffb558b9dd1
Deleted: sha256:c29d7749333e9d645b99e1ed7763e5e88ee9291153d1514e2f2fd7bcb8ca6db3
Deleted: sha256:be42730812d823cf8cef6fda5a7fb04a17e7e9504d5652259361dfe9a019fda2
Deleted: sha256:e626f8b4d53c863674e7a1b04f815a2962ba430d3d270a91262de50e6a18dc01
Build step 'Execute shell' marked build as failure

Mime
View raw message