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 2017/01/18 18:50:15 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2) #3126
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3126/changes>
Changes:
[vinodkone] Added MESOS-6917 to CHANGELOG for 1.0.3.
[vinodkone] Paused the status update manager in `Slave::initialize()`.
[vinodkone] Fixed SlaveRecoveryTest.RecoverTerminatedExecutor test.
------------------------------------------
[...truncated 143842 lines...]
I0118 14:27:19.496654 24799 hierarchical.cpp:577] Agent 7190f101-fd1d-4fa8-ad00-3606286e1551-S0 deactivated
I0118 14:27:19.499446 24793 master.cpp:1100] Master terminating
I0118 14:27:19.499668 24800 hierarchical.cpp:510] Removed agent 7190f101-fd1d-4fa8-ad00-3606286e1551-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSession/0 (373 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSession/1
I0118 14:27:19.505123 24793 cluster.cpp:160] Creating default 'local' authorizer
I0118 14:27:19.506364 24809 master.cpp:383] Master 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9 (48a8b8c0b334) started on 172.17.0.3:36408
I0118 14:27:19.506402 24809 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/27EVjD/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="/usr/local/share/mesos/webui" --work_dir="/tmp/27EVjD/master" --zk_session_timeout="10secs"
I0118 14:27:19.506712 24809 master.cpp:435] Master only allowing authenticated frameworks to register
I0118 14:27:19.506731 24809 master.cpp:449] Master only allowing authenticated agents to register
I0118 14:27:19.506743 24809 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0118 14:27:19.506757 24809 credentials.hpp:37] Loading credentials for authentication from '/tmp/27EVjD/credentials'
I0118 14:27:19.506934 24809 master.cpp:507] Using default 'crammd5' authenticator
I0118 14:27:19.506992 24809 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0118 14:27:19.507076 24809 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0118 14:27:19.507164 24809 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0118 14:27:19.507246 24809 master.cpp:587] Authorization enabled
I0118 14:27:19.507556 24797 hierarchical.cpp:151] Initialized hierarchical allocator process
I0118 14:27:19.507565 24798 whitelist_watcher.cpp:77] No whitelist given
I0118 14:27:19.508616 24807 master.cpp:2119] Elected as the leading master!
I0118 14:27:19.508635 24807 master.cpp:1641] Recovering from registrar
I0118 14:27:19.508738 24804 registrar.cpp:329] Recovering registrar
I0118 14:27:19.509142 24804 registrar.cpp:362] Successfully fetched the registry (0B) in 377088ns
I0118 14:27:19.509184 24804 registrar.cpp:461] Applied 1 operations in 9777ns; attempting to update the registry
I0118 14:27:19.509577 24801 registrar.cpp:506] Successfully updated the registry in 329984ns
I0118 14:27:19.509635 24801 registrar.cpp:392] Successfully recovered registrar
I0118 14:27:19.509912 24795 master.cpp:1757] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0118 14:27:19.509940 24806 hierarchical.cpp:178] Skipping recovery of hierarchical allocator: nothing to recover
I0118 14:27:19.511348 24793 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0118 14:27:19.511801 24793 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W0118 14:27:19.511932 24793 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0118 14:27:19.513914 24793 cluster.cpp:446] Creating default 'local' authorizer
I0118 14:27:19.514590 24808 slave.cpp:209] Mesos agent started on (582)@172.17.0.3:36408
I0118 14:27:19.514626 24808 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_LaunchNestedContainerSession_1_uPJHl3/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_LaunchNestedContainerSession_1_uPJHl3/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_LaunchNestedContainerSession_1_uPJHl3/http_credentials" --http_heartbeat_interval="30secs" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/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_LaunchNestedContainerSession_1_uPJHl3" --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_LaunchNestedContainerSession_1_RZ7Nkl"
I0118 14:27:19.515272 24808 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_uPJHl3/credential'
I0118 14:27:19.515398 24808 slave.cpp:352] Agent using credential for: test-principal
I0118 14:27:19.515414 24808 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_uPJHl3/http_credentials'
I0118 14:27:19.515434 24793 sched.cpp:232] Version: 1.2.0
I0118 14:27:19.515564 24808 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0118 14:27:19.515646 24808 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0118 14:27:19.515663 24804 sched.cpp:336] New master detected at master@172.17.0.3:36408
I0118 14:27:19.515697 24804 sched.cpp:407] Authenticating with master master@172.17.0.3:36408
I0118 14:27:19.515710 24804 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0118 14:27:19.515821 24796 authenticatee.cpp:121] Creating new client SASL connection
I0118 14:27:19.516088 24805 master.cpp:6842] Authenticating scheduler-4c480956-b28c-4811-8403-ebd1711ab501@172.17.0.3:36408
I0118 14:27:19.516206 24803 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1118)@172.17.0.3:36408
I0118 14:27:19.516340 24805 authenticator.cpp:98] Creating new server SASL connection
I0118 14:27:19.516456 24808 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0118 14:27:19.516506 24808 slave.cpp:547] Agent attributes: [ ]
I0118 14:27:19.516513 24808 slave.cpp:552] Agent hostname: 48a8b8c0b334
I0118 14:27:19.516520 24805 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0118 14:27:19.516557 24805 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0118 14:27:19.516594 24809 status_update_manager.cpp:177] Pausing sending status updates
I0118 14:27:19.516618 24805 authenticator.cpp:204] Received SASL authentication start
I0118 14:27:19.516688 24805 authenticator.cpp:326] Authentication requires more steps
I0118 14:27:19.516753 24806 authenticatee.cpp:259] Received SASL authentication step
I0118 14:27:19.516855 24805 authenticator.cpp:232] Received SASL authentication step
I0118 14:27:19.517001 24805 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '48a8b8c0b334' server FQDN: '48a8b8c0b334' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0118 14:27:19.517031 24805 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0118 14:27:19.517060 24805 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0118 14:27:19.517078 24805 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '48a8b8c0b334' server FQDN: '48a8b8c0b334' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0118 14:27:19.517097 24805 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0118 14:27:19.517108 24805 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0118 14:27:19.517129 24805 authenticator.cpp:318] Authentication success
I0118 14:27:19.517177 24808 state.cpp:60] Recovering state from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl/meta'
I0118 14:27:19.517218 24797 authenticatee.cpp:299] Authentication success
I0118 14:27:19.517251 24806 master.cpp:6872] Successfully authenticated principal 'test-principal' at scheduler-4c480956-b28c-4811-8403-ebd1711ab501@172.17.0.3:36408
I0118 14:27:19.517437 24805 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1118)@172.17.0.3:36408
I0118 14:27:19.517464 24806 status_update_manager.cpp:203] Recovering status update manager
I0118 14:27:19.517647 24797 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:36408
I0118 14:27:19.517669 24797 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:36408
I0118 14:27:19.517798 24797 sched.cpp:869] Will retry registration in 1.279596213secs if necessary
I0118 14:27:19.517921 24804 master.cpp:2707] Received SUBSCRIBE call for framework 'default' at scheduler-4c480956-b28c-4811-8403-ebd1711ab501@172.17.0.3:36408
I0118 14:27:19.517964 24799 containerizer.cpp:599] Recovering containerizer
I0118 14:27:19.518013 24804 master.cpp:2155] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0118 14:27:19.518426 24806 master.cpp:2783] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0118 14:27:19.518599 24806 sched.cpp:759] Framework registered with 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.518628 24806 sched.cpp:773] Scheduler::registered took 16845ns
I0118 14:27:19.518654 24800 hierarchical.cpp:271] Added framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.518712 24800 hierarchical.cpp:1677] No allocations performed
I0118 14:27:19.518728 24800 hierarchical.cpp:1772] No inverse offers to send out!
I0118 14:27:19.518772 24800 hierarchical.cpp:1279] Performed allocation for 0 agents in 83414ns
I0118 14:27:19.519065 24795 provisioner.cpp:251] Provisioner recovery complete
I0118 14:27:19.519274 24809 slave.cpp:5422] Finished recovery
I0118 14:27:19.519738 24809 slave.cpp:5596] Querying resource estimator for oversubscribable resources
I0118 14:27:19.519875 24795 slave.cpp:929] New master detected at master@172.17.0.3:36408
I0118 14:27:19.519894 24804 status_update_manager.cpp:177] Pausing sending status updates
I0118 14:27:19.519920 24795 slave.cpp:964] Detecting new master
I0118 14:27:19.520018 24795 slave.cpp:5610] Received oversubscribable resources {} from the resource estimator
I0118 14:27:19.526258 24801 slave.cpp:991] Authenticating with master master@172.17.0.3:36408
I0118 14:27:19.526289 24801 slave.cpp:1002] Using default CRAM-MD5 authenticatee
I0118 14:27:19.526360 24798 authenticatee.cpp:121] Creating new client SASL connection
I0118 14:27:19.526566 24798 master.cpp:6842] Authenticating slave(582)@172.17.0.3:36408
I0118 14:27:19.526634 24805 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1119)@172.17.0.3:36408
I0118 14:27:19.526757 24794 authenticator.cpp:98] Creating new server SASL connection
I0118 14:27:19.526968 24794 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0118 14:27:19.527009 24794 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0118 14:27:19.527084 24794 authenticator.cpp:204] Received SASL authentication start
I0118 14:27:19.527145 24794 authenticator.cpp:326] Authentication requires more steps
I0118 14:27:19.527243 24794 authenticatee.cpp:259] Received SASL authentication step
I0118 14:27:19.527555 24807 authenticator.cpp:232] Received SASL authentication step
I0118 14:27:19.527583 24807 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '48a8b8c0b334' server FQDN: '48a8b8c0b334' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0118 14:27:19.527618 24807 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0118 14:27:19.527642 24807 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0118 14:27:19.527663 24807 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '48a8b8c0b334' server FQDN: '48a8b8c0b334' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0118 14:27:19.527680 24807 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0118 14:27:19.527693 24807 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0118 14:27:19.527715 24807 authenticator.cpp:318] Authentication success
I0118 14:27:19.527770 24797 authenticatee.cpp:299] Authentication success
I0118 14:27:19.527936 24794 master.cpp:6872] Successfully authenticated principal 'test-principal' at slave(582)@172.17.0.3:36408
I0118 14:27:19.528007 24804 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1119)@172.17.0.3:36408
I0118 14:27:19.528093 24798 slave.cpp:1086] Successfully authenticated with master master@172.17.0.3:36408
I0118 14:27:19.528177 24798 slave.cpp:1508] Will retry registration in 19.815059ms if necessary
I0118 14:27:19.528324 24802 master.cpp:5232] Registering agent at slave(582)@172.17.0.3:36408 (48a8b8c0b334) with id 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0
I0118 14:27:19.528478 24806 registrar.cpp:461] Applied 1 operations in 27481ns; attempting to update the registry
I0118 14:27:19.529000 24806 registrar.cpp:506] Successfully updated the registry in 468224ns
I0118 14:27:19.529383 24802 slave.cpp:4286] Received ping from slave-observer(527)@172.17.0.3:36408
I0118 14:27:19.529381 24805 master.cpp:5303] Registered agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 at slave(582)@172.17.0.3:36408 (48a8b8c0b334) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0118 14:27:19.529472 24806 hierarchical.cpp:478] Added agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 (48a8b8c0b334) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0118 14:27:19.529500 24802 slave.cpp:1132] Registered with master master@172.17.0.3:36408; given agent ID 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0
I0118 14:27:19.529578 24802 fetcher.cpp:90] Clearing fetcher cache
I0118 14:27:19.529736 24796 status_update_manager.cpp:184] Resuming sending status updates
I0118 14:27:19.529834 24806 hierarchical.cpp:1772] No inverse offers to send out!
I0118 14:27:19.529862 24806 hierarchical.cpp:1302] Performed allocation for agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 in 311410ns
I0118 14:27:19.530026 24809 master.cpp:6671] Sending 1 offers to framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 (default) at scheduler-4c480956-b28c-4811-8403-ebd1711ab501@172.17.0.3:36408
I0118 14:27:19.530148 24802 slave.cpp:1160] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl/meta/slaves/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0/slave.info'
I0118 14:27:19.530303 24795 sched.cpp:933] Scheduler::resourceOffers took 78710ns
I0118 14:27:19.530506 24802 slave.cpp:1198] Forwarding total oversubscribed resources {}
I0118 14:27:19.530612 24801 master.cpp:5710] Received update of agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 at slave(582)@172.17.0.3:36408 (48a8b8c0b334) with total oversubscribed resources {}
I0118 14:27:19.530777 24805 hierarchical.cpp:548] Agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 (48a8b8c0b334) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I0118 14:27:19.530848 24805 hierarchical.cpp:1677] No allocations performed
I0118 14:27:19.530867 24805 hierarchical.cpp:1772] No inverse offers to send out!
I0118 14:27:19.530891 24805 hierarchical.cpp:1302] Performed allocation for agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 in 75612ns
I0118 14:27:19.530989 24800 master.cpp:3661] Processing ACCEPT call for offers: [ 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-O0 ] on agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 at slave(582)@172.17.0.3:36408 (48a8b8c0b334) for framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 (default) at scheduler-4c480956-b28c-4811-8403-ebd1711ab501@172.17.0.3:36408
I0118 14:27:19.531044 24800 master.cpp:3249] Authorizing framework principal 'test-principal' to launch task 12154f47-0469-4319-85b3-c3b7ad15a954
I0118 14:27:19.531723 24797 master.cpp:8584] Adding task 12154f47-0469-4319-85b3-c3b7ad15a954 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 at slave(582)@172.17.0.3:36408 (48a8b8c0b334)
I0118 14:27:19.531813 24797 master.cpp:4311] Launching task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 (default) at scheduler-4c480956-b28c-4811-8403-ebd1711ab501@172.17.0.3:36408 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 at slave(582)@172.17.0.3:36408 (48a8b8c0b334)
I0118 14:27:19.532161 24807 slave.cpp:1576] Got assigned task '12154f47-0469-4319-85b3-c3b7ad15a954' for framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.532515 24807 slave.cpp:1736] Launching task '12154f47-0469-4319-85b3-c3b7ad15a954' for framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.533205 24807 paths.cpp:547] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl/slaves/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0/frameworks/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000/executors/12154f47-0469-4319-85b3-c3b7ad15a954/runs/0a48bd0a-615f-4cfb-bf82-f43ba2796718' to user 'mesos'
I0118 14:27:19.533444 24807 slave.cpp:6350] Launching executor '12154f47-0469-4319-85b3-c3b7ad15a954' of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl/slaves/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0/frameworks/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000/executors/12154f47-0469-4319-85b3-c3b7ad15a954/runs/0a48bd0a-615f-4cfb-bf82-f43ba2796718'
I0118 14:27:19.533766 24796 containerizer.cpp:991] Starting container 0a48bd0a-615f-4cfb-bf82-f43ba2796718 for executor '12154f47-0469-4319-85b3-c3b7ad15a954' of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.533795 24807 slave.cpp:2058] Queued task '12154f47-0469-4319-85b3-c3b7ad15a954' for executor '12154f47-0469-4319-85b3-c3b7ad15a954' of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.533885 24807 slave.cpp:882] Successfully attached file '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl/slaves/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0/frameworks/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000/executors/12154f47-0469-4319-85b3-c3b7ad15a954/runs/0a48bd0a-615f-4cfb-bf82-f43ba2796718'
I0118 14:27:19.536061 24795 containerizer.cpp:1540] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/mesos\/build\/src"],"shell":false,"value":"\/mesos\/build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","value":"172.17.0.3:36408"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl\/slaves\/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0\/frameworks\/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000\/executors\/12154f47-0469-4319-85b3-c3b7ad15a954\/runs\/0a48bd0a-615f-4cfb-bf82-f43ba2796718"},{"name":"MESOS_EXECUTOR_ID","value":"12154f47-0469-4319-85b3-c3b7ad15a954"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(582)@172.17.0.3:36408"},{"name":"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl\/slaves\/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0\/frameworks\/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000\/executors\/12154f47-0469-4319-85b3-c3b7ad15a954\/runs\/0a48bd0a-615f-4cfb-bf82-f43ba2796718"}]},"err":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl\/slaves\/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0\/frameworks\/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000\/executors\/12154f47-0469-4319-85b3-c3b7ad15a954\/runs\/0a48bd0a-615f-4cfb-bf82-f43ba2796718\/stderr","type":"PATH"},"out":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl\/slaves\/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0\/frameworks\/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000\/executors\/12154f47-0469-4319-85b3-c3b7ad15a954\/runs\/0a48bd0a-615f-4cfb-bf82-f43ba2796718\/stdout","type":"PATH"},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl\/slaves\/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0\/frameworks\/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000\/executors\/12154f47-0469-4319-85b3-c3b7ad15a954\/runs\/0a48bd0a-615f-4cfb-bf82-f43ba2796718"}" --pipe_read="8" --pipe_write="9" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_uPJHl3/containers/0a48bd0a-615f-4cfb-bf82-f43ba2796718" --unshare_namespace_mnt="false"'
I0118 14:27:19.539036 24795 launcher.cpp:133] Forked child with pid '28979' for container '0a48bd0a-615f-4cfb-bf82-f43ba2796718'
I0118 14:27:19.541411 24799 fetcher.cpp:349] Starting to fetch URIs for container: 0a48bd0a-615f-4cfb-bf82-f43ba2796718, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl/slaves/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0/frameworks/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000/executors/12154f47-0469-4319-85b3-c3b7ad15a954/runs/0a48bd0a-615f-4cfb-bf82-f43ba2796718
I0118 14:27:19.651913 24800 slave.cpp:3325] Got registration for executor '12154f47-0469-4319-85b3-c3b7ad15a954' of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 from executor(1)@172.17.0.3:32857
I0118 14:27:19.652899 24800 slave.cpp:2271] Sending queued task '12154f47-0469-4319-85b3-c3b7ad15a954' to executor '12154f47-0469-4319-85b3-c3b7ad15a954' of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 at executor(1)@172.17.0.3:32857
I0118 14:27:19.659653 24796 slave.cpp:3756] Handling status update TASK_RUNNING (UUID: 4b67e95b-c490-40c1-9058-239083686260) for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 from executor(1)@172.17.0.3:32857
I0118 14:27:19.660459 24795 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 4b67e95b-c490-40c1-9058-239083686260) for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.660503 24795 status_update_manager.cpp:500] Creating StatusUpdate stream for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.660771 24795 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 4b67e95b-c490-40c1-9058-239083686260) for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 to the agent
I0118 14:27:19.660948 24801 slave.cpp:4196] Forwarding the update TASK_RUNNING (UUID: 4b67e95b-c490-40c1-9058-239083686260) for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 to master@172.17.0.3:36408
I0118 14:27:19.661067 24801 slave.cpp:4090] Status update manager successfully handled status update TASK_RUNNING (UUID: 4b67e95b-c490-40c1-9058-239083686260) for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.661103 24801 slave.cpp:4106] Sending acknowledgement for status update TASK_RUNNING (UUID: 4b67e95b-c490-40c1-9058-239083686260) for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 to executor(1)@172.17.0.3:32857
I0118 14:27:19.661293 24808 master.cpp:5855] Status update TASK_RUNNING (UUID: 4b67e95b-c490-40c1-9058-239083686260) for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 from agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 at slave(582)@172.17.0.3:36408 (48a8b8c0b334)
I0118 14:27:19.661330 24808 master.cpp:5917] Forwarding status update TASK_RUNNING (UUID: 4b67e95b-c490-40c1-9058-239083686260) for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.661422 24808 master.cpp:7956] Updating the state of task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0118 14:27:19.661578 24796 sched.cpp:1041] Scheduler::statusUpdate took 99851ns
I0118 14:27:19.661793 24809 master.cpp:4948] Processing ACKNOWLEDGE call 4b67e95b-c490-40c1-9058-239083686260 for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 (default) at scheduler-4c480956-b28c-4811-8403-ebd1711ab501@172.17.0.3:36408 on agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0
I0118 14:27:19.662035 24799 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 4b67e95b-c490-40c1-9058-239083686260) for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.662263 24797 slave.cpp:3045] Status update manager successfully handled status update acknowledgement (UUID: 4b67e95b-c490-40c1-9058-239083686260) for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.663365 24807 process.cpp:3676] Handling HTTP event for process 'slave(582)' with path: '/slave(582)/api/v1'
I0118 14:27:19.664041 24799 http.cpp:303] HTTP POST for /slave(582)/api/v1 from 172.17.0.3:51806
I0118 14:27:19.664388 24799 http.cpp:464] Processing call LAUNCH_NESTED_CONTAINER_SESSION
I0118 14:27:19.664666 24800 containerizer.cpp:1807] Starting nested container 0a48bd0a-615f-4cfb-bf82-f43ba2796718.eea0df2a-913c-4543-87b5-831609d0f80b
I0118 14:27:19.664952 24800 containerizer.cpp:1831] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl/slaves/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0/frameworks/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000/executors/12154f47-0469-4319-85b3-c3b7ad15a954/runs/0a48bd0a-615f-4cfb-bf82-f43ba2796718/containers/eea0df2a-913c-4543-87b5-831609d0f80b' to user 'mesos'
I0118 14:27:19.666141 24803 switchboard.cpp:570] Launching 'mesos-io-switchboard' with flags '--heartbeat_interval="30secs" --help="false" --socket_address="/tmp/mesos-io-switchboard-95eb1dd3-d02a-4251-9974-8649a50766aa" --stderr_from_fd="16" --stderr_to_fd="2" --stdin_to_fd="13" --stdout_from_fd="14" --stdout_to_fd="1" --tty="false" --wait_for_connection="true"' for container 0a48bd0a-615f-4cfb-bf82-f43ba2796718.eea0df2a-913c-4543-87b5-831609d0f80b
I0118 14:27:19.668589 24803 switchboard.cpp:600] Created I/O switchboard server (pid: 28999) listening on socket file '/tmp/mesos-io-switchboard-95eb1dd3-d02a-4251-9974-8649a50766aa' for container 0a48bd0a-615f-4cfb-bf82-f43ba2796718.eea0df2a-913c-4543-87b5-831609d0f80b
I0118 14:27:19.670063 24809 containerizer.cpp:1540] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"printf output && printf error 1>&2"},"environment":{},"err":{"fd":17,"type":"FD"},"in":{"fd":12,"type":"FD"},"out":{"fd":15,"type":"FD"},"user":"mesos"}" --pipe_read="13" --pipe_write="14" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_uPJHl3/containers/0a48bd0a-615f-4cfb-bf82-f43ba2796718/containers/eea0df2a-913c-4543-87b5-831609d0f80b" --unshare_namespace_mnt="false"'
I0118 14:27:19.671250 24809 launcher.cpp:133] Forked child with pid '29000' for container '0a48bd0a-615f-4cfb-bf82-f43ba2796718.eea0df2a-913c-4543-87b5-831609d0f80b'
I0118 14:27:19.673565 24804 fetcher.cpp:349] Starting to fetch URIs for container: 0a48bd0a-615f-4cfb-bf82-f43ba2796718.eea0df2a-913c-4543-87b5-831609d0f80b, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl/slaves/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0/frameworks/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000/executors/12154f47-0469-4319-85b3-c3b7ad15a954/runs/0a48bd0a-615f-4cfb-bf82-f43ba2796718/containers/eea0df2a-913c-4543-87b5-831609d0f80b
**** DEADLOCK DETECTED! ****
You are waiting on process __limiter__(602)@172.17.0.3:36408 that it is currently executing.
I0118 14:27:19.728075 24796 http.cpp:2708] Received EOF attach response for 0a48bd0a-615f-4cfb-bf82-f43ba2796718.eea0df2a-913c-4543-87b5-831609d0f80b
I0118 14:27:19.728543 24798 containerizer.cpp:2118] Destroying container 0a48bd0a-615f-4cfb-bf82-f43ba2796718.eea0df2a-913c-4543-87b5-831609d0f80b in RUNNING state
W0118 14:27:19.728680 24795 http.cpp:2723] Launch nested container session connection for container 0a48bd0a-615f-4cfb-bf82-f43ba2796718.eea0df2a-913c-4543-87b5-831609d0f80b closed
I0118 14:27:19.728694 24798 launcher.cpp:149] Asked to destroy container 0a48bd0a-615f-4cfb-bf82-f43ba2796718.eea0df2a-913c-4543-87b5-831609d0f80b
I0118 14:27:19.729008 24793 sched.cpp:2021] Asked to stop the driver
I0118 14:27:19.729087 24805 sched.cpp:1203] Stopping framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.729341 24801 master.cpp:7380] Processing TEARDOWN call for framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 (default) at scheduler-4c480956-b28c-4811-8403-ebd1711ab501@172.17.0.3:36408
I0118 14:27:19.729367 24801 master.cpp:7392] Removing framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 (default) at scheduler-4c480956-b28c-4811-8403-ebd1711ab501@172.17.0.3:36408
I0118 14:27:19.729591 24805 hierarchical.cpp:386] Deactivated framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.729661 24807 slave.cpp:2598] Asked to shut down framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 by master@172.17.0.3:36408
I0118 14:27:19.729666 24801 master.cpp:7956] Updating the state of task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0118 14:27:19.729684 24807 slave.cpp:2623] Shutting down framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.729740 24807 slave.cpp:5005] Shutting down executor '12154f47-0469-4319-85b3-c3b7ad15a954' of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 at executor(1)@172.17.0.3:32857
I0118 14:27:19.729851 24801 master.cpp:8052] Removing task 12154f47-0469-4319-85b3-c3b7ad15a954 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 on agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 at slave(582)@172.17.0.3:36408 (48a8b8c0b334)
I0118 14:27:19.729960 24808 hierarchical.cpp:1011] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 from framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.730255 24795 hierarchical.cpp:337] Removed framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.732147 24799 containerizer.cpp:2118] Destroying container 0a48bd0a-615f-4cfb-bf82-f43ba2796718 in RUNNING state
I0118 14:27:19.746992 24799 slave.cpp:3756] Handling status update TASK_KILLED (UUID: 28392090-2a09-4cd3-9df0-ae0dba1f6ba3) for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 from executor(1)@172.17.0.3:32857
W0118 14:27:19.747032 24799 slave.cpp:3825] Ignoring status update TASK_KILLED (UUID: 28392090-2a09-4cd3-9df0-ae0dba1f6ba3) for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 for terminating framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.794616 24801 switchboard.cpp:861] I/O switchboard server process for container 0a48bd0a-615f-4cfb-bf82-f43ba2796718.eea0df2a-913c-4543-87b5-831609d0f80b has terminated (status=0)
I0118 14:27:19.795023 24802 containerizer.cpp:2481] Container 0a48bd0a-615f-4cfb-bf82-f43ba2796718.eea0df2a-913c-4543-87b5-831609d0f80b has exited
I0118 14:27:19.796123 24807 provisioner.cpp:322] Ignoring destroy request for unknown container 0a48bd0a-615f-4cfb-bf82-f43ba2796718.eea0df2a-913c-4543-87b5-831609d0f80b
I0118 14:27:19.796258 24804 containerizer.cpp:2397] Checkpointing termination state to nested container's runtime directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_uPJHl3/containers/0a48bd0a-615f-4cfb-bf82-f43ba2796718/containers/eea0df2a-913c-4543-87b5-831609d0f80b/termination'
I0118 14:27:19.796941 24804 launcher.cpp:149] Asked to destroy container 0a48bd0a-615f-4cfb-bf82-f43ba2796718
I0118 14:27:19.804502 24804 slave.cpp:4328] Got exited event for executor(1)@172.17.0.3:32857
I0118 14:27:19.895658 24794 containerizer.cpp:2481] Container 0a48bd0a-615f-4cfb-bf82-f43ba2796718 has exited
I0118 14:27:19.897486 24804 provisioner.cpp:322] Ignoring destroy request for unknown container 0a48bd0a-615f-4cfb-bf82-f43ba2796718
I0118 14:27:19.898604 24799 slave.cpp:4691] Executor '12154f47-0469-4319-85b3-c3b7ad15a954' of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 terminated with signal Killed
I0118 14:27:19.898645 24799 slave.cpp:4791] Cleaning up executor '12154f47-0469-4319-85b3-c3b7ad15a954' of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000 at executor(1)@172.17.0.3:32857
I0118 14:27:19.898900 24800 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl/slaves/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0/frameworks/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000/executors/12154f47-0469-4319-85b3-c3b7ad15a954/runs/0a48bd0a-615f-4cfb-bf82-f43ba2796718' for gc 6.99998959726815days in the future
I0118 14:27:19.898933 24799 slave.cpp:4879] Cleaning up framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.899026 24800 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl/slaves/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0/frameworks/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000/executors/12154f47-0469-4319-85b3-c3b7ad15a954' for gc 6.99998959597333days in the future
I0118 14:27:19.899067 24808 status_update_manager.cpp:285] Closing status update streams for framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
I0118 14:27:19.899091 24808 status_update_manager.cpp:531] Cleaning up status update stream for task 12154f47-0469-4319-85b3-c3b7ad15a954 of framework 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000
W0118 14:27:19.899128 24807 containerizer.cpp:2106] Attempted to destroy unknown container 0a48bd0a-615f-4cfb-bf82-f43ba2796718.eea0df2a-913c-4543-87b5-831609d0f80b
I0118 14:27:19.899176 24800 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_1_RZ7Nkl/slaves/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0/frameworks/6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-0000' for gc 6.99998959428148days in the future
I0118 14:27:19.899466 24797 slave.cpp:801] Agent terminating
I0118 14:27:19.899618 24794 master.cpp:1261] Agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 at slave(582)@172.17.0.3:36408 (48a8b8c0b334) disconnected
I0118 14:27:19.899651 24794 master.cpp:3051] Disconnecting agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 at slave(582)@172.17.0.3:36408 (48a8b8c0b334)
I0118 14:27:19.899700 24794 master.cpp:3070] Deactivating agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 at slave(582)@172.17.0.3:36408 (48a8b8c0b334)
I0118 14:27:19.899797 24803 hierarchical.cpp:577] Agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0 deactivated
I0118 14:27:19.903779 24793 master.cpp:1100] Master terminating
I0118 14:27:19.904060 24808 hierarchical.cpp:510] Removed agent 6b51565e-1a6c-4d19-9b92-f1344f0dd9b9-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSession/1 (402 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/0
I0118 14:27:19.909760 24793 cluster.cpp:160] Creating default 'local' authorizer
I0118 14:27:19.910931 24799 master.cpp:383] Master 50c441ed-7e31-433f-b2db-d216e937e5f8 (48a8b8c0b334) started on 172.17.0.3:36408
I0118 14:27:19.910970 24799 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/N78l4u/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="/usr/local/share/mesos/webui" --work_dir="/tmp/N78l4u/master" --zk_session_timeout="10secs"
I0118 14:27:19.911319 24799 master.cpp:435] Master only allowing authenticated frameworks to register
I0118 14:27:19.911337 24799 master.cpp:449] Master only allowing authenticated agents to register
I0118 14:27:19.911351 24799 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0118 14:27:19.911365 24799 credentials.hpp:37] Loading credentials for authentication from '/tmp/N78l4u/credentials'
I0118 14:27:19.911556 24799 master.cpp:507] Using default 'crammd5' authenticator
I0118 14:27:19.911617 24799 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0118 14:27:19.911675 24799 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0118 14:27:19.911754 24799 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0118 14:27:19.911833 24799 master.cpp:587] Authorization enabled
I0118 14:27:19.912106 24797 whitelist_watcher.cpp:77] No whitelist given
I0118 14:27:19.912123 24802 hierarchical.cpp:151] Initialized hierarchical allocator process
I0118 14:27:19.913197 24797 master.cpp:2119] Elected as the leading master!
I0118 14:27:19.913218 24797 master.cpp:1641] Recovering from registrar
I0118 14:27:19.913316 24804 registrar.cpp:329] Recovering registrar
I0118 14:27:19.913561 24804 registrar.cpp:362] Successfully fetched the registry (0B) in 203008ns
I0118 14:27:19.913621 24804 registrar.cpp:461] Applied 1 operations in 20984ns; attempting to update the registry
I0118 14:27:19.913990 24801 registrar.cpp:506] Successfully updated the registry in 320us
I0118 14:27:19.914072 24801 registrar.cpp:392] Successfully recovered registrar
I0118 14:27:19.914394 24800 master.cpp:1757] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0118 14:27:19.914424 24799 hierarchical.cpp:178] Skipping recovery of hierarchical allocator: nothing to recover
Build timed out (after 300 minutes). Marking the build as failed.
Build was aborted
[operations-center-context] Triggering Mesos-Reviewbot-Test
++ docker rmi mesos-1484747372-25254
Error response from daemon: conflict: unable to remove repository reference "mesos-1484747372-25254" (must force) - container 48a8b8c0b334 is using its referenced image 6ce224eef5fb
Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2) #3127
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3127/changes>