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/13 04:42:33 UTC

Build failed in Jenkins: Mesos » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2) #3033

See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3033/changes>

Changes:

[anand] Fixed erroneous usage of 'Accept' header as 'Accept-Type'.

[vinodkone] Removed "enable_io_switchboard_server" agent flag.

[vinodkone] Fixed EOF bug when tty is enabled in switch board server.

[yujie.jay] Updated address of 'http::connect()' with http scheme for SSL support.

[yujie.jay] Changed 'Socket::create()' in IOSwitchboard to always be of 'kind' POLL.

[yujie.jay] Changed the timing of entering mount namespace for DEBUG container.

[yujie.jay] Fixed an unreachable statement in launch.cpp.

[vinodkone] Added a `http_heartbeat_interval` flag to the agent flags.

[vinodkone] Added a bi-directional heartbeat for IOSwitchboard connections.

------------------------------------------
[...truncated 156520 lines...]
I1213 00:19:27.193925 32629 cluster.cpp:160] Creating default 'local' authorizer
I1213 00:19:27.196971 32662 master.cpp:380] Master 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1 (3aa2cbe708fa) started on 172.17.0.2:47317
I1213 00:19:27.197008 32662 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/paJhlI/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/paJhlI/master" --zk_session_timeout="10secs"
I1213 00:19:27.197639 32662 master.cpp:432] Master only allowing authenticated frameworks to register
I1213 00:19:27.197664 32662 master.cpp:446] Master only allowing authenticated agents to register
I1213 00:19:27.197685 32662 master.cpp:459] Master only allowing authenticated HTTP frameworks to register
I1213 00:19:27.197703 32662 credentials.hpp:37] Loading credentials for authentication from '/tmp/paJhlI/credentials'
I1213 00:19:27.198114 32662 master.cpp:504] Using default 'crammd5' authenticator
I1213 00:19:27.198263 32662 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I1213 00:19:27.198428 32662 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I1213 00:19:27.198714 32662 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I1213 00:19:27.199061 32662 master.cpp:584] Authorization enabled
I1213 00:19:27.199239 32663 hierarchical.cpp:149] Initialized hierarchical allocator process
I1213 00:19:27.199252 32658 whitelist_watcher.cpp:77] No whitelist given
I1213 00:19:27.202890 32651 master.cpp:2045] Elected as the leading master!
I1213 00:19:27.202924 32651 master.cpp:1568] Recovering from registrar
I1213 00:19:27.203121 32662 registrar.cpp:329] Recovering registrar
I1213 00:19:27.204025 32662 registrar.cpp:362] Successfully fetched the registry (0B) in 838912ns
I1213 00:19:27.204144 32662 registrar.cpp:461] Applied 1 operations in 25451ns; attempting to update the registry
I1213 00:19:27.205054 32657 registrar.cpp:506] Successfully updated the registry in 835072ns
I1213 00:19:27.205222 32657 registrar.cpp:392] Successfully recovered registrar
I1213 00:19:27.205902 32650 master.cpp:1684] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I1213 00:19:27.206099 32662 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover
I1213 00:19:27.208978 32629 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1213 00:19:27.209626 32629 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1213 00:19:27.209852 32629 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1213 00:19:27.214242 32629 cluster.cpp:446] Creating default 'local' authorizer
I1213 00:19:27.216205 32657 slave.cpp:209] Mesos agent started on (582)@172.17.0.2:47317
I1213 00:19:27.216223 32657 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_AgentAPIStreamingTest_AttachContainerInput_1_JjOdqe/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_AgentAPIStreamingTest_AttachContainerInput_1_JjOdqe/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_AgentAPIStreamingTest_AttachContainerInput_1_JjOdqe/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_AgentAPIStreamingTest_AttachContainerInput_1_JjOdqe" --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_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY"
I1213 00:19:27.216694 32657 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_JjOdqe/credential'
I1213 00:19:27.216847 32657 slave.cpp:347] Agent using credential for: test-principal
I1213 00:19:27.216866 32657 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_JjOdqe/http_credentials'
I1213 00:19:27.217075 32657 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1213 00:19:27.217224 32657 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1213 00:19:27.218057 32629 sched.cpp:232] Version: 1.2.0
I1213 00:19:27.218216 32657 slave.cpp:534] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1213 00:19:27.218293 32657 slave.cpp:542] Agent attributes: [  ]
I1213 00:19:27.218304 32657 slave.cpp:547] Agent hostname: 3aa2cbe708fa
I1213 00:19:27.218714 32659 sched.cpp:336] New master detected at master@172.17.0.2:47317
I1213 00:19:27.218817 32659 sched.cpp:402] Authenticating with master master@172.17.0.2:47317
I1213 00:19:27.218850 32659 sched.cpp:409] Using default CRAM-MD5 authenticatee
I1213 00:19:27.219144 32661 authenticatee.cpp:121] Creating new client SASL connection
I1213 00:19:27.219480 32653 master.cpp:6748] Authenticating scheduler-1e1e9efe-7532-40af-8c59-ea7f2a65ae84@172.17.0.2:47317
I1213 00:19:27.219660 32652 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1129)@172.17.0.2:47317
I1213 00:19:27.220088 32656 state.cpp:57] Recovering state from '/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY/meta'
I1213 00:19:27.220108 32654 authenticator.cpp:98] Creating new server SASL connection
I1213 00:19:27.220355 32662 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1213 00:19:27.220526 32662 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1213 00:19:27.220402 32648 status_update_manager.cpp:203] Recovering status update manager
I1213 00:19:27.220685 32650 authenticator.cpp:204] Received SASL authentication start
I1213 00:19:27.220757 32650 authenticator.cpp:326] Authentication requires more steps
I1213 00:19:27.220878 32660 containerizer.cpp:594] Recovering containerizer
I1213 00:19:27.220909 32656 authenticatee.cpp:259] Received SASL authentication step
I1213 00:19:27.221041 32656 authenticator.cpp:232] Received SASL authentication step
I1213 00:19:27.221079 32656 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3aa2cbe708fa' server FQDN: '3aa2cbe708fa' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I1213 00:19:27.221107 32656 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1213 00:19:27.221146 32656 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1213 00:19:27.221175 32656 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3aa2cbe708fa' server FQDN: '3aa2cbe708fa' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I1213 00:19:27.221192 32656 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1213 00:19:27.221202 32656 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1213 00:19:27.221221 32656 authenticator.cpp:318] Authentication success
I1213 00:19:27.221367 32662 master.cpp:6778] Successfully authenticated principal 'test-principal' at scheduler-1e1e9efe-7532-40af-8c59-ea7f2a65ae84@172.17.0.2:47317
I1213 00:19:27.221483 32655 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1129)@172.17.0.2:47317
I1213 00:19:27.221498 32653 authenticatee.cpp:299] Authentication success
I1213 00:19:27.222126 32652 sched.cpp:508] Successfully authenticated with master master@172.17.0.2:47317
I1213 00:19:27.222154 32652 sched.cpp:826] Sending SUBSCRIBE call to master@172.17.0.2:47317
I1213 00:19:27.222333 32652 sched.cpp:859] Will retry registration in 265.889451ms if necessary
I1213 00:19:27.222502 32654 master.cpp:2633] Received SUBSCRIBE call for framework 'default' at scheduler-1e1e9efe-7532-40af-8c59-ea7f2a65ae84@172.17.0.2:47317
I1213 00:19:27.222590 32654 master.cpp:2081] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1213 00:19:27.223049 32659 provisioner.cpp:253] Provisioner recovery complete
I1213 00:19:27.223127 32655 master.cpp:2709] Subscribing framework default with checkpointing disabled and capabilities [  ]
I1213 00:19:27.223659 32650 slave.cpp:5415] Finished recovery
I1213 00:19:27.223955 32653 sched.cpp:749] Framework registered with 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.223981 32661 hierarchical.cpp:276] Added framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.224010 32653 sched.cpp:763] Scheduler::registered took 25374ns
I1213 00:19:27.224064 32661 hierarchical.cpp:1689] No allocations performed
I1213 00:19:27.224097 32661 hierarchical.cpp:1784] No inverse offers to send out!
I1213 00:19:27.224154 32661 hierarchical.cpp:1291] Performed allocation for 0 agents in 141365ns
I1213 00:19:27.224352 32650 slave.cpp:5589] Querying resource estimator for oversubscribable resources
I1213 00:19:27.224608 32650 slave.cpp:919] New master detected at master@172.17.0.2:47317
I1213 00:19:27.224614 32651 status_update_manager.cpp:177] Pausing sending status updates
I1213 00:19:27.224643 32650 slave.cpp:978] Authenticating with master master@172.17.0.2:47317
I1213 00:19:27.224736 32650 slave.cpp:989] Using default CRAM-MD5 authenticatee
I1213 00:19:27.224923 32650 slave.cpp:951] Detecting new master
I1213 00:19:27.224968 32658 authenticatee.cpp:121] Creating new client SASL connection
I1213 00:19:27.225117 32650 slave.cpp:5603] Received oversubscribable resources {} from the resource estimator
I1213 00:19:27.225425 32649 master.cpp:6748] Authenticating slave(582)@172.17.0.2:47317
I1213 00:19:27.225549 32659 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1130)@172.17.0.2:47317
I1213 00:19:27.225816 32648 authenticator.cpp:98] Creating new server SASL connection
I1213 00:19:27.226048 32658 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1213 00:19:27.226120 32658 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1213 00:19:27.226246 32663 authenticator.cpp:204] Received SASL authentication start
I1213 00:19:27.226341 32663 authenticator.cpp:326] Authentication requires more steps
I1213 00:19:27.226487 32653 authenticatee.cpp:259] Received SASL authentication step
I1213 00:19:27.226632 32650 authenticator.cpp:232] Received SASL authentication step
I1213 00:19:27.226667 32650 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3aa2cbe708fa' server FQDN: '3aa2cbe708fa' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I1213 00:19:27.226686 32650 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1213 00:19:27.226718 32650 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1213 00:19:27.226761 32650 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3aa2cbe708fa' server FQDN: '3aa2cbe708fa' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I1213 00:19:27.226794 32650 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1213 00:19:27.226819 32650 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1213 00:19:27.226857 32650 authenticator.cpp:318] Authentication success
I1213 00:19:27.227008 32661 authenticatee.cpp:299] Authentication success
I1213 00:19:27.227035 32658 master.cpp:6778] Successfully authenticated principal 'test-principal' at slave(582)@172.17.0.2:47317
I1213 00:19:27.227099 32652 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1130)@172.17.0.2:47317
I1213 00:19:27.227391 32662 slave.cpp:1073] Successfully authenticated with master master@172.17.0.2:47317
I1213 00:19:27.227607 32662 slave.cpp:1487] Will retry registration in 9.022531ms if necessary
I1213 00:19:27.227841 32648 master.cpp:5161] Registering agent at slave(582)@172.17.0.2:47317 (3aa2cbe708fa) with id 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0
I1213 00:19:27.228296 32656 registrar.cpp:461] Applied 1 operations in 62775ns; attempting to update the registry
I1213 00:19:27.230116 32656 registrar.cpp:506] Successfully updated the registry in 1.715968ms
I1213 00:19:27.230890 32656 slave.cpp:4267] Received ping from slave-observer(530)@172.17.0.2:47317
I1213 00:19:27.230934 32657 master.cpp:5232] Registered agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 at slave(582)@172.17.0.2:47317 (3aa2cbe708fa) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1213 00:19:27.231128 32656 slave.cpp:1119] Registered with master master@172.17.0.2:47317; given agent ID 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0
I1213 00:19:27.231159 32656 fetcher.cpp:90] Clearing fetcher cache
I1213 00:19:27.231315 32662 status_update_manager.cpp:184] Resuming sending status updates
I1213 00:19:27.231343 32660 hierarchical.cpp:490] Added agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 (3aa2cbe708fa) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I1213 00:19:27.231581 32656 slave.cpp:1142] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY/meta/slaves/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0/slave.info'
I1213 00:19:27.231964 32656 slave.cpp:1179] Forwarding total oversubscribed resources {}
I1213 00:19:27.232131 32663 master.cpp:5633] Received update of agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 at slave(582)@172.17.0.2:47317 (3aa2cbe708fa) with total oversubscribed resources {}
I1213 00:19:27.232540 32660 hierarchical.cpp:1784] No inverse offers to send out!
I1213 00:19:27.232616 32660 hierarchical.cpp:1314] Performed allocation for agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 in 1.215913ms
I1213 00:19:27.232780 32660 hierarchical.cpp:560] Agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 (3aa2cbe708fa) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I1213 00:19:27.232923 32660 hierarchical.cpp:1689] No allocations performed
I1213 00:19:27.232964 32660 hierarchical.cpp:1784] No inverse offers to send out!
I1213 00:19:27.233021 32660 hierarchical.cpp:1314] Performed allocation for agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 in 188583ns
I1213 00:19:27.233085 32656 master.cpp:6577] Sending 1 offers to framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 (default) at scheduler-1e1e9efe-7532-40af-8c59-ea7f2a65ae84@172.17.0.2:47317
I1213 00:19:27.233588 32656 sched.cpp:923] Scheduler::resourceOffers took 122008ns
I1213 00:19:27.235607 32658 master.cpp:3588] Processing ACCEPT call for offers: [ 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-O0 ] on agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 at slave(582)@172.17.0.2:47317 (3aa2cbe708fa) for framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 (default) at scheduler-1e1e9efe-7532-40af-8c59-ea7f2a65ae84@172.17.0.2:47317
I1213 00:19:27.235715 32658 master.cpp:3175] Authorizing framework principal 'test-principal' to launch task 46dafd76-5c59-4f10-b79e-3d248bffc3de
I1213 00:19:27.238010 32658 master.cpp:8501] Adding task 46dafd76-5c59-4f10-b79e-3d248bffc3de with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 (3aa2cbe708fa)
I1213 00:19:27.238373 32658 master.cpp:4240] Launching task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 (default) at scheduler-1e1e9efe-7532-40af-8c59-ea7f2a65ae84@172.17.0.2:47317 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 at slave(582)@172.17.0.2:47317 (3aa2cbe708fa)
I1213 00:19:27.239056 32659 slave.cpp:1551] Got assigned task '46dafd76-5c59-4f10-b79e-3d248bffc3de' for framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.239701 32659 slave.cpp:1713] Launching task '46dafd76-5c59-4f10-b79e-3d248bffc3de' for framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.240286 32659 paths.cpp:530] Trying to chown '/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY/slaves/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0/frameworks/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000/executors/46dafd76-5c59-4f10-b79e-3d248bffc3de/runs/12a7c8f2-3068-4c26-bdf5-f20627535e48' to user 'mesos'
I1213 00:19:27.251576 32659 slave.cpp:6342] Launching executor '46dafd76-5c59-4f10-b79e-3d248bffc3de' of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY/slaves/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0/frameworks/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000/executors/46dafd76-5c59-4f10-b79e-3d248bffc3de/runs/12a7c8f2-3068-4c26-bdf5-f20627535e48'
I1213 00:19:27.252375 32654 containerizer.cpp:986] Starting container 12a7c8f2-3068-4c26-bdf5-f20627535e48 for executor '46dafd76-5c59-4f10-b79e-3d248bffc3de' of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.252511 32659 slave.cpp:2035] Queued task '46dafd76-5c59-4f10-b79e-3d248bffc3de' for executor '46dafd76-5c59-4f10-b79e-3d248bffc3de' of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.252600 32659 slave.cpp:872] Successfully attached file '/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY/slaves/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0/frameworks/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000/executors/46dafd76-5c59-4f10-b79e-3d248bffc3de/runs/12a7c8f2-3068-4c26-bdf5-f20627535e48'
I1213 00:19:27.257302 32653 containerizer.cpp:1522] 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.2:47317"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY\/slaves\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0\/frameworks\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000\/executors\/46dafd76-5c59-4f10-b79e-3d248bffc3de\/runs\/12a7c8f2-3068-4c26-bdf5-f20627535e48"},{"name":"MESOS_EXECUTOR_ID","value":"46dafd76-5c59-4f10-b79e-3d248bffc3de"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(582)@172.17.0.2:47317"},{"name":"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY\/slaves\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0\/frameworks\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000\/executors\/46dafd76-5c59-4f10-b79e-3d248bffc3de\/runs\/12a7c8f2-3068-4c26-bdf5-f20627535e48"}]},"err":{"path":"\/tmp\/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY\/slaves\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0\/frameworks\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000\/executors\/46dafd76-5c59-4f10-b79e-3d248bffc3de\/runs\/12a7c8f2-3068-4c26-bdf5-f20627535e48\/stderr","type":"PATH"},"out":{"path":"\/tmp\/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY\/slaves\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0\/frameworks\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000\/executors\/46dafd76-5c59-4f10-b79e-3d248bffc3de\/runs\/12a7c8f2-3068-4c26-bdf5-f20627535e48\/stdout","type":"PATH"},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY\/slaves\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0\/frameworks\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000\/executors\/46dafd76-5c59-4f10-b79e-3d248bffc3de\/runs\/12a7c8f2-3068-4c26-bdf5-f20627535e48"}" --pipe_read="16" --pipe_write="18" --runtime_directory="/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_JjOdqe/containers/12a7c8f2-3068-4c26-bdf5-f20627535e48" --unshare_namespace_mnt="false"'
I1213 00:19:27.261512 32653 launcher.cpp:133] Forked child with pid '12735' for container '12a7c8f2-3068-4c26-bdf5-f20627535e48'
I1213 00:19:27.265645 32660 fetcher.cpp:349] Starting to fetch URIs for container: 12a7c8f2-3068-4c26-bdf5-f20627535e48, directory: /tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY/slaves/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0/frameworks/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000/executors/46dafd76-5c59-4f10-b79e-3d248bffc3de/runs/12a7c8f2-3068-4c26-bdf5-f20627535e48
I1213 00:19:27.446826 32661 slave.cpp:3309] Got registration for executor '46dafd76-5c59-4f10-b79e-3d248bffc3de' of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 from executor(1)@172.17.0.2:52470
I1213 00:19:27.449189 32662 slave.cpp:2251] Sending queued task '46dafd76-5c59-4f10-b79e-3d248bffc3de' to executor '46dafd76-5c59-4f10-b79e-3d248bffc3de' of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 at executor(1)@172.17.0.2:52470
I1213 00:19:27.463459 32656 slave.cpp:3744] Handling status update TASK_RUNNING (UUID: 359987cc-13ae-450e-b521-cbd077969d88) for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 from executor(1)@172.17.0.2:52470
I1213 00:19:27.465181 32650 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 359987cc-13ae-450e-b521-cbd077969d88) for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.465232 32650 status_update_manager.cpp:500] Creating StatusUpdate stream for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.465754 32650 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 359987cc-13ae-450e-b521-cbd077969d88) for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 to the agent
I1213 00:19:27.466089 32653 slave.cpp:4185] Forwarding the update TASK_RUNNING (UUID: 359987cc-13ae-450e-b521-cbd077969d88) for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 to master@172.17.0.2:47317
I1213 00:19:27.466297 32653 slave.cpp:4079] Status update manager successfully handled status update TASK_RUNNING (UUID: 359987cc-13ae-450e-b521-cbd077969d88) for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.466351 32653 slave.cpp:4095] Sending acknowledgement for status update TASK_RUNNING (UUID: 359987cc-13ae-450e-b521-cbd077969d88) for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 to executor(1)@172.17.0.2:52470
I1213 00:19:27.466537 32649 master.cpp:5769] Status update TASK_RUNNING (UUID: 359987cc-13ae-450e-b521-cbd077969d88) for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 from agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 at slave(582)@172.17.0.2:47317 (3aa2cbe708fa)
I1213 00:19:27.466598 32649 master.cpp:5831] Forwarding status update TASK_RUNNING (UUID: 359987cc-13ae-450e-b521-cbd077969d88) for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.466820 32649 master.cpp:7867] Updating the state of task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1213 00:19:27.467196 32655 sched.cpp:1031] Scheduler::statusUpdate took 143560ns
I1213 00:19:27.467667 32655 master.cpp:4877] Processing ACKNOWLEDGE call 359987cc-13ae-450e-b521-cbd077969d88 for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 (default) at scheduler-1e1e9efe-7532-40af-8c59-ea7f2a65ae84@172.17.0.2:47317 on agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0
I1213 00:19:27.468057 32657 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 359987cc-13ae-450e-b521-cbd077969d88) for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.468540 32661 slave.cpp:3026] Status update manager successfully handled status update acknowledgement (UUID: 359987cc-13ae-450e-b521-cbd077969d88) for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.469650 32662 process.cpp:3679] Handling HTTP event for process 'slave(582)' with path: '/slave(582)/api/v1'
I1213 00:19:27.470640 32651 http.cpp:301] HTTP POST for /slave(582)/api/v1 from 172.17.0.2:59930
I1213 00:19:27.471092 32651 http.cpp:462] Processing call LAUNCH_NESTED_CONTAINER
I1213 00:19:27.471566 32661 containerizer.cpp:1789] Starting nested container 12a7c8f2-3068-4c26-bdf5-f20627535e48.26563ec1-1fcc-42f9-9c51-632593c3d28f
I1213 00:19:27.471809 32661 containerizer.cpp:1813] Trying to chown '/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY/slaves/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0/frameworks/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000/executors/46dafd76-5c59-4f10-b79e-3d248bffc3de/runs/12a7c8f2-3068-4c26-bdf5-f20627535e48/containers/26563ec1-1fcc-42f9-9c51-632593c3d28f' to user 'mesos'
I1213 00:19:27.484879 32654 switchboard.cpp:430] Allocated pseudo terminal '/dev/pts/1' for container 12a7c8f2-3068-4c26-bdf5-f20627535e48.26563ec1-1fcc-42f9-9c51-632593c3d28f
I1213 00:19:27.485303 32654 switchboard.cpp:567] Launching 'mesos-io-switchboard' with flags '--heartbeat_interval="30secs" --help="false" --socket_address="/tmp/mesos-io-switchboard-df43f34b-e969-4051-9f88-2c01befd987c" --stderr_from_fd="22" --stderr_to_fd="2" --stdin_to_fd="22" --stdout_from_fd="22" --stdout_to_fd="1" --tty="true" --wait_for_connection="false"' for container 12a7c8f2-3068-4c26-bdf5-f20627535e48.26563ec1-1fcc-42f9-9c51-632593c3d28f
I1213 00:19:27.489310 32654 switchboard.cpp:597] Created I/O switchboard server (pid: 12795) listening on socket file '/tmp/mesos-io-switchboard-df43f34b-e969-4051-9f88-2c01befd987c' for container 12a7c8f2-3068-4c26-bdf5-f20627535e48.26563ec1-1fcc-42f9-9c51-632593c3d28f
I1213 00:19:27.491619 32652 containerizer.cpp:1522] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"cat"},"environment":{"variables":[{"name":"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY\/slaves\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0\/frameworks\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000\/executors\/46dafd76-5c59-4f10-b79e-3d248bffc3de\/runs\/12a7c8f2-3068-4c26-bdf5-f20627535e48\/containers\/26563ec1-1fcc-42f9-9c51-632593c3d28f"}]},"err":{"fd":23,"type":"FD"},"in":{"fd":23,"type":"FD"},"out":{"fd":23,"type":"FD"},"tty_slave_path":"\/dev\/pts\/1","user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY\/slaves\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0\/frameworks\/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000\/executors\/46dafd76-5c59-4f10-b79e-3d248bffc3de\/runs\/12a7c8f2-3068-4c26-bdf5-f20627535e48\/containers\/26563ec1-1fcc-42f9-9c51-632593c3d28f"}" --pipe_read="22" --pipe_write="42" --runtime_directory="/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_JjOdqe/containers/12a7c8f2-3068-4c26-bdf5-f20627535e48/containers/26563ec1-1fcc-42f9-9c51-632593c3d28f" --unshare_namespace_mnt="false"'
I1213 00:19:27.495465 32652 launcher.cpp:133] Forked child with pid '12797' for container '12a7c8f2-3068-4c26-bdf5-f20627535e48.26563ec1-1fcc-42f9-9c51-632593c3d28f'
I1213 00:19:27.498608 32660 fetcher.cpp:349] Starting to fetch URIs for container: 12a7c8f2-3068-4c26-bdf5-f20627535e48.26563ec1-1fcc-42f9-9c51-632593c3d28f, directory: /tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY/slaves/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0/frameworks/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000/executors/46dafd76-5c59-4f10-b79e-3d248bffc3de/runs/12a7c8f2-3068-4c26-bdf5-f20627535e48/containers/26563ec1-1fcc-42f9-9c51-632593c3d28f
I1213 00:19:27.505760 32659 process.cpp:3679] Handling HTTP event for process 'slave(582)' with path: '/slave(582)/api/v1'
I1213 00:19:27.506942 32653 http.cpp:301] HTTP POST for /slave(582)/api/v1 from 172.17.0.2:59931
I1213 00:19:27.507413 32653 http.cpp:462] Processing call ATTACH_CONTAINER_OUTPUT

**** DEADLOCK DETECTED! ****
You are waiting on process __limiter__(603)@172.17.0.2:47317 that it is currently executing.
I1213 00:19:27.579654 32658 process.cpp:3679] Handling HTTP event for process 'slave(582)' with path: '/slave(582)/api/v1'
I1213 00:19:27.580656 32657 http.cpp:301] HTTP POST for /slave(582)/api/v1 from 172.17.0.2:59932
I1213 00:19:27.581431 32650 http.cpp:462] Processing call ATTACH_CONTAINER_INPUT
I1213 00:19:27.607985 32657 master.cpp:1305] Framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 (default) at scheduler-1e1e9efe-7532-40af-8c59-ea7f2a65ae84@172.17.0.2:47317 disconnected
I1213 00:19:27.608026 32657 master.cpp:2918] Disconnecting framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 (default) at scheduler-1e1e9efe-7532-40af-8c59-ea7f2a65ae84@172.17.0.2:47317
I1213 00:19:27.608091 32657 master.cpp:2942] Deactivating framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 (default) at scheduler-1e1e9efe-7532-40af-8c59-ea7f2a65ae84@172.17.0.2:47317
I1213 00:19:27.608561 32663 hierarchical.cpp:391] Deactivated framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.608562 32657 master.cpp:1318] Giving framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 (default) at scheduler-1e1e9efe-7532-40af-8c59-ea7f2a65ae84@172.17.0.2:47317 0ns to failover
I1213 00:19:27.608887 32662 master.cpp:6429] Framework failover timeout, removing framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 (default) at scheduler-1e1e9efe-7532-40af-8c59-ea7f2a65ae84@172.17.0.2:47317
I1213 00:19:27.608922 32662 master.cpp:7299] Removing framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 (default) at scheduler-1e1e9efe-7532-40af-8c59-ea7f2a65ae84@172.17.0.2:47317
I1213 00:19:27.609056 32657 slave.cpp:2579] Asked to shut down framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 by master@172.17.0.2:47317
I1213 00:19:27.609102 32662 master.cpp:7867] Updating the state of task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I1213 00:19:27.609242 32657 slave.cpp:2604] Shutting down framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.609352 32657 slave.cpp:4994] Shutting down executor '46dafd76-5c59-4f10-b79e-3d248bffc3de' of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 at executor(1)@172.17.0.2:52470
I1213 00:19:27.609246 32656 containerizer.cpp:2100] Destroying container 12a7c8f2-3068-4c26-bdf5-f20627535e48 in RUNNING state
I1213 00:19:27.609529 32656 containerizer.cpp:2100] Destroying container 12a7c8f2-3068-4c26-bdf5-f20627535e48.26563ec1-1fcc-42f9-9c51-632593c3d28f in RUNNING state
I1213 00:19:27.610378 32649 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 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 from framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.610560 32662 master.cpp:7963] Removing task 46dafd76-5c59-4f10-b79e-3d248bffc3de with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 on agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 at slave(582)@172.17.0.2:47317 (3aa2cbe708fa)
I1213 00:19:27.610864 32656 launcher.cpp:149] Asked to destroy container 12a7c8f2-3068-4c26-bdf5-f20627535e48.26563ec1-1fcc-42f9-9c51-632593c3d28f
I1213 00:19:27.611184 32649 hierarchical.cpp:342] Removed framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.638597 32650 slave.cpp:3744] Handling status update TASK_KILLED (UUID: 513a58b3-cb66-41fd-b314-01960efe212d) for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 from executor(1)@172.17.0.2:52470
W1213 00:19:27.638655 32650 slave.cpp:3813] Ignoring status update TASK_KILLED (UUID: 513a58b3-cb66-41fd-b314-01960efe212d) for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 for terminating framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
E1213 00:19:27.679812 32654 switchboard.cpp:862] Unexpected termination of I/O switchboard server: 'IOSwitchboard' exited with status: 1 for container 12a7c8f2-3068-4c26-bdf5-f20627535e48.26563ec1-1fcc-42f9-9c51-632593c3d28f
I1213 00:19:27.680251 32649 containerizer.cpp:2463] Container 12a7c8f2-3068-4c26-bdf5-f20627535e48.26563ec1-1fcc-42f9-9c51-632593c3d28f has exited
I1213 00:19:27.682404 32658 provisioner.cpp:324] Ignoring destroy request for unknown container 12a7c8f2-3068-4c26-bdf5-f20627535e48.26563ec1-1fcc-42f9-9c51-632593c3d28f
I1213 00:19:27.682673 32657 containerizer.cpp:2379] Checkpointing termination state to nested container's runtime directory '/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_JjOdqe/containers/12a7c8f2-3068-4c26-bdf5-f20627535e48/containers/26563ec1-1fcc-42f9-9c51-632593c3d28f/termination'
I1213 00:19:27.683805 32657 launcher.cpp:149] Asked to destroy container 12a7c8f2-3068-4c26-bdf5-f20627535e48
I1213 00:19:27.694270 32655 slave.cpp:4313] Got exited event for executor(1)@172.17.0.2:52470
I1213 00:19:27.781460 32650 containerizer.cpp:2463] Container 12a7c8f2-3068-4c26-bdf5-f20627535e48 has exited
I1213 00:19:27.783449 32663 provisioner.cpp:324] Ignoring destroy request for unknown container 12a7c8f2-3068-4c26-bdf5-f20627535e48
I1213 00:19:27.784315 32662 slave.cpp:4676] Executor '46dafd76-5c59-4f10-b79e-3d248bffc3de' of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 terminated with signal Killed
I1213 00:19:27.784374 32662 slave.cpp:4780] Cleaning up executor '46dafd76-5c59-4f10-b79e-3d248bffc3de' of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000 at executor(1)@172.17.0.2:52470
I1213 00:19:27.784615 32648 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY/slaves/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0/frameworks/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000/executors/46dafd76-5c59-4f10-b79e-3d248bffc3de/runs/12a7c8f2-3068-4c26-bdf5-f20627535e48' for gc 6.9999909198963days in the future
I1213 00:19:27.784741 32662 slave.cpp:4868] Cleaning up framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.784837 32648 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY/slaves/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0/frameworks/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000/executors/46dafd76-5c59-4f10-b79e-3d248bffc3de' for gc 6.99999091803259days in the future
I1213 00:19:27.784914 32655 status_update_manager.cpp:285] Closing status update streams for framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
I1213 00:19:27.784991 32653 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_KT7JnY/slaves/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0/frameworks/5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000' for gc 6.99999091553185days in the future
I1213 00:19:27.785022 32655 status_update_manager.cpp:531] Cleaning up status update stream for task 46dafd76-5c59-4f10-b79e-3d248bffc3de of framework 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-0000
W1213 00:19:27.785109 32650 containerizer.cpp:2088] Attempted to destroy unknown container 12a7c8f2-3068-4c26-bdf5-f20627535e48.26563ec1-1fcc-42f9-9c51-632593c3d28f
I1213 00:19:27.785683 32653 slave.cpp:791] Agent terminating
I1213 00:19:27.785877 32661 master.cpp:1258] Agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 at slave(582)@172.17.0.2:47317 (3aa2cbe708fa) disconnected
I1213 00:19:27.785904 32661 master.cpp:2977] Disconnecting agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 at slave(582)@172.17.0.2:47317 (3aa2cbe708fa)
I1213 00:19:27.785964 32661 master.cpp:2996] Deactivating agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 at slave(582)@172.17.0.2:47317 (3aa2cbe708fa)
I1213 00:19:27.786257 32656 hierarchical.cpp:589] Agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0 deactivated
I1213 00:19:27.790937 32648 master.cpp:1097] Master terminating
I1213 00:19:27.791599 32656 hierarchical.cpp:522] Removed agent 5de4c2e6-0cb0-49b2-bdfa-48eaa8c3c5f1-S0
[       OK ] ContentType/AgentAPIStreamingTest.AttachContainerInput/1 (602 ms)
[ RUN      ] ContentType/AgentAPIStreamingTest.AttachInputToNestedContainerSession/0
I1213 00:19:27.799043 32629 cluster.cpp:160] Creating default 'local' authorizer
I1213 00:19:27.802963 32661 master.cpp:380] Master a2534773-3308-49a4-b4d0-dd8509d7fb65 (3aa2cbe708fa) started on 172.17.0.2:47317
I1213 00:19:27.802996 32661 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/Xu0ZZn/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/Xu0ZZn/master" --zk_session_timeout="10secs"
I1213 00:19:27.803436 32661 master.cpp:432] Master only allowing authenticated frameworks to register
I1213 00:19:27.803448 32661 master.cpp:446] Master only allowing authenticated agents to register
I1213 00:19:27.803455 32661 master.cpp:459] Master only allowing authenticated HTTP frameworks to register
I1213 00:19:27.803463 32661 credentials.hpp:37] Loading credentials for authentication from '/tmp/Xu0ZZn/credentials'
I1213 00:19:27.803748 32661 master.cpp:504] Using default 'crammd5' authenticator
I1213 00:19:27.803874 32661 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I1213 00:19:27.804070 32661 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I1213 00:19:27.804277 32661 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I1213 00:19:27.804399 32661 master.cpp:584] Authorization enabled
I1213 00:19:27.804685 32651 hierarchical.cpp:149] Initialized hierarchical allocator process
I1213 00:19:27.804762 32650 whitelist_watcher.cpp:77] No whitelist given
I1213 00:19:27.806727 32657 master.cpp:2045] Elected as the leading master!
I1213 00:19:27.806816 32657 master.cpp:1568] Recovering from registrar
I1213 00:19:27.806993 32652 registrar.cpp:329] Recovering registrar
I1213 00:19:27.807771 32652 registrar.cpp:362] Successfully fetched the registry (0B) in 720128ns
I1213 00:19:27.807874 32652 registrar.cpp:461] Applied 1 operations in 18533ns; attempting to update the registry
I1213 00:19:27.808758 32654 registrar.cpp:506] Successfully updated the registry in 808192ns
I1213 00:19:27.808928 32654 registrar.cpp:392] Successfully recovered registrar
I1213 00:19:27.809551 32653 master.cpp:1684] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I1213 00:19:27.809587 32657 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover
Build timed out (after 300 minutes). Marking the build as failed.
Build was aborted
++ docker rmi mesos-1481586038-16496
Error response from daemon: conflict: unable to remove repository reference "mesos-1481586038-16496" (must force) - container 3aa2cbe708fa is using its referenced image 0d6a07299058

Jenkins build is back to normal : Mesos » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2) #3034

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3034/changes>