You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/12/14 11:20:34 UTC
Build failed in Jenkins: Mesos » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2) #3035
See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3035/changes>
Changes:
[adam] Added authorization actions VIEW_CONTAINERS and SET_LOG_LEVEL.
[adam] Enabled fine grained authorization for the getContainers API Call.
[adam] Enabled authorization for the GET_FLAGS API Call.
[adam] Enabled authorization in SET_LOG_LEVEL API call.
[vinodkone] Fixed a check bug in LAUNCH_NESTED_CONTAINER_SESSION_CALL.
------------------------------------------
[...truncated 152123 lines...]
I1214 07:07:16.980834 29734 provisioner.cpp:324] Ignoring destroy request for unknown container 8a3e2af7-e7d2-4304-84b9-2700971e333f
I1214 07:07:16.981859 29733 slave.cpp:4681] Executor '2847e55d-442e-49a6-ae70-e137796bed9e' of framework 5b297a1f-9827-43e5-a83d-ec499b730e2e-0000 terminated with signal Killed
I1214 07:07:16.981909 29733 slave.cpp:4785] Cleaning up executor '2847e55d-442e-49a6-ae70-e137796bed9e' of framework 5b297a1f-9827-43e5-a83d-ec499b730e2e-0000 at executor(1)@172.17.0.4:32906
I1214 07:07:16.982239 29732 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_8mObfp/slaves/5b297a1f-9827-43e5-a83d-ec499b730e2e-S0/frameworks/5b297a1f-9827-43e5-a83d-ec499b730e2e-0000/executors/2847e55d-442e-49a6-ae70-e137796bed9e/runs/8a3e2af7-e7d2-4304-84b9-2700971e333f' for gc 6.99998863303407days in the future
I1214 07:07:16.982379 29733 slave.cpp:4873] Cleaning up framework 5b297a1f-9827-43e5-a83d-ec499b730e2e-0000
I1214 07:07:16.982388 29732 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_8mObfp/slaves/5b297a1f-9827-43e5-a83d-ec499b730e2e-S0/frameworks/5b297a1f-9827-43e5-a83d-ec499b730e2e-0000/executors/2847e55d-442e-49a6-ae70-e137796bed9e' for gc 6.99998863063704days in the future
I1214 07:07:16.982478 29734 status_update_manager.cpp:285] Closing status update streams for framework 5b297a1f-9827-43e5-a83d-ec499b730e2e-0000
I1214 07:07:16.982530 29734 status_update_manager.cpp:531] Cleaning up status update stream for task 2847e55d-442e-49a6-ae70-e137796bed9e of framework 5b297a1f-9827-43e5-a83d-ec499b730e2e-0000
I1214 07:07:16.982659 29729 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_8mObfp/slaves/5b297a1f-9827-43e5-a83d-ec499b730e2e-S0/frameworks/5b297a1f-9827-43e5-a83d-ec499b730e2e-0000' for gc 6.99998862837037days in the future
I1214 07:07:16.982807 29726 slave.cpp:796] Agent terminating
I1214 07:07:16.983043 29730 master.cpp:1258] Agent 5b297a1f-9827-43e5-a83d-ec499b730e2e-S0 at slave(578)@172.17.0.4:52704 (fbd32d96d796) disconnected
I1214 07:07:16.983170 29730 master.cpp:2977] Disconnecting agent 5b297a1f-9827-43e5-a83d-ec499b730e2e-S0 at slave(578)@172.17.0.4:52704 (fbd32d96d796)
I1214 07:07:16.983254 29730 master.cpp:2996] Deactivating agent 5b297a1f-9827-43e5-a83d-ec499b730e2e-S0 at slave(578)@172.17.0.4:52704 (fbd32d96d796)
I1214 07:07:16.983434 29726 hierarchical.cpp:589] Agent 5b297a1f-9827-43e5-a83d-ec499b730e2e-S0 deactivated
I1214 07:07:16.988057 29734 master.cpp:1097] Master terminating
I1214 07:07:16.988945 29732 hierarchical.cpp:522] Removed agent 5b297a1f-9827-43e5-a83d-ec499b730e2e-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/0 (600 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/1
I1214 07:07:16.996788 29707 cluster.cpp:160] Creating default 'local' authorizer
I1214 07:07:16.999749 29727 master.cpp:380] Master 742b4025-db9e-41f4-9f64-78437ef36b65 (fbd32d96d796) started on 172.17.0.4:52704
I1214 07:07:16.999781 29727 master.cpp:382] 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/8Xj3QF/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" --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/8Xj3QF/master" --zk_session_timeout="10secs"
I1214 07:07:17.000217 29727 master.cpp:432] Master only allowing authenticated frameworks to register
I1214 07:07:17.000237 29727 master.cpp:446] Master only allowing authenticated agents to register
I1214 07:07:17.000257 29727 master.cpp:459] Master only allowing authenticated HTTP frameworks to register
I1214 07:07:17.000274 29727 credentials.hpp:37] Loading credentials for authentication from '/tmp/8Xj3QF/credentials'
I1214 07:07:17.000586 29727 master.cpp:504] Using default 'crammd5' authenticator
I1214 07:07:17.000730 29727 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I1214 07:07:17.000944 29727 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I1214 07:07:17.001117 29727 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I1214 07:07:17.001292 29727 master.cpp:584] Authorization enabled
I1214 07:07:17.001565 29729 hierarchical.cpp:149] Initialized hierarchical allocator process
I1214 07:07:17.001587 29728 whitelist_watcher.cpp:77] No whitelist given
I1214 07:07:17.004076 29732 master.cpp:2045] Elected as the leading master!
I1214 07:07:17.004108 29732 master.cpp:1568] Recovering from registrar
I1214 07:07:17.004263 29736 registrar.cpp:329] Recovering registrar
I1214 07:07:17.005146 29729 registrar.cpp:362] Successfully fetched the registry (0B) in 724992ns
I1214 07:07:17.005257 29729 registrar.cpp:461] Applied 1 operations in 21068ns; attempting to update the registry
I1214 07:07:17.006255 29735 registrar.cpp:506] Successfully updated the registry in 844800ns
I1214 07:07:17.006407 29735 registrar.cpp:392] Successfully recovered registrar
I1214 07:07:17.007083 29736 master.cpp:1684] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I1214 07:07:17.007136 29739 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover
I1214 07:07:17.010064 29707 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1214 07:07:17.010704 29707 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1214 07:07:17.010876 29707 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1214 07:07:17.015245 29707 cluster.cpp:446] Creating default 'local' authorizer
I1214 07:07:17.016762 29733 slave.cpp:209] Mesos agent started on (579)@172.17.0.4:52704
I1214 07:07:17.016780 29733 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/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_BJTx9K/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/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_BJTx9K/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/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_BJTx9K/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/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_BJTx9K" --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/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI"
I1214 07:07:17.017228 29733 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_BJTx9K/credential'
I1214 07:07:17.017354 29733 slave.cpp:352] Agent using credential for: test-principal
I1214 07:07:17.017374 29733 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_BJTx9K/http_credentials'
I1214 07:07:17.017606 29733 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1214 07:07:17.017727 29733 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1214 07:07:17.018021 29707 sched.cpp:232] Version: 1.2.0
I1214 07:07:17.018613 29740 sched.cpp:336] New master detected at master@172.17.0.4:52704
I1214 07:07:17.018690 29740 sched.cpp:402] Authenticating with master master@172.17.0.4:52704
I1214 07:07:17.018707 29740 sched.cpp:409] Using default CRAM-MD5 authenticatee
I1214 07:07:17.018662 29733 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1214 07:07:17.018735 29733 slave.cpp:547] Agent attributes: [ ]
I1214 07:07:17.018746 29733 slave.cpp:552] Agent hostname: fbd32d96d796
I1214 07:07:17.018966 29741 authenticatee.cpp:121] Creating new client SASL connection
I1214 07:07:17.019212 29729 master.cpp:6748] Authenticating scheduler-d6ce6142-53ac-4e68-9d73-382dac66cae4@172.17.0.4:52704
I1214 07:07:17.019439 29727 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1132)@172.17.0.4:52704
I1214 07:07:17.019652 29738 authenticator.cpp:98] Creating new server SASL connection
I1214 07:07:17.019908 29730 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1214 07:07:17.019961 29730 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1214 07:07:17.020100 29735 authenticator.cpp:204] Received SASL authentication start
I1214 07:07:17.020153 29735 authenticator.cpp:326] Authentication requires more steps
I1214 07:07:17.020246 29735 state.cpp:57] Recovering state from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI/meta'
I1214 07:07:17.020290 29736 authenticatee.cpp:259] Received SASL authentication step
I1214 07:07:17.020474 29736 authenticator.cpp:232] Received SASL authentication step
I1214 07:07:17.020503 29726 status_update_manager.cpp:203] Recovering status update manager
I1214 07:07:17.020624 29736 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'fbd32d96d796' server FQDN: 'fbd32d96d796' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1214 07:07:17.020660 29736 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1214 07:07:17.020737 29736 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1214 07:07:17.020828 29736 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'fbd32d96d796' server FQDN: 'fbd32d96d796' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1214 07:07:17.020836 29733 containerizer.cpp:594] Recovering containerizer
I1214 07:07:17.020895 29736 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1214 07:07:17.020917 29736 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1214 07:07:17.020936 29736 authenticator.cpp:318] Authentication success
I1214 07:07:17.021008 29728 authenticatee.cpp:299] Authentication success
I1214 07:07:17.021096 29740 master.cpp:6778] Successfully authenticated principal 'test-principal' at scheduler-d6ce6142-53ac-4e68-9d73-382dac66cae4@172.17.0.4:52704
I1214 07:07:17.021294 29726 sched.cpp:508] Successfully authenticated with master master@172.17.0.4:52704
I1214 07:07:17.021304 29732 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1132)@172.17.0.4:52704
I1214 07:07:17.021320 29726 sched.cpp:826] Sending SUBSCRIBE call to master@172.17.0.4:52704
I1214 07:07:17.021555 29726 sched.cpp:859] Will retry registration in 1.918555254secs if necessary
I1214 07:07:17.021690 29735 master.cpp:2633] Received SUBSCRIBE call for framework 'default' at scheduler-d6ce6142-53ac-4e68-9d73-382dac66cae4@172.17.0.4:52704
I1214 07:07:17.021752 29735 master.cpp:2081] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1214 07:07:17.022167 29728 master.cpp:2709] Subscribing framework default with checkpointing disabled and capabilities [ ]
I1214 07:07:17.022325 29734 provisioner.cpp:253] Provisioner recovery complete
I1214 07:07:17.022619 29739 sched.cpp:749] Framework registered with 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.022651 29726 slave.cpp:5420] Finished recovery
I1214 07:07:17.022656 29736 hierarchical.cpp:276] Added framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.022673 29739 sched.cpp:763] Scheduler::registered took 27060ns
I1214 07:07:17.022742 29736 hierarchical.cpp:1689] No allocations performed
I1214 07:07:17.022773 29736 hierarchical.cpp:1784] No inverse offers to send out!
I1214 07:07:17.022819 29736 hierarchical.cpp:1291] Performed allocation for 0 agents in 118987ns
I1214 07:07:17.023072 29726 slave.cpp:5594] Querying resource estimator for oversubscribable resources
I1214 07:07:17.023280 29726 slave.cpp:924] New master detected at master@172.17.0.4:52704
I1214 07:07:17.023288 29734 status_update_manager.cpp:177] Pausing sending status updates
I1214 07:07:17.023313 29726 slave.cpp:983] Authenticating with master master@172.17.0.4:52704
I1214 07:07:17.023375 29726 slave.cpp:994] Using default CRAM-MD5 authenticatee
I1214 07:07:17.023510 29726 slave.cpp:956] Detecting new master
I1214 07:07:17.023552 29730 authenticatee.cpp:121] Creating new client SASL connection
I1214 07:07:17.023823 29732 slave.cpp:5608] Received oversubscribable resources {} from the resource estimator
I1214 07:07:17.023841 29735 master.cpp:6748] Authenticating slave(579)@172.17.0.4:52704
I1214 07:07:17.023927 29732 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1133)@172.17.0.4:52704
I1214 07:07:17.024117 29740 authenticator.cpp:98] Creating new server SASL connection
I1214 07:07:17.024286 29739 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1214 07:07:17.024312 29739 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1214 07:07:17.024416 29729 authenticator.cpp:204] Received SASL authentication start
I1214 07:07:17.024468 29729 authenticator.cpp:326] Authentication requires more steps
I1214 07:07:17.024569 29739 authenticatee.cpp:259] Received SASL authentication step
I1214 07:07:17.024667 29741 authenticator.cpp:232] Received SASL authentication step
I1214 07:07:17.024699 29741 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'fbd32d96d796' server FQDN: 'fbd32d96d796' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1214 07:07:17.024714 29741 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1214 07:07:17.024736 29741 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1214 07:07:17.024755 29741 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'fbd32d96d796' server FQDN: 'fbd32d96d796' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1214 07:07:17.024770 29741 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1214 07:07:17.024811 29741 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1214 07:07:17.024833 29741 authenticator.cpp:318] Authentication success
I1214 07:07:17.024909 29728 authenticatee.cpp:299] Authentication success
I1214 07:07:17.024945 29731 master.cpp:6778] Successfully authenticated principal 'test-principal' at slave(579)@172.17.0.4:52704
I1214 07:07:17.024972 29740 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1133)@172.17.0.4:52704
I1214 07:07:17.025218 29739 slave.cpp:1078] Successfully authenticated with master master@172.17.0.4:52704
I1214 07:07:17.025388 29739 slave.cpp:1492] Will retry registration in 19.136837ms if necessary
I1214 07:07:17.025530 29732 master.cpp:5161] Registering agent at slave(579)@172.17.0.4:52704 (fbd32d96d796) with id 742b4025-db9e-41f4-9f64-78437ef36b65-S0
I1214 07:07:17.025876 29726 registrar.cpp:461] Applied 1 operations in 43552ns; attempting to update the registry
I1214 07:07:17.027626 29726 registrar.cpp:506] Successfully updated the registry in 1.677824ms
I1214 07:07:17.028501 29726 slave.cpp:4272] Received ping from slave-observer(533)@172.17.0.4:52704
I1214 07:07:17.028575 29740 master.cpp:5232] Registered agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 at slave(579)@172.17.0.4:52704 (fbd32d96d796) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1214 07:07:17.028684 29726 slave.cpp:1124] Registered with master master@172.17.0.4:52704; given agent ID 742b4025-db9e-41f4-9f64-78437ef36b65-S0
I1214 07:07:17.028839 29726 fetcher.cpp:90] Clearing fetcher cache
I1214 07:07:17.029018 29729 status_update_manager.cpp:184] Resuming sending status updates
I1214 07:07:17.029033 29741 hierarchical.cpp:490] Added agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 (fbd32d96d796) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I1214 07:07:17.029319 29726 slave.cpp:1147] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI/meta/slaves/742b4025-db9e-41f4-9f64-78437ef36b65-S0/slave.info'
I1214 07:07:17.029733 29726 slave.cpp:1184] Forwarding total oversubscribed resources {}
I1214 07:07:17.029917 29735 master.cpp:5633] Received update of agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 at slave(579)@172.17.0.4:52704 (fbd32d96d796) with total oversubscribed resources {}
I1214 07:07:17.030285 29741 hierarchical.cpp:1784] No inverse offers to send out!
I1214 07:07:17.030375 29741 hierarchical.cpp:1314] Performed allocation for agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 in 1.279915ms
I1214 07:07:17.030571 29741 hierarchical.cpp:560] Agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 (fbd32d96d796) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I1214 07:07:17.030724 29741 hierarchical.cpp:1689] No allocations performed
I1214 07:07:17.030774 29741 hierarchical.cpp:1784] No inverse offers to send out!
I1214 07:07:17.030769 29734 master.cpp:6577] Sending 1 offers to framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 (default) at scheduler-d6ce6142-53ac-4e68-9d73-382dac66cae4@172.17.0.4:52704
I1214 07:07:17.030846 29741 hierarchical.cpp:1314] Performed allocation for agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 in 219737ns
I1214 07:07:17.031255 29741 sched.cpp:923] Scheduler::resourceOffers took 118974ns
I1214 07:07:17.033166 29734 master.cpp:3588] Processing ACCEPT call for offers: [ 742b4025-db9e-41f4-9f64-78437ef36b65-O0 ] on agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 at slave(579)@172.17.0.4:52704 (fbd32d96d796) for framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 (default) at scheduler-d6ce6142-53ac-4e68-9d73-382dac66cae4@172.17.0.4:52704
I1214 07:07:17.033272 29734 master.cpp:3175] Authorizing framework principal 'test-principal' to launch task 89840687-a77c-41bf-8bae-72c29e24ac5d
I1214 07:07:17.035531 29734 master.cpp:8501] Adding task 89840687-a77c-41bf-8bae-72c29e24ac5d with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 (fbd32d96d796)
I1214 07:07:17.035892 29734 master.cpp:4240] Launching task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 (default) at scheduler-d6ce6142-53ac-4e68-9d73-382dac66cae4@172.17.0.4:52704 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 at slave(579)@172.17.0.4:52704 (fbd32d96d796)
I1214 07:07:17.036711 29729 slave.cpp:1556] Got assigned task '89840687-a77c-41bf-8bae-72c29e24ac5d' for framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.037549 29729 slave.cpp:1718] Launching task '89840687-a77c-41bf-8bae-72c29e24ac5d' for framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.038277 29729 paths.cpp:530] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI/slaves/742b4025-db9e-41f4-9f64-78437ef36b65-S0/frameworks/742b4025-db9e-41f4-9f64-78437ef36b65-0000/executors/89840687-a77c-41bf-8bae-72c29e24ac5d/runs/92e75463-e336-4e32-ad52-d350f9ee8a21' to user 'mesos'
I1214 07:07:17.046413 29729 slave.cpp:6347] Launching executor '89840687-a77c-41bf-8bae-72c29e24ac5d' of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI/slaves/742b4025-db9e-41f4-9f64-78437ef36b65-S0/frameworks/742b4025-db9e-41f4-9f64-78437ef36b65-0000/executors/89840687-a77c-41bf-8bae-72c29e24ac5d/runs/92e75463-e336-4e32-ad52-d350f9ee8a21'
I1214 07:07:17.047343 29738 containerizer.cpp:986] Starting container 92e75463-e336-4e32-ad52-d350f9ee8a21 for executor '89840687-a77c-41bf-8bae-72c29e24ac5d' of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.047405 29729 slave.cpp:2040] Queued task '89840687-a77c-41bf-8bae-72c29e24ac5d' for executor '89840687-a77c-41bf-8bae-72c29e24ac5d' of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.047531 29729 slave.cpp:877] Successfully attached file '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI/slaves/742b4025-db9e-41f4-9f64-78437ef36b65-S0/frameworks/742b4025-db9e-41f4-9f64-78437ef36b65-0000/executors/89840687-a77c-41bf-8bae-72c29e24ac5d/runs/92e75463-e336-4e32-ad52-d350f9ee8a21'
I1214 07:07:17.053659 29733 containerizer.cpp:1535] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/mesos\/mesos-1.2.0\/_build\/src"],"shell":false,"value":"\/mesos\/mesos-1.2.0\/_build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","value":"172.17.0.4:52704"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI\/slaves\/742b4025-db9e-41f4-9f64-78437ef36b65-S0\/frameworks\/742b4025-db9e-41f4-9f64-78437ef36b65-0000\/executors\/89840687-a77c-41bf-8bae-72c29e24ac5d\/runs\/92e75463-e336-4e32-ad52-d350f9ee8a21"},{"name":"MESOS_EXECUTOR_ID","value":"89840687-a77c-41bf-8bae-72c29e24ac5d"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"742b4025-db9e-41f4-9f64-78437ef36b65-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"742b4025-db9e-41f4-9f64-78437ef36b65-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(579)@172.17.0.4:52704"},{"name":"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI\/slaves\/742b4025-db9e-41f4-9f64-78437ef36b65-S0\/frameworks\/742b4025-db9e-41f4-9f64-78437ef36b65-0000\/executors\/89840687-a77c-41bf-8bae-72c29e24ac5d\/runs\/92e75463-e336-4e32-ad52-d350f9ee8a21"}]},"err":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI\/slaves\/742b4025-db9e-41f4-9f64-78437ef36b65-S0\/frameworks\/742b4025-db9e-41f4-9f64-78437ef36b65-0000\/executors\/89840687-a77c-41bf-8bae-72c29e24ac5d\/runs\/92e75463-e336-4e32-ad52-d350f9ee8a21\/stderr","type":"PATH"},"out":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI\/slaves\/742b4025-db9e-41f4-9f64-78437ef36b65-S0\/frameworks\/742b4025-db9e-41f4-9f64-78437ef36b65-0000\/executors\/89840687-a77c-41bf-8bae-72c29e24ac5d\/runs\/92e75463-e336-4e32-ad52-d350f9ee8a21\/stdout","type":"PATH"},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI\/slaves\/742b4025-db9e-41f4-9f64-78437ef36b65-S0\/frameworks\/742b4025-db9e-41f4-9f64-78437ef36b65-0000\/executors\/89840687-a77c-41bf-8bae-72c29e24ac5d\/runs\/92e75463-e336-4e32-ad52-d350f9ee8a21"}" --pipe_read="22" --pipe_write="24" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_BJTx9K/containers/92e75463-e336-4e32-ad52-d350f9ee8a21" --unshare_namespace_mnt="false"'
I1214 07:07:17.057402 29733 launcher.cpp:133] Forked child with pid '10394' for container '92e75463-e336-4e32-ad52-d350f9ee8a21'
I1214 07:07:17.060606 29728 fetcher.cpp:349] Starting to fetch URIs for container: 92e75463-e336-4e32-ad52-d350f9ee8a21, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI/slaves/742b4025-db9e-41f4-9f64-78437ef36b65-S0/frameworks/742b4025-db9e-41f4-9f64-78437ef36b65-0000/executors/89840687-a77c-41bf-8bae-72c29e24ac5d/runs/92e75463-e336-4e32-ad52-d350f9ee8a21
I1214 07:07:17.238140 29733 slave.cpp:3314] Got registration for executor '89840687-a77c-41bf-8bae-72c29e24ac5d' of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 from executor(1)@172.17.0.4:43464
I1214 07:07:17.240610 29737 slave.cpp:2256] Sending queued task '89840687-a77c-41bf-8bae-72c29e24ac5d' to executor '89840687-a77c-41bf-8bae-72c29e24ac5d' of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 at executor(1)@172.17.0.4:43464
I1214 07:07:17.255806 29727 slave.cpp:3749] Handling status update TASK_RUNNING (UUID: 060ae6af-baa2-4e8e-9ec8-2cf456e46aca) for task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 from executor(1)@172.17.0.4:43464
I1214 07:07:17.257293 29739 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 060ae6af-baa2-4e8e-9ec8-2cf456e46aca) for task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.257347 29739 status_update_manager.cpp:500] Creating StatusUpdate stream for task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.257863 29739 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 060ae6af-baa2-4e8e-9ec8-2cf456e46aca) for task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 to the agent
I1214 07:07:17.258157 29729 slave.cpp:4190] Forwarding the update TASK_RUNNING (UUID: 060ae6af-baa2-4e8e-9ec8-2cf456e46aca) for task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 to master@172.17.0.4:52704
I1214 07:07:17.258363 29729 slave.cpp:4084] Status update manager successfully handled status update TASK_RUNNING (UUID: 060ae6af-baa2-4e8e-9ec8-2cf456e46aca) for task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.258424 29729 slave.cpp:4100] Sending acknowledgement for status update TASK_RUNNING (UUID: 060ae6af-baa2-4e8e-9ec8-2cf456e46aca) for task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 to executor(1)@172.17.0.4:43464
I1214 07:07:17.258589 29736 master.cpp:5769] Status update TASK_RUNNING (UUID: 060ae6af-baa2-4e8e-9ec8-2cf456e46aca) for task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 from agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 at slave(579)@172.17.0.4:52704 (fbd32d96d796)
I1214 07:07:17.258703 29736 master.cpp:5831] Forwarding status update TASK_RUNNING (UUID: 060ae6af-baa2-4e8e-9ec8-2cf456e46aca) for task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.258977 29736 master.cpp:7867] Updating the state of task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1214 07:07:17.259239 29733 sched.cpp:1031] Scheduler::statusUpdate took 134952ns
I1214 07:07:17.259558 29733 master.cpp:4877] Processing ACKNOWLEDGE call 060ae6af-baa2-4e8e-9ec8-2cf456e46aca for task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 (default) at scheduler-d6ce6142-53ac-4e68-9d73-382dac66cae4@172.17.0.4:52704 on agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0
I1214 07:07:17.259918 29739 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 060ae6af-baa2-4e8e-9ec8-2cf456e46aca) for task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.260350 29728 slave.cpp:3031] Status update manager successfully handled status update acknowledgement (UUID: 060ae6af-baa2-4e8e-9ec8-2cf456e46aca) for task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.262564 29729 process.cpp:3679] Handling HTTP event for process 'slave(579)' with path: '/slave(579)/api/v1'
I1214 07:07:17.263818 29731 http.cpp:301] HTTP POST for /slave(579)/api/v1 from 172.17.0.4:37755
I1214 07:07:17.264328 29731 http.cpp:462] Processing call LAUNCH_NESTED_CONTAINER_SESSION
I1214 07:07:17.264747 29737 containerizer.cpp:1802] Starting nested container 92e75463-e336-4e32-ad52-d350f9ee8a21.2f1ea6f0-4b48-4f73-9c80-b0405571739a
I1214 07:07:17.265012 29737 containerizer.cpp:1826] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI/slaves/742b4025-db9e-41f4-9f64-78437ef36b65-S0/frameworks/742b4025-db9e-41f4-9f64-78437ef36b65-0000/executors/89840687-a77c-41bf-8bae-72c29e24ac5d/runs/92e75463-e336-4e32-ad52-d350f9ee8a21/containers/2f1ea6f0-4b48-4f73-9c80-b0405571739a' to user 'mesos'
I1214 07:07:17.275467 29730 switchboard.cpp:567] Launching 'mesos-io-switchboard' with flags '--heartbeat_interval="30secs" --help="false" --socket_address="/tmp/mesos-io-switchboard-0629d5d8-bd9c-4904-b7c0-f7378a9e0340" --stderr_from_fd="60" --stderr_to_fd="2" --stdin_to_fd="29" --stdout_from_fd="40" --stdout_to_fd="1" --tty="false" --wait_for_connection="true"' for container 92e75463-e336-4e32-ad52-d350f9ee8a21.2f1ea6f0-4b48-4f73-9c80-b0405571739a
I1214 07:07:17.278549 29730 switchboard.cpp:597] Created I/O switchboard server (pid: 10455) listening on socket file '/tmp/mesos-io-switchboard-0629d5d8-bd9c-4904-b7c0-f7378a9e0340' for container 92e75463-e336-4e32-ad52-d350f9ee8a21.2f1ea6f0-4b48-4f73-9c80-b0405571739a
I1214 07:07:17.280396 29734 containerizer.cpp:1535] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"cat"},"environment":{},"err":{"fd":61,"type":"FD"},"in":{"fd":28,"type":"FD"},"out":{"fd":41,"type":"FD"},"user":"mesos"}" --pipe_read="29" --pipe_write="40" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_BJTx9K/containers/92e75463-e336-4e32-ad52-d350f9ee8a21/containers/2f1ea6f0-4b48-4f73-9c80-b0405571739a" --unshare_namespace_mnt="false"'
I1214 07:07:17.283907 29734 launcher.cpp:133] Forked child with pid '10456' for container '92e75463-e336-4e32-ad52-d350f9ee8a21.2f1ea6f0-4b48-4f73-9c80-b0405571739a'
I1214 07:07:17.286752 29729 fetcher.cpp:349] Starting to fetch URIs for container: 92e75463-e336-4e32-ad52-d350f9ee8a21.2f1ea6f0-4b48-4f73-9c80-b0405571739a, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI/slaves/742b4025-db9e-41f4-9f64-78437ef36b65-S0/frameworks/742b4025-db9e-41f4-9f64-78437ef36b65-0000/executors/89840687-a77c-41bf-8bae-72c29e24ac5d/runs/92e75463-e336-4e32-ad52-d350f9ee8a21/containers/2f1ea6f0-4b48-4f73-9c80-b0405571739a
**** DEADLOCK DETECTED! ****
You are waiting on process __limiter__(597)@172.17.0.4:52704 that it is currently executing.
W1214 07:07:17.373704 29736 http.cpp:2723] Launch nested container session connection for container 92e75463-e336-4e32-ad52-d350f9ee8a21.2f1ea6f0-4b48-4f73-9c80-b0405571739a closed
I1214 07:07:17.373935 29738 containerizer.cpp:2113] Destroying container 92e75463-e336-4e32-ad52-d350f9ee8a21.2f1ea6f0-4b48-4f73-9c80-b0405571739a in RUNNING state
I1214 07:07:17.374240 29738 launcher.cpp:149] Asked to destroy container 92e75463-e336-4e32-ad52-d350f9ee8a21.2f1ea6f0-4b48-4f73-9c80-b0405571739a
I1214 07:07:17.389567 29734 http.cpp:2708] Received EOF attach response for 92e75463-e336-4e32-ad52-d350f9ee8a21.2f1ea6f0-4b48-4f73-9c80-b0405571739a
I1214 07:07:17.485394 29731 switchboard.cpp:855] I/O switchboard server process for container 92e75463-e336-4e32-ad52-d350f9ee8a21.2f1ea6f0-4b48-4f73-9c80-b0405571739a has terminated (status=0)
I1214 07:07:17.486018 29740 containerizer.cpp:2476] Container 92e75463-e336-4e32-ad52-d350f9ee8a21.2f1ea6f0-4b48-4f73-9c80-b0405571739a has exited
I1214 07:07:17.487499 29740 provisioner.cpp:324] Ignoring destroy request for unknown container 92e75463-e336-4e32-ad52-d350f9ee8a21.2f1ea6f0-4b48-4f73-9c80-b0405571739a
I1214 07:07:17.487639 29732 containerizer.cpp:2392] Checkpointing termination state to nested container's runtime directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_BJTx9K/containers/92e75463-e336-4e32-ad52-d350f9ee8a21/containers/2f1ea6f0-4b48-4f73-9c80-b0405571739a/termination'
I1214 07:07:17.488368 29707 sched.cpp:2008] Asked to stop the driver
I1214 07:07:17.488625 29741 sched.cpp:1193] Stopping framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.488915 29728 master.cpp:7287] Processing TEARDOWN call for framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 (default) at scheduler-d6ce6142-53ac-4e68-9d73-382dac66cae4@172.17.0.4:52704
I1214 07:07:17.488951 29728 master.cpp:7299] Removing framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 (default) at scheduler-d6ce6142-53ac-4e68-9d73-382dac66cae4@172.17.0.4:52704
I1214 07:07:17.489125 29734 slave.cpp:2584] Asked to shut down framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 by master@172.17.0.4:52704
I1214 07:07:17.489132 29733 hierarchical.cpp:391] Deactivated framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.489172 29734 slave.cpp:2609] Shutting down framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.489169 29728 master.cpp:7867] Updating the state of task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I1214 07:07:17.489225 29734 slave.cpp:4999] Shutting down executor '89840687-a77c-41bf-8bae-72c29e24ac5d' of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 at executor(1)@172.17.0.4:43464
I1214 07:07:17.492221 29728 master.cpp:7963] Removing task 89840687-a77c-41bf-8bae-72c29e24ac5d with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 on agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 at slave(579)@172.17.0.4:52704 (fbd32d96d796)
I1214 07:07:17.492624 29739 containerizer.cpp:2113] Destroying container 92e75463-e336-4e32-ad52-d350f9ee8a21 in RUNNING state
I1214 07:07:17.492954 29739 launcher.cpp:149] Asked to destroy container 92e75463-e336-4e32-ad52-d350f9ee8a21
I1214 07:07:17.492993 29733 hierarchical.cpp:1023] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 from framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.493263 29733 hierarchical.cpp:342] Removed framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.504864 29726 slave.cpp:4318] Got exited event for executor(1)@172.17.0.4:43464
I1214 07:07:17.586716 29730 containerizer.cpp:2476] Container 92e75463-e336-4e32-ad52-d350f9ee8a21 has exited
I1214 07:07:17.589184 29727 provisioner.cpp:324] Ignoring destroy request for unknown container 92e75463-e336-4e32-ad52-d350f9ee8a21
I1214 07:07:17.590572 29741 slave.cpp:4681] Executor '89840687-a77c-41bf-8bae-72c29e24ac5d' of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 terminated with signal Killed
I1214 07:07:17.590634 29741 slave.cpp:4785] Cleaning up executor '89840687-a77c-41bf-8bae-72c29e24ac5d' of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000 at executor(1)@172.17.0.4:43464
I1214 07:07:17.590948 29727 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI/slaves/742b4025-db9e-41f4-9f64-78437ef36b65-S0/frameworks/742b4025-db9e-41f4-9f64-78437ef36b65-0000/executors/89840687-a77c-41bf-8bae-72c29e24ac5d/runs/92e75463-e336-4e32-ad52-d350f9ee8a21' for gc 6.99999316164444days in the future
I1214 07:07:17.591158 29727 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI/slaves/742b4025-db9e-41f4-9f64-78437ef36b65-S0/frameworks/742b4025-db9e-41f4-9f64-78437ef36b65-0000/executors/89840687-a77c-41bf-8bae-72c29e24ac5d' for gc 6.99999315873778days in the future
I1214 07:07:17.591166 29741 slave.cpp:4873] Cleaning up framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.591316 29728 status_update_manager.cpp:285] Closing status update streams for framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.591368 29728 status_update_manager.cpp:531] Cleaning up status update stream for task 89840687-a77c-41bf-8bae-72c29e24ac5d of framework 742b4025-db9e-41f4-9f64-78437ef36b65-0000
I1214 07:07:17.591526 29741 slave.cpp:796] Agent terminating
I1214 07:07:17.591578 29734 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_DF2hvI/slaves/742b4025-db9e-41f4-9f64-78437ef36b65-S0/frameworks/742b4025-db9e-41f4-9f64-78437ef36b65-0000' for gc 6.99999315561185days in the future
I1214 07:07:17.591706 29734 master.cpp:1258] Agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 at slave(579)@172.17.0.4:52704 (fbd32d96d796) disconnected
I1214 07:07:17.591735 29734 master.cpp:2977] Disconnecting agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 at slave(579)@172.17.0.4:52704 (fbd32d96d796)
I1214 07:07:17.591821 29734 master.cpp:2996] Deactivating agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 at slave(579)@172.17.0.4:52704 (fbd32d96d796)
I1214 07:07:17.591964 29732 hierarchical.cpp:589] Agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0 deactivated
I1214 07:07:17.596750 29729 master.cpp:1097] Master terminating
I1214 07:07:17.597570 29734 hierarchical.cpp:522] Removed agent 742b4025-db9e-41f4-9f64-78437ef36b65-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/1 (606 ms)
[ RUN ] ContentType/AgentAPITest.AttachContainerOutputFailure/0
I1214 07:07:17.609922 29740 slave.cpp:209] Mesos agent started on (580)@172.17.0.4:52704
I1214 07:07:17.609949 29740 slave.cpp:210] Flags at startup: --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/ContentType_AgentAPITest_AttachContainerOutputFailure_0_Dx62fn/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/ContentType_AgentAPITest_AttachContainerOutputFailure_0_Dx62fn/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/ContentType_AgentAPITest_AttachContainerOutputFailure_0_Dx62fn/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/ContentType_AgentAPITest_AttachContainerOutputFailure_0_Dx62fn" --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/ContentType_AgentAPITest_AttachContainerOutputFailure_0_suj52i"
I1214 07:07:17.610493 29740 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_Dx62fn/credential'
I1214 07:07:17.610651 29740 slave.cpp:352] Agent using credential for: test-principal
I1214 07:07:17.610671 29740 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_Dx62fn/http_credentials'
I1214 07:07:17.610898 29740 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1214 07:07:17.611043 29740 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1214 07:07:17.612336 29740 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1214 07:07:17.612404 29740 slave.cpp:547] Agent attributes: [ ]
I1214 07:07:17.612419 29740 slave.cpp:552] Agent hostname: fbd32d96d796
I1214 07:07:17.613922 29734 state.cpp:57] Recovering state from '/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_suj52i/meta'
I1214 07:07:17.614267 29738 status_update_manager.cpp:203] Recovering status update manager
I1214 07:07:17.614701 29739 slave.cpp:5420] Finished recovery
I1214 07:07:17.615146 29739 slave.cpp:5594] Querying resource estimator for oversubscribable resources
I1214 07:07:17.615444 29736 slave.cpp:5608] Received oversubscribable resources {} from the resource estimator
Build timed out (after 300 minutes). Marking the build as failed.
Build was aborted
++ docker rmi mesos-1481696370-11232
Error response from daemon: conflict: unable to remove repository reference "mesos-1481696370-11232" (must force) - container fbd32d96d796 is using its referenced image 377a768bcf21
Jenkins build is back to normal : Mesos » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2) #3036
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3036/>