See <https://builds.apache.org/job/mesos-reviewbot/16454/changes>
Changes: [benjamin.hindman] Added a synchronous version of loop for io::read/write/redirect. [benjamin.hindman] Used process::loop in infinitely recursive functions. ------------------------------------------ [...truncated 151685 lines...] I1214 18:19:19.440029 29728 master.cpp:2977] Disconnecting agent 59c19a54-dfe1-4ba5-ac28-1eba8e06a418-S0 at slave(573)@172.17.0.2:33319 (873da6a9820c) I1214 18:19:19.440111 29728 master.cpp:2996] Deactivating agent 59c19a54-dfe1-4ba5-ac28-1eba8e06a418-S0 at slave(573)@172.17.0.2:33319 (873da6a9820c) I1214 18:19:19.440263 29725 hierarchical.cpp:589] Agent 59c19a54-dfe1-4ba5-ac28-1eba8e06a418-S0 deactivated I1214 18:19:19.444171 29737 master.cpp:1097] Master terminating I1214 18:19:19.444818 29738 hierarchical.cpp:522] Removed agent 59c19a54-dfe1-4ba5-ac28-1eba8e06a418-S0 [ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionAttachFailure/1 (126 ms) [ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSession/0 I1214 18:19:19.454186 29705 cluster.cpp:160] Creating default 'local' authorizer I1214 18:19:19.457927 29728 master.cpp:380] Master 92eca39c-3dc7-4dac-8e43-966ae3dc9460 (873da6a9820c) started on 172.17.0.2:33319 I1214 18:19:19.457950 29728 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/04x0OA/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/04x0OA/master" --zk_session_timeout="10secs" I1214 18:19:19.458374 29728 master.cpp:432] Master only allowing authenticated frameworks to register I1214 18:19:19.458390 29728 master.cpp:446] Master only allowing authenticated agents to register I1214 18:19:19.458405 29728 master.cpp:459] Master only allowing authenticated HTTP frameworks to register I1214 18:19:19.458418 29728 credentials.hpp:37] Loading credentials for authentication from '/tmp/04x0OA/credentials' I1214 18:19:19.458727 29728 master.cpp:504] Using default 'crammd5' authenticator I1214 18:19:19.458883 29728 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I1214 18:19:19.459051 29728 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I1214 18:19:19.459199 29728 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I1214 18:19:19.459328 29728 master.cpp:584] Authorization enabled I1214 18:19:19.459609 29736 hierarchical.cpp:149] Initialized hierarchical allocator process I1214 18:19:19.459619 29731 whitelist_watcher.cpp:77] No whitelist given I1214 18:19:19.461966 29725 master.cpp:2045] Elected as the leading master! I1214 18:19:19.462000 29725 master.cpp:1568] Recovering from registrar I1214 18:19:19.462185 29739 registrar.cpp:329] Recovering registrar I1214 18:19:19.463173 29739 registrar.cpp:362] Successfully fetched the registry (0B) in 941056ns I1214 18:19:19.463301 29739 registrar.cpp:461] Applied 1 operations in 23286ns; attempting to update the registry I1214 18:19:19.464186 29734 registrar.cpp:506] Successfully updated the registry in 805888ns I1214 18:19:19.464301 29734 registrar.cpp:392] Successfully recovered registrar I1214 18:19:19.464764 29731 master.cpp:1684] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I1214 18:19:19.464797 29736 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover I1214 18:19:19.468168 29705 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni W1214 18:19:19.468886 29705 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos W1214 18:19:19.469058 29705 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges I1214 18:19:19.472707 29705 cluster.cpp:446] Creating default 'local' authorizer I1214 18:19:19.474402 29727 slave.cpp:209] Mesos agent started on (574)@172.17.0.2:33319 I1214 18:19:19.474434 29727 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_0_iDqBEp/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_0_iDqBEp/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_0_iDqBEp/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_LaunchNestedContainerSession_0_iDqBEp" --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_0_RogNev" I1214 18:19:19.475119 29727 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_iDqBEp/credential' I1214 18:19:19.475319 29727 slave.cpp:352] Agent using credential for: test-principal I1214 18:19:19.475347 29727 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_iDqBEp/http_credentials' I1214 18:19:19.475610 29727 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I1214 18:19:19.475744 29705 sched.cpp:232] Version: 1.2.0 I1214 18:19:19.475833 29727 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I1214 18:19:19.476438 29728 sched.cpp:336] New master detected at [email protected]:33319 I1214 18:19:19.476559 29728 sched.cpp:402] Authenticating with master [email protected]:33319 I1214 18:19:19.476580 29728 sched.cpp:409] Using default CRAM-MD5 authenticatee I1214 18:19:19.476874 29726 authenticatee.cpp:121] Creating new client SASL connection I1214 18:19:19.477181 29729 master.cpp:6748] Authenticating [email protected]:33319 I1214 18:19:19.477306 29738 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1117)@172.17.0.2:33319 I1214 18:19:19.477264 29727 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1214 18:19:19.477347 29727 slave.cpp:547] Agent attributes: [ ] I1214 18:19:19.477367 29727 slave.cpp:552] Agent hostname: 873da6a9820c I1214 18:19:19.477571 29730 authenticator.cpp:98] Creating new server SASL connection I1214 18:19:19.477787 29725 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I1214 18:19:19.477819 29725 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I1214 18:19:19.477938 29737 authenticator.cpp:204] Received SASL authentication start I1214 18:19:19.478008 29737 authenticator.cpp:326] Authentication requires more steps I1214 18:19:19.478111 29737 authenticatee.cpp:259] Received SASL authentication step I1214 18:19:19.478246 29732 authenticator.cpp:232] Received SASL authentication step I1214 18:19:19.478291 29732 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '873da6a9820c' server FQDN: '873da6a9820c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1214 18:19:19.478305 29732 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I1214 18:19:19.478349 29732 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1214 18:19:19.478379 29732 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '873da6a9820c' server FQDN: '873da6a9820c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1214 18:19:19.478395 29732 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1214 18:19:19.478410 29732 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1214 18:19:19.478435 29732 authenticator.cpp:318] Authentication success I1214 18:19:19.478533 29733 authenticatee.cpp:299] Authentication success I1214 18:19:19.478641 29731 master.cpp:6778] Successfully authenticated principal 'test-principal' at [email protected]:33319 I1214 18:19:19.478672 29737 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1117)@172.17.0.2:33319 I1214 18:19:19.478915 29736 sched.cpp:508] Successfully authenticated with master [email protected]:33319 I1214 18:19:19.478946 29736 sched.cpp:826] Sending SUBSCRIBE call to [email protected]:33319 I1214 18:19:19.479063 29726 state.cpp:57] Recovering state from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev/meta' I1214 18:19:19.479096 29736 sched.cpp:859] Will retry registration in 328.177171ms if necessary I1214 18:19:19.479229 29737 master.cpp:2633] Received SUBSCRIBE call for framework 'default' at [email protected]:33319 I1214 18:19:19.479290 29737 master.cpp:2081] Authorizing framework principal 'test-principal' to receive offers for role '*' I1214 18:19:19.479418 29731 status_update_manager.cpp:203] Recovering status update manager I1214 18:19:19.479686 29728 containerizer.cpp:594] Recovering containerizer I1214 18:19:19.479737 29725 master.cpp:2709] Subscribing framework default with checkpointing disabled and capabilities [ ] I1214 18:19:19.480343 29726 hierarchical.cpp:276] Added framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.480367 29739 sched.cpp:749] Framework registered with 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.480424 29726 hierarchical.cpp:1689] No allocations performed I1214 18:19:19.480446 29739 sched.cpp:763] Scheduler::registered took 22397ns I1214 18:19:19.480461 29726 hierarchical.cpp:1784] No inverse offers to send out! I1214 18:19:19.480535 29726 hierarchical.cpp:1291] Performed allocation for 0 agents in 161082ns I1214 18:19:19.481303 29732 provisioner.cpp:253] Provisioner recovery complete I1214 18:19:19.481679 29738 slave.cpp:5420] Finished recovery I1214 18:19:19.482152 29738 slave.cpp:5594] Querying resource estimator for oversubscribable resources I1214 18:19:19.482414 29738 slave.cpp:924] New master detected at [email protected]:33319 I1214 18:19:19.482425 29735 status_update_manager.cpp:177] Pausing sending status updates I1214 18:19:19.482441 29738 slave.cpp:983] Authenticating with master [email protected]:33319 I1214 18:19:19.482503 29738 slave.cpp:994] Using default CRAM-MD5 authenticatee I1214 18:19:19.482640 29738 slave.cpp:956] Detecting new master I1214 18:19:19.482699 29728 authenticatee.cpp:121] Creating new client SASL connection I1214 18:19:19.482787 29738 slave.cpp:5608] Received oversubscribable resources {} from the resource estimator I1214 18:19:19.482956 29730 master.cpp:6748] Authenticating slave(574)@172.17.0.2:33319 I1214 18:19:19.483054 29724 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1118)@172.17.0.2:33319 I1214 18:19:19.483247 29726 authenticator.cpp:98] Creating new server SASL connection I1214 18:19:19.483415 29725 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I1214 18:19:19.483446 29725 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I1214 18:19:19.483546 29734 authenticator.cpp:204] Received SASL authentication start I1214 18:19:19.483623 29734 authenticator.cpp:326] Authentication requires more steps I1214 18:19:19.483719 29734 authenticatee.cpp:259] Received SASL authentication step I1214 18:19:19.483860 29725 authenticator.cpp:232] Received SASL authentication step I1214 18:19:19.483891 29725 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '873da6a9820c' server FQDN: '873da6a9820c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1214 18:19:19.483911 29725 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I1214 18:19:19.483952 29725 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1214 18:19:19.483981 29725 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '873da6a9820c' server FQDN: '873da6a9820c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1214 18:19:19.483997 29725 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1214 18:19:19.484012 29725 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1214 18:19:19.484033 29725 authenticator.cpp:318] Authentication success I1214 18:19:19.484117 29732 authenticatee.cpp:299] Authentication success I1214 18:19:19.484160 29734 master.cpp:6778] Successfully authenticated principal 'test-principal' at slave(574)@172.17.0.2:33319 I1214 18:19:19.484196 29738 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1118)@172.17.0.2:33319 I1214 18:19:19.484459 29736 slave.cpp:1078] Successfully authenticated with master [email protected]:33319 I1214 18:19:19.484669 29736 slave.cpp:1492] Will retry registration in 14.926313ms if necessary I1214 18:19:19.484841 29725 master.cpp:5161] Registering agent at slave(574)@172.17.0.2:33319 (873da6a9820c) with id 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 I1214 18:19:19.485272 29734 registrar.cpp:461] Applied 1 operations in 60919ns; attempting to update the registry I1214 18:19:19.486765 29734 registrar.cpp:506] Successfully updated the registry in 1.4208ms I1214 18:19:19.487531 29734 slave.cpp:4272] Received ping from slave-observer(526)@172.17.0.2:33319 I1214 18:19:19.487676 29734 slave.cpp:1124] Registered with master [email protected]:33319; given agent ID 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 I1214 18:19:19.487702 29734 fetcher.cpp:90] Clearing fetcher cache I1214 18:19:19.487623 29739 master.cpp:5232] Registered agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 at slave(574)@172.17.0.2:33319 (873da6a9820c) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1214 18:19:19.487886 29724 status_update_manager.cpp:184] Resuming sending status updates I1214 18:19:19.488076 29734 slave.cpp:1147] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev/meta/slaves/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0/slave.info' I1214 18:19:19.488101 29728 hierarchical.cpp:490] Added agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 (873da6a9820c) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I1214 18:19:19.488373 29734 slave.cpp:1184] Forwarding total oversubscribed resources {} I1214 18:19:19.488524 29736 master.cpp:5633] Received update of agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 at slave(574)@172.17.0.2:33319 (873da6a9820c) with total oversubscribed resources {} I1214 18:19:19.489537 29728 hierarchical.cpp:1784] No inverse offers to send out! I1214 18:19:19.489619 29728 hierarchical.cpp:1314] Performed allocation for agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 in 1.463552ms I1214 18:19:19.489855 29728 hierarchical.cpp:560] Agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 (873da6a9820c) 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 18:19:19.489956 29734 master.cpp:6577] Sending 1 offers to framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 (default) at [email protected]:33319 I1214 18:19:19.490012 29728 hierarchical.cpp:1689] No allocations performed I1214 18:19:19.490061 29728 hierarchical.cpp:1784] No inverse offers to send out! I1214 18:19:19.490128 29728 hierarchical.cpp:1314] Performed allocation for agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 in 209939ns I1214 18:19:19.490414 29736 sched.cpp:923] Scheduler::resourceOffers took 121239ns I1214 18:19:19.492182 29736 master.cpp:3588] Processing ACCEPT call for offers: [ 92eca39c-3dc7-4dac-8e43-966ae3dc9460-O0 ] on agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 at slave(574)@172.17.0.2:33319 (873da6a9820c) for framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 (default) at [email protected]:33319 I1214 18:19:19.492276 29736 master.cpp:3175] Authorizing framework principal 'test-principal' to launch task a473d01e-9b42-4c1f-b365-6e2991edf8c9 I1214 18:19:19.494122 29736 master.cpp:8501] Adding task a473d01e-9b42-4c1f-b365-6e2991edf8c9 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 (873da6a9820c) I1214 18:19:19.494365 29736 master.cpp:4240] Launching task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 (default) at [email protected]:33319 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 at slave(574)@172.17.0.2:33319 (873da6a9820c) I1214 18:19:19.495051 29725 slave.cpp:1556] Got assigned task 'a473d01e-9b42-4c1f-b365-6e2991edf8c9' for framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.495708 29725 slave.cpp:1718] Launching task 'a473d01e-9b42-4c1f-b365-6e2991edf8c9' for framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.496462 29725 paths.cpp:530] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev/slaves/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0/frameworks/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000/executors/a473d01e-9b42-4c1f-b365-6e2991edf8c9/runs/d5d88634-f9f3-43b2-8802-14fa3a732425' to user 'mesos' I1214 18:19:19.505141 29725 slave.cpp:6347] Launching executor 'a473d01e-9b42-4c1f-b365-6e2991edf8c9' of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev/slaves/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0/frameworks/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000/executors/a473d01e-9b42-4c1f-b365-6e2991edf8c9/runs/d5d88634-f9f3-43b2-8802-14fa3a732425' I1214 18:19:19.505800 29726 containerizer.cpp:986] Starting container d5d88634-f9f3-43b2-8802-14fa3a732425 for executor 'a473d01e-9b42-4c1f-b365-6e2991edf8c9' of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.505908 29725 slave.cpp:2040] Queued task 'a473d01e-9b42-4c1f-b365-6e2991edf8c9' for executor 'a473d01e-9b42-4c1f-b365-6e2991edf8c9' of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.505990 29725 slave.cpp:877] Successfully attached file '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev/slaves/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0/frameworks/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000/executors/a473d01e-9b42-4c1f-b365-6e2991edf8c9/runs/d5d88634-f9f3-43b2-8802-14fa3a732425' I1214 18:19:19.510773 29734 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.2:33319"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev\/slaves\/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0\/frameworks\/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000\/executors\/a473d01e-9b42-4c1f-b365-6e2991edf8c9\/runs\/d5d88634-f9f3-43b2-8802-14fa3a732425"},{"name":"MESOS_EXECUTOR_ID","value":"a473d01e-9b42-4c1f-b365-6e2991edf8c9"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(574)@172.17.0.2:33319"},{"name":"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev\/slaves\/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0\/frameworks\/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000\/executors\/a473d01e-9b42-4c1f-b365-6e2991edf8c9\/runs\/d5d88634-f9f3-43b2-8802-14fa3a732425"}]},"err":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev\/slaves\/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0\/frameworks\/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000\/executors\/a473d01e-9b42-4c1f-b365-6e2991edf8c9\/runs\/d5d88634-f9f3-43b2-8802-14fa3a732425\/stderr","type":"PATH"},"out":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev\/slaves\/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0\/frameworks\/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000\/executors\/a473d01e-9b42-4c1f-b365-6e2991edf8c9\/runs\/d5d88634-f9f3-43b2-8802-14fa3a732425\/stdout","type":"PATH"},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev\/slaves\/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0\/frameworks\/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000\/executors\/a473d01e-9b42-4c1f-b365-6e2991edf8c9\/runs\/d5d88634-f9f3-43b2-8802-14fa3a732425"}" --pipe_read="22" --pipe_write="24" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_iDqBEp/containers/d5d88634-f9f3-43b2-8802-14fa3a732425" --unshare_namespace_mnt="false"' I1214 18:19:19.514947 29734 launcher.cpp:133] Forked child with pid '9842' for container 'd5d88634-f9f3-43b2-8802-14fa3a732425' I1214 18:19:19.518127 29732 fetcher.cpp:349] Starting to fetch URIs for container: d5d88634-f9f3-43b2-8802-14fa3a732425, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev/slaves/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0/frameworks/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000/executors/a473d01e-9b42-4c1f-b365-6e2991edf8c9/runs/d5d88634-f9f3-43b2-8802-14fa3a732425 I1214 18:19:19.710624 29727 slave.cpp:3314] Got registration for executor 'a473d01e-9b42-4c1f-b365-6e2991edf8c9' of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 from executor(1)@172.17.0.2:40354 I1214 18:19:19.714192 29731 slave.cpp:2256] Sending queued task 'a473d01e-9b42-4c1f-b365-6e2991edf8c9' to executor 'a473d01e-9b42-4c1f-b365-6e2991edf8c9' of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 at executor(1)@172.17.0.2:40354 I1214 18:19:19.730171 29738 slave.cpp:3749] Handling status update TASK_RUNNING (UUID: 7b254530-0921-4155-9e42-a3db5197b738) for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 from executor(1)@172.17.0.2:40354 I1214 18:19:19.732291 29736 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 7b254530-0921-4155-9e42-a3db5197b738) for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.732364 29736 status_update_manager.cpp:500] Creating StatusUpdate stream for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.732883 29736 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 7b254530-0921-4155-9e42-a3db5197b738) for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 to the agent I1214 18:19:19.733191 29731 slave.cpp:4190] Forwarding the update TASK_RUNNING (UUID: 7b254530-0921-4155-9e42-a3db5197b738) for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 to [email protected]:33319 I1214 18:19:19.733436 29731 slave.cpp:4084] Status update manager successfully handled status update TASK_RUNNING (UUID: 7b254530-0921-4155-9e42-a3db5197b738) for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.733490 29731 slave.cpp:4100] Sending acknowledgement for status update TASK_RUNNING (UUID: 7b254530-0921-4155-9e42-a3db5197b738) for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 to executor(1)@172.17.0.2:40354 I1214 18:19:19.733583 29734 master.cpp:5769] Status update TASK_RUNNING (UUID: 7b254530-0921-4155-9e42-a3db5197b738) for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 from agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 at slave(574)@172.17.0.2:33319 (873da6a9820c) I1214 18:19:19.733649 29734 master.cpp:5831] Forwarding status update TASK_RUNNING (UUID: 7b254530-0921-4155-9e42-a3db5197b738) for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.733871 29734 master.cpp:7867] Updating the state of task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I1214 18:19:19.734098 29731 sched.cpp:1031] Scheduler::statusUpdate took 140126ns I1214 18:19:19.734653 29732 master.cpp:4877] Processing ACKNOWLEDGE call 7b254530-0921-4155-9e42-a3db5197b738 for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 (default) at [email protected]:33319 on agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 I1214 18:19:19.735038 29730 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 7b254530-0921-4155-9e42-a3db5197b738) for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.735592 29732 slave.cpp:3031] Status update manager successfully handled status update acknowledgement (UUID: 7b254530-0921-4155-9e42-a3db5197b738) for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.737820 29733 process.cpp:3679] Handling HTTP event for process 'slave(574)' with path: '/slave(574)/api/v1' I1214 18:19:19.738844 29736 http.cpp:303] HTTP POST for /slave(574)/api/v1 from 172.17.0.2:42678 I1214 18:19:19.739369 29736 http.cpp:464] Processing call LAUNCH_NESTED_CONTAINER_SESSION I1214 18:19:19.739992 29724 containerizer.cpp:1802] Starting nested container d5d88634-f9f3-43b2-8802-14fa3a732425.68773117-6c10-4483-adb2-0004fcb1e625 I1214 18:19:19.740272 29724 containerizer.cpp:1826] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev/slaves/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0/frameworks/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000/executors/a473d01e-9b42-4c1f-b365-6e2991edf8c9/runs/d5d88634-f9f3-43b2-8802-14fa3a732425/containers/68773117-6c10-4483-adb2-0004fcb1e625' to user 'mesos' I1214 18:19:19.752816 29728 switchboard.cpp:567] Launching 'mesos-io-switchboard' with flags '--heartbeat_interval="30secs" --help="false" --socket_address="/tmp/mesos-io-switchboard-d264761c-0ade-45ca-ae84-380b1451108b" --stderr_from_fd="62" --stderr_to_fd="2" --stdin_to_fd="29" --stdout_from_fd="42" --stdout_to_fd="1" --tty="false" --wait_for_connection="true"' for container d5d88634-f9f3-43b2-8802-14fa3a732425.68773117-6c10-4483-adb2-0004fcb1e625 I1214 18:19:19.757395 29728 switchboard.cpp:597] Created I/O switchboard server (pid: 9903) listening on socket file '/tmp/mesos-io-switchboard-d264761c-0ade-45ca-ae84-380b1451108b' for container d5d88634-f9f3-43b2-8802-14fa3a732425.68773117-6c10-4483-adb2-0004fcb1e625 I1214 18:19:19.759740 29726 containerizer.cpp:1535] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"printf output && printf error 1>&2"},"environment":{},"err":{"fd":63,"type":"FD"},"in":{"fd":28,"type":"FD"},"out":{"fd":43,"type":"FD"},"user":"mesos"}" --pipe_read="29" --pipe_write="42" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_iDqBEp/containers/d5d88634-f9f3-43b2-8802-14fa3a732425/containers/68773117-6c10-4483-adb2-0004fcb1e625" --unshare_namespace_mnt="false"' I1214 18:19:19.763988 29726 launcher.cpp:133] Forked child with pid '9904' for container 'd5d88634-f9f3-43b2-8802-14fa3a732425.68773117-6c10-4483-adb2-0004fcb1e625' I1214 18:19:19.767976 29726 fetcher.cpp:349] Starting to fetch URIs for container: d5d88634-f9f3-43b2-8802-14fa3a732425.68773117-6c10-4483-adb2-0004fcb1e625, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev/slaves/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0/frameworks/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000/executors/a473d01e-9b42-4c1f-b365-6e2991edf8c9/runs/d5d88634-f9f3-43b2-8802-14fa3a732425/containers/68773117-6c10-4483-adb2-0004fcb1e625 **** DEADLOCK DETECTED! **** You are waiting on process __limiter__(587)@172.17.0.2:33319 that it is currently executing. I1214 18:19:19.862511 29738 http.cpp:2708] Received EOF attach response for d5d88634-f9f3-43b2-8802-14fa3a732425.68773117-6c10-4483-adb2-0004fcb1e625 I1214 18:19:19.863039 29730 containerizer.cpp:2113] Destroying container d5d88634-f9f3-43b2-8802-14fa3a732425.68773117-6c10-4483-adb2-0004fcb1e625 in RUNNING state I1214 18:19:19.863719 29730 launcher.cpp:149] Asked to destroy container d5d88634-f9f3-43b2-8802-14fa3a732425.68773117-6c10-4483-adb2-0004fcb1e625 W1214 18:19:19.863060 29738 http.cpp:2723] Launch nested container session connection for container d5d88634-f9f3-43b2-8802-14fa3a732425.68773117-6c10-4483-adb2-0004fcb1e625 closed I1214 18:19:19.864117 29705 sched.cpp:2008] Asked to stop the driver I1214 18:19:19.864323 29739 sched.cpp:1193] Stopping framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.864941 29733 master.cpp:7287] Processing TEARDOWN call for framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 (default) at [email protected]:33319 I1214 18:19:19.865083 29733 master.cpp:7299] Removing framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 (default) at [email protected]:33319 I1214 18:19:19.865648 29731 hierarchical.cpp:391] Deactivated framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.866195 29733 master.cpp:7867] Updating the state of task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I1214 18:19:19.866341 29735 slave.cpp:2584] Asked to shut down framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 by [email protected]:33319 I1214 18:19:19.866493 29735 slave.cpp:2609] Shutting down framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.866593 29735 slave.cpp:4999] Shutting down executor 'a473d01e-9b42-4c1f-b365-6e2991edf8c9' of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 at executor(1)@172.17.0.2:40354 I1214 18:19:19.867432 29733 master.cpp:7963] Removing task a473d01e-9b42-4c1f-b365-6e2991edf8c9 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 on agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 at slave(574)@172.17.0.2:33319 (873da6a9820c) I1214 18:19:19.867700 29732 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 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 from framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.868140 29738 hierarchical.cpp:342] Removed framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.871314 29732 containerizer.cpp:2113] Destroying container d5d88634-f9f3-43b2-8802-14fa3a732425 in RUNNING state I1214 18:19:19.901546 29735 slave.cpp:3749] Handling status update TASK_KILLED (UUID: 4a8b3539-8609-4b6d-8003-dc2b0f611689) for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 from executor(1)@172.17.0.2:40354 W1214 18:19:19.901649 29735 slave.cpp:3818] Ignoring status update TASK_KILLED (UUID: 4a8b3539-8609-4b6d-8003-dc2b0f611689) for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 for terminating framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:19.958916 29739 switchboard.cpp:855] I/O switchboard server process for container d5d88634-f9f3-43b2-8802-14fa3a732425.68773117-6c10-4483-adb2-0004fcb1e625 has terminated (status=0) I1214 18:19:19.959591 29734 containerizer.cpp:2476] Container d5d88634-f9f3-43b2-8802-14fa3a732425.68773117-6c10-4483-adb2-0004fcb1e625 has exited I1214 18:19:19.961449 29739 provisioner.cpp:324] Ignoring destroy request for unknown container d5d88634-f9f3-43b2-8802-14fa3a732425.68773117-6c10-4483-adb2-0004fcb1e625 I1214 18:19:19.961673 29734 containerizer.cpp:2392] Checkpointing termination state to nested container's runtime directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_iDqBEp/containers/d5d88634-f9f3-43b2-8802-14fa3a732425/containers/68773117-6c10-4483-adb2-0004fcb1e625/termination' I1214 18:19:19.962436 29734 launcher.cpp:149] Asked to destroy container d5d88634-f9f3-43b2-8802-14fa3a732425 I1214 18:19:19.973532 29739 slave.cpp:4318] Got exited event for executor(1)@172.17.0.2:40354 I1214 18:19:20.061413 29732 containerizer.cpp:2476] Container d5d88634-f9f3-43b2-8802-14fa3a732425 has exited I1214 18:19:20.063446 29732 provisioner.cpp:324] Ignoring destroy request for unknown container d5d88634-f9f3-43b2-8802-14fa3a732425 I1214 18:19:20.204258 29732 slave.cpp:4681] Executor 'a473d01e-9b42-4c1f-b365-6e2991edf8c9' of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 terminated with signal Killed I1214 18:19:20.204375 29732 slave.cpp:4785] Cleaning up executor 'a473d01e-9b42-4c1f-b365-6e2991edf8c9' of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 at executor(1)@172.17.0.2:40354 I1214 18:19:20.204818 29732 slave.cpp:4873] Cleaning up framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:20.204955 29734 status_update_manager.cpp:285] Closing status update streams for framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:20.205008 29734 status_update_manager.cpp:531] Cleaning up status update stream for task a473d01e-9b42-4c1f-b365-6e2991edf8c9 of framework 92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000 I1214 18:19:20.205288 29735 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev/slaves/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0/frameworks/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000/executors/a473d01e-9b42-4c1f-b365-6e2991edf8c9/runs/d5d88634-f9f3-43b2-8802-14fa3a732425' for gc 6.99999763233778days in the future I1214 18:19:20.205447 29735 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev/slaves/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0/frameworks/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000/executors/a473d01e-9b42-4c1f-b365-6e2991edf8c9' for gc 6.99999763005926days in the future I1214 18:19:20.205557 29735 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_RogNev/slaves/92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0/frameworks/92eca39c-3dc7-4dac-8e43-966ae3dc9460-0000' for gc 6.9999976282637days in the future W1214 18:19:20.205972 29729 containerizer.cpp:2101] Attempted to destroy unknown container d5d88634-f9f3-43b2-8802-14fa3a732425.68773117-6c10-4483-adb2-0004fcb1e625 I1214 18:19:20.206229 29724 slave.cpp:796] Agent terminating I1214 18:19:20.206549 29731 master.cpp:1258] Agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 at slave(574)@172.17.0.2:33319 (873da6a9820c) disconnected I1214 18:19:20.206579 29731 master.cpp:2977] Disconnecting agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 at slave(574)@172.17.0.2:33319 (873da6a9820c) I1214 18:19:20.206631 29731 master.cpp:2996] Deactivating agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 at slave(574)@172.17.0.2:33319 (873da6a9820c) I1214 18:19:20.206815 29731 hierarchical.cpp:589] Agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 deactivated I1214 18:19:20.211172 29728 master.cpp:1097] Master terminating I1214 18:19:20.211989 29725 hierarchical.cpp:522] Removed agent 92eca39c-3dc7-4dac-8e43-966ae3dc9460-S0 [ OK ] ContentType/AgentAPITest.LaunchNestedContainerSession/0 (764 ms) [ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSession/1 I1214 18:19:20.219393 29705 cluster.cpp:160] Creating default 'local' authorizer I1214 18:19:20.221999 29725 master.cpp:380] Master 5b6bbd39-d6cb-484e-94e9-170b2ac1e618 (873da6a9820c) started on 172.17.0.2:33319 I1214 18:19:20.222167 29725 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/blM5UH/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/blM5UH/master" --zk_session_timeout="10secs" I1214 18:19:20.222551 29725 master.cpp:432] Master only allowing authenticated frameworks to register I1214 18:19:20.222566 29725 master.cpp:446] Master only allowing authenticated agents to register I1214 18:19:20.222580 29725 master.cpp:459] Master only allowing authenticated HTTP frameworks to register I1214 18:19:20.222594 29725 credentials.hpp:37] Loading credentials for authentication from '/tmp/blM5UH/credentials' I1214 18:19:20.222970 29725 master.cpp:504] Using default 'crammd5' authenticator I1214 18:19:20.223103 29725 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I1214 18:19:20.223232 29725 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I1214 18:19:20.223395 29725 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I1214 18:19:20.223502 29725 master.cpp:584] Authorization enabled I1214 18:19:20.223816 29735 whitelist_watcher.cpp:77] No whitelist given I1214 18:19:20.223858 29737 hierarchical.cpp:149] Initialized hierarchical allocator process I1214 18:19:20.225967 29728 master.cpp:2045] Elected as the leading master! I1214 18:19:20.225998 29728 master.cpp:1568] Recovering from registrar I1214 18:19:20.226160 29736 registrar.cpp:329] Recovering registrar I1214 18:19:20.226985 29736 registrar.cpp:362] Successfully fetched the registry (0B) in 783872ns I1214 18:19:20.227088 29736 registrar.cpp:461] Applied 1 operations in 18333ns; attempting to update the registry I1214 18:19:20.227898 29733 registrar.cpp:506] Successfully updated the registry in 736us I1214 18:19:20.228025 29733 registrar.cpp:392] Successfully recovered registrar I1214 18:19:20.228469 29728 master.cpp:1684] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I1214 18:19:20.228502 29732 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover Build timed out (after 180 minutes). Marking the build as failed. Build was aborted ++ docker rmi mesos-1481736361-10867 Error response from daemon: conflict: unable to remove repository reference "mesos-1481736361-10867" (must force) - container 873da6a9820c is using its referenced image 65bb61236419
