See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,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)/3108/changes>
Changes: [josephwu] Windows: Fixed the locale guard in jsonify.hpp. [josephwu] Windows: Fixed typo in `stout/os/windows/write.hpp`. [josephwu] Windows: Normalize `#endif` comments of `// !__WINDOWS__`. [josephwu] Windows: Stout: Removed dependency on Shell API. ------------------------------------------ [...truncated 153776 lines...] I0113 03:19:45.511051 28473 provisioner.cpp:322] Ignoring destroy request for unknown container 49974bba-b9c1-4e44-ae87-1684a20702e9 I0113 03:19:45.513188 28471 slave.cpp:4690] Executor 'bdf74b15-49f9-4b2d-bcd2-a21f1bf6d05f' of framework 858bd2db-8cd5-4945-99a7-95e44e6c05e4-0000 terminated with signal Killed I0113 03:19:45.513273 28471 slave.cpp:4790] Cleaning up executor 'bdf74b15-49f9-4b2d-bcd2-a21f1bf6d05f' of framework 858bd2db-8cd5-4945-99a7-95e44e6c05e4-0000 at executor(1)@172.17.0.3:60661 I0113 03:19:45.513664 28464 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_jZOf6t/slaves/858bd2db-8cd5-4945-99a7-95e44e6c05e4-S0/frameworks/858bd2db-8cd5-4945-99a7-95e44e6c05e4-0000/executors/bdf74b15-49f9-4b2d-bcd2-a21f1bf6d05f/runs/49974bba-b9c1-4e44-ae87-1684a20702e9' for gc 6.99999405689778days in the future I0113 03:19:45.513919 28471 slave.cpp:4878] Cleaning up framework 858bd2db-8cd5-4945-99a7-95e44e6c05e4-0000 I0113 03:19:45.514031 28467 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_jZOf6t/slaves/858bd2db-8cd5-4945-99a7-95e44e6c05e4-S0/frameworks/858bd2db-8cd5-4945-99a7-95e44e6c05e4-0000/executors/bdf74b15-49f9-4b2d-bcd2-a21f1bf6d05f' for gc 6.99999405285926days in the future I0113 03:19:45.514078 28479 status_update_manager.cpp:285] Closing status update streams for framework 858bd2db-8cd5-4945-99a7-95e44e6c05e4-0000 I0113 03:19:45.514168 28479 status_update_manager.cpp:531] Cleaning up status update stream for task bdf74b15-49f9-4b2d-bcd2-a21f1bf6d05f of framework 858bd2db-8cd5-4945-99a7-95e44e6c05e4-0000 I0113 03:19:45.514392 28467 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_jZOf6t/slaves/858bd2db-8cd5-4945-99a7-95e44e6c05e4-S0/frameworks/858bd2db-8cd5-4945-99a7-95e44e6c05e4-0000' for gc 6.99999404985185days in the future I0113 03:19:45.515095 28470 slave.cpp:796] Agent terminating I0113 03:19:45.515328 28464 master.cpp:1261] Agent 858bd2db-8cd5-4945-99a7-95e44e6c05e4-S0 at slave(588)@172.17.0.3:58437 (02fc61e719e7) disconnected I0113 03:19:45.515368 28464 master.cpp:3051] Disconnecting agent 858bd2db-8cd5-4945-99a7-95e44e6c05e4-S0 at slave(588)@172.17.0.3:58437 (02fc61e719e7) I0113 03:19:45.515470 28464 master.cpp:3070] Deactivating agent 858bd2db-8cd5-4945-99a7-95e44e6c05e4-S0 at slave(588)@172.17.0.3:58437 (02fc61e719e7) I0113 03:19:45.515683 28479 hierarchical.cpp:590] Agent 858bd2db-8cd5-4945-99a7-95e44e6c05e4-S0 deactivated I0113 03:19:45.522238 28469 master.cpp:1100] Master terminating I0113 03:19:45.522994 28470 hierarchical.cpp:523] Removed agent 858bd2db-8cd5-4945-99a7-95e44e6c05e4-S0 [ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/0 (608 ms) [ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/1 I0113 03:19:45.532004 28446 cluster.cpp:160] Creating default 'local' authorizer I0113 03:19:45.534306 28475 master.cpp:383] Master b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76 (02fc61e719e7) started on 172.17.0.3:58437 I0113 03:19:45.534365 28475 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/Z6o7Bp/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/Z6o7Bp/master" --zk_session_timeout="10secs" I0113 03:19:45.534713 28475 master.cpp:435] Master only allowing authenticated frameworks to register I0113 03:19:45.534735 28475 master.cpp:449] Master only allowing authenticated agents to register I0113 03:19:45.534741 28475 master.cpp:462] Master only allowing authenticated HTTP frameworks to register I0113 03:19:45.534749 28475 credentials.hpp:37] Loading credentials for authentication from '/tmp/Z6o7Bp/credentials' I0113 03:19:45.535001 28475 master.cpp:507] Using default 'crammd5' authenticator I0113 03:19:45.535126 28475 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0113 03:19:45.535339 28475 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0113 03:19:45.535573 28475 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0113 03:19:45.535769 28475 master.cpp:587] Authorization enabled I0113 03:19:45.536005 28474 whitelist_watcher.cpp:77] No whitelist given I0113 03:19:45.536020 28464 hierarchical.cpp:149] Initialized hierarchical allocator process I0113 03:19:45.539289 28466 master.cpp:2119] Elected as the leading master! I0113 03:19:45.539320 28466 master.cpp:1641] Recovering from registrar I0113 03:19:45.539444 28478 registrar.cpp:329] Recovering registrar I0113 03:19:45.540117 28478 registrar.cpp:362] Successfully fetched the registry (0B) in 632064ns I0113 03:19:45.540241 28478 registrar.cpp:461] Applied 1 operations in 42156ns; attempting to update the registry I0113 03:19:45.541105 28478 registrar.cpp:506] Successfully updated the registry in 794112ns I0113 03:19:45.541224 28478 registrar.cpp:392] Successfully recovered registrar I0113 03:19:45.541719 28474 master.cpp:1757] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0113 03:19:45.541745 28479 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover I0113 03:19:45.544173 28446 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni W0113 03:19:45.544670 28446 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos W0113 03:19:45.544821 28446 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges I0113 03:19:45.548642 28446 cluster.cpp:446] Creating default 'local' authorizer I0113 03:19:45.550842 28470 slave.cpp:209] Mesos agent started on (589)@172.17.0.3:58437 I0113 03:19:45.550884 28470 slave.cpp:210] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_l6CqrP/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_l6CqrP/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_l6CqrP/http_credentials" --http_heartbeat_interval="30secs" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_l6CqrP" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7" I0113 03:19:45.551565 28470 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_l6CqrP/credential' I0113 03:19:45.551774 28470 slave.cpp:352] Agent using credential for: test-principal I0113 03:19:45.551801 28470 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_l6CqrP/http_credentials' I0113 03:19:45.552068 28470 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0113 03:19:45.552256 28470 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0113 03:19:45.552544 28446 sched.cpp:232] Version: 1.2.0 I0113 03:19:45.553102 28466 sched.cpp:336] New master detected at master@172.17.0.3:58437 I0113 03:19:45.553179 28466 sched.cpp:407] Authenticating with master master@172.17.0.3:58437 I0113 03:19:45.553197 28466 sched.cpp:414] Using default CRAM-MD5 authenticatee I0113 03:19:45.553413 28476 authenticatee.cpp:121] Creating new client SASL connection I0113 03:19:45.553649 28466 master.cpp:6835] Authenticating scheduler-f95214b2-e489-4104-9118-48e5a218815c@172.17.0.3:58437 I0113 03:19:45.553793 28474 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1135)@172.17.0.3:58437 I0113 03:19:45.553743 28470 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0113 03:19:45.553849 28470 slave.cpp:547] Agent attributes: [ ] I0113 03:19:45.553864 28470 slave.cpp:552] Agent hostname: 02fc61e719e7 I0113 03:19:45.554149 28467 authenticator.cpp:98] Creating new server SASL connection I0113 03:19:45.554399 28472 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0113 03:19:45.554445 28472 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0113 03:19:45.554584 28466 authenticator.cpp:204] Received SASL authentication start I0113 03:19:45.554661 28466 authenticator.cpp:326] Authentication requires more steps I0113 03:19:45.555282 28473 authenticatee.cpp:259] Received SASL authentication step I0113 03:19:45.555474 28466 authenticator.cpp:232] Received SASL authentication step I0113 03:19:45.555529 28466 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '02fc61e719e7' server FQDN: '02fc61e719e7' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0113 03:19:45.555550 28474 state.cpp:60] Recovering state from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7/meta' I0113 03:19:45.555559 28466 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0113 03:19:45.555640 28466 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0113 03:19:45.555691 28466 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '02fc61e719e7' server FQDN: '02fc61e719e7' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0113 03:19:45.555714 28466 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0113 03:19:45.555724 28466 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0113 03:19:45.555744 28466 authenticator.cpp:318] Authentication success I0113 03:19:45.555938 28472 authenticatee.cpp:299] Authentication success I0113 03:19:45.555981 28465 master.cpp:6865] Successfully authenticated principal 'test-principal' at scheduler-f95214b2-e489-4104-9118-48e5a218815c@172.17.0.3:58437 I0113 03:19:45.556099 28477 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1135)@172.17.0.3:58437 I0113 03:19:45.556382 28465 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:58437 I0113 03:19:45.556423 28465 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:58437 I0113 03:19:45.556452 28469 status_update_manager.cpp:203] Recovering status update manager I0113 03:19:45.556651 28465 sched.cpp:869] Will retry registration in 1.913111928secs if necessary I0113 03:19:45.556809 28467 master.cpp:2707] Received SUBSCRIBE call for framework 'default' at scheduler-f95214b2-e489-4104-9118-48e5a218815c@172.17.0.3:58437 I0113 03:19:45.556851 28467 master.cpp:2155] Authorizing framework principal 'test-principal' to receive offers for role '*' I0113 03:19:45.557209 28464 containerizer.cpp:599] Recovering containerizer I0113 03:19:45.557437 28474 master.cpp:2783] Subscribing framework default with checkpointing disabled and capabilities [ ] I0113 03:19:45.558243 28471 hierarchical.cpp:277] Added framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:45.558244 28477 sched.cpp:759] Framework registered with b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:45.558327 28477 sched.cpp:773] Scheduler::registered took 44926ns I0113 03:19:45.558338 28471 hierarchical.cpp:1690] No allocations performed I0113 03:19:45.558372 28471 hierarchical.cpp:1785] No inverse offers to send out! I0113 03:19:45.558441 28471 hierarchical.cpp:1292] Performed allocation for 0 agents in 172864ns I0113 03:19:45.559175 28473 provisioner.cpp:251] Provisioner recovery complete I0113 03:19:45.559629 28465 slave.cpp:5421] Finished recovery I0113 03:19:45.560205 28465 slave.cpp:5595] Querying resource estimator for oversubscribable resources I0113 03:19:45.560508 28469 slave.cpp:924] New master detected at master@172.17.0.3:58437 I0113 03:19:45.560564 28467 status_update_manager.cpp:177] Pausing sending status updates I0113 03:19:45.560665 28469 slave.cpp:959] Detecting new master I0113 03:19:45.560830 28469 slave.cpp:5609] Received oversubscribable resources {} from the resource estimator I0113 03:19:45.563114 28468 slave.cpp:986] Authenticating with master master@172.17.0.3:58437 I0113 03:19:45.563242 28468 slave.cpp:997] Using default CRAM-MD5 authenticatee I0113 03:19:45.563511 28473 authenticatee.cpp:121] Creating new client SASL connection I0113 03:19:45.563820 28467 master.cpp:6835] Authenticating slave(589)@172.17.0.3:58437 I0113 03:19:45.563952 28466 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1136)@172.17.0.3:58437 I0113 03:19:45.564329 28464 authenticator.cpp:98] Creating new server SASL connection I0113 03:19:45.564616 28475 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0113 03:19:45.564664 28475 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0113 03:19:45.564788 28476 authenticator.cpp:204] Received SASL authentication start I0113 03:19:45.564854 28476 authenticator.cpp:326] Authentication requires more steps I0113 03:19:45.564965 28472 authenticatee.cpp:259] Received SASL authentication step I0113 03:19:45.565081 28472 authenticator.cpp:232] Received SASL authentication step I0113 03:19:45.565119 28472 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '02fc61e719e7' server FQDN: '02fc61e719e7' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0113 03:19:45.565135 28472 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0113 03:19:45.565173 28472 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0113 03:19:45.565201 28472 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '02fc61e719e7' server FQDN: '02fc61e719e7' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0113 03:19:45.565215 28472 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0113 03:19:45.565227 28472 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0113 03:19:45.565246 28472 authenticator.cpp:318] Authentication success I0113 03:19:45.565405 28475 authenticatee.cpp:299] Authentication success I0113 03:19:45.565412 28464 master.cpp:6865] Successfully authenticated principal 'test-principal' at slave(589)@172.17.0.3:58437 I0113 03:19:45.565454 28469 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1136)@172.17.0.3:58437 I0113 03:19:45.565798 28473 slave.cpp:1081] Successfully authenticated with master master@172.17.0.3:58437 I0113 03:19:45.565999 28473 slave.cpp:1503] Will retry registration in 18.596504ms if necessary I0113 03:19:45.566259 28465 master.cpp:5234] Registering agent at slave(589)@172.17.0.3:58437 (02fc61e719e7) with id b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 I0113 03:19:45.566697 28467 registrar.cpp:461] Applied 1 operations in 85024ns; attempting to update the registry I0113 03:19:45.568433 28467 registrar.cpp:506] Successfully updated the registry in 1.648128ms I0113 03:19:45.569370 28472 slave.cpp:4285] Received ping from slave-observer(535)@172.17.0.3:58437 I0113 03:19:45.569486 28473 master.cpp:5305] Registered agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 at slave(589)@172.17.0.3:58437 (02fc61e719e7) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0113 03:19:45.569617 28472 slave.cpp:1127] Registered with master master@172.17.0.3:58437; given agent ID b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 I0113 03:19:45.569653 28472 fetcher.cpp:90] Clearing fetcher cache I0113 03:19:45.569849 28471 hierarchical.cpp:491] Added agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 (02fc61e719e7) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0113 03:19:45.569878 28474 status_update_manager.cpp:184] Resuming sending status updates I0113 03:19:45.570144 28472 slave.cpp:1155] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7/meta/slaves/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0/slave.info' I0113 03:19:45.570586 28472 slave.cpp:1193] Forwarding total oversubscribed resources {} I0113 03:19:45.570787 28472 master.cpp:5712] Received update of agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 at slave(589)@172.17.0.3:58437 (02fc61e719e7) with total oversubscribed resources {} I0113 03:19:45.571228 28471 hierarchical.cpp:1785] No inverse offers to send out! I0113 03:19:45.571418 28471 hierarchical.cpp:1315] Performed allocation for agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 in 1.52373ms I0113 03:19:45.571569 28471 hierarchical.cpp:561] Agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 (02fc61e719e7) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) I0113 03:19:45.571759 28471 hierarchical.cpp:1690] No allocations performed I0113 03:19:45.571810 28471 hierarchical.cpp:1785] No inverse offers to send out! I0113 03:19:45.571840 28469 master.cpp:6664] Sending 1 offers to framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 (default) at scheduler-f95214b2-e489-4104-9118-48e5a218815c@172.17.0.3:58437 I0113 03:19:45.571873 28471 hierarchical.cpp:1315] Performed allocation for agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 in 254313ns I0113 03:19:45.572424 28467 sched.cpp:933] Scheduler::resourceOffers took 142378ns I0113 03:19:45.574537 28466 master.cpp:3662] Processing ACCEPT call for offers: [ b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-O0 ] on agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 at slave(589)@172.17.0.3:58437 (02fc61e719e7) for framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 (default) at scheduler-f95214b2-e489-4104-9118-48e5a218815c@172.17.0.3:58437 I0113 03:19:45.574671 28466 master.cpp:3249] Authorizing framework principal 'test-principal' to launch task 004f0939-362a-4493-bd46-971b9bde3d6e I0113 03:19:45.576984 28466 master.cpp:8581] Adding task 004f0939-362a-4493-bd46-971b9bde3d6e with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 at slave(589)@172.17.0.3:58437 (02fc61e719e7) I0113 03:19:45.577373 28466 master.cpp:4313] Launching task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 (default) at scheduler-f95214b2-e489-4104-9118-48e5a218815c@172.17.0.3:58437 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 at slave(589)@172.17.0.3:58437 (02fc61e719e7) I0113 03:19:45.578186 28472 slave.cpp:1571] Got assigned task '004f0939-362a-4493-bd46-971b9bde3d6e' for framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:45.579022 28472 slave.cpp:1731] Launching task '004f0939-362a-4493-bd46-971b9bde3d6e' for framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:45.579838 28472 paths.cpp:530] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7/slaves/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0/frameworks/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000/executors/004f0939-362a-4493-bd46-971b9bde3d6e/runs/a89b5d67-e494-4e13-b04e-e295620cdceb' to user 'mesos' I0113 03:19:45.588372 28472 slave.cpp:6348] Launching executor '004f0939-362a-4493-bd46-971b9bde3d6e' of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7/slaves/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0/frameworks/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000/executors/004f0939-362a-4493-bd46-971b9bde3d6e/runs/a89b5d67-e494-4e13-b04e-e295620cdceb' I0113 03:19:45.589298 28476 containerizer.cpp:991] Starting container a89b5d67-e494-4e13-b04e-e295620cdceb for executor '004f0939-362a-4493-bd46-971b9bde3d6e' of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:45.589447 28472 slave.cpp:2053] Queued task '004f0939-362a-4493-bd46-971b9bde3d6e' for executor '004f0939-362a-4493-bd46-971b9bde3d6e' of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:45.589565 28472 slave.cpp:877] Successfully attached file '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7/slaves/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0/frameworks/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000/executors/004f0939-362a-4493-bd46-971b9bde3d6e/runs/a89b5d67-e494-4e13-b04e-e295620cdceb' I0113 03:19:45.595461 28472 containerizer.cpp:1540] 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.3:58437"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7\/slaves\/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0\/frameworks\/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000\/executors\/004f0939-362a-4493-bd46-971b9bde3d6e\/runs\/a89b5d67-e494-4e13-b04e-e295620cdceb"},{"name":"MESOS_EXECUTOR_ID","value":"004f0939-362a-4493-bd46-971b9bde3d6e"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(589)@172.17.0.3:58437"},{"name":"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7\/slaves\/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0\/frameworks\/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000\/executors\/004f0939-362a-4493-bd46-971b9bde3d6e\/runs\/a89b5d67-e494-4e13-b04e-e295620cdceb"}]},"err":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7\/slaves\/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0\/frameworks\/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000\/executors\/004f0939-362a-4493-bd46-971b9bde3d6e\/runs\/a89b5d67-e494-4e13-b04e-e295620cdceb\/stderr","type":"PATH"},"out":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7\/slaves\/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0\/frameworks\/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000\/executors\/004f0939-362a-4493-bd46-971b9bde3d6e\/runs\/a89b5d67-e494-4e13-b04e-e295620cdceb\/stdout","type":"PATH"},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7\/slaves\/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0\/frameworks\/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000\/executors\/004f0939-362a-4493-bd46-971b9bde3d6e\/runs\/a89b5d67-e494-4e13-b04e-e295620cdceb"}" --pipe_read="22" --pipe_write="24" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_l6CqrP/containers/a89b5d67-e494-4e13-b04e-e295620cdceb" --unshare_namespace_mnt="false"' I0113 03:19:45.599905 28472 launcher.cpp:133] Forked child with pid '9294' for container 'a89b5d67-e494-4e13-b04e-e295620cdceb' I0113 03:19:45.603154 28464 fetcher.cpp:349] Starting to fetch URIs for container: a89b5d67-e494-4e13-b04e-e295620cdceb, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7/slaves/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0/frameworks/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000/executors/004f0939-362a-4493-bd46-971b9bde3d6e/runs/a89b5d67-e494-4e13-b04e-e295620cdceb I0113 03:19:45.809825 28472 slave.cpp:3322] Got registration for executor '004f0939-362a-4493-bd46-971b9bde3d6e' of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 from executor(1)@172.17.0.3:40705 I0113 03:19:45.812794 28478 slave.cpp:2267] Sending queued task '004f0939-362a-4493-bd46-971b9bde3d6e' to executor '004f0939-362a-4493-bd46-971b9bde3d6e' of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 at executor(1)@172.17.0.3:40705 I0113 03:19:45.829761 28477 slave.cpp:3754] Handling status update TASK_RUNNING (UUID: fa660646-ff39-4dce-8d06-91d0be84a72b) for task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 from executor(1)@172.17.0.3:40705 I0113 03:19:45.831300 28467 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: fa660646-ff39-4dce-8d06-91d0be84a72b) for task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:45.831351 28467 status_update_manager.cpp:500] Creating StatusUpdate stream for task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:45.831812 28467 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: fa660646-ff39-4dce-8d06-91d0be84a72b) for task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 to the agent I0113 03:19:45.832104 28475 slave.cpp:4195] Forwarding the update TASK_RUNNING (UUID: fa660646-ff39-4dce-8d06-91d0be84a72b) for task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 to master@172.17.0.3:58437 I0113 03:19:45.832346 28475 slave.cpp:4089] Status update manager successfully handled status update TASK_RUNNING (UUID: fa660646-ff39-4dce-8d06-91d0be84a72b) for task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:45.832391 28475 slave.cpp:4105] Sending acknowledgement for status update TASK_RUNNING (UUID: fa660646-ff39-4dce-8d06-91d0be84a72b) for task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 to executor(1)@172.17.0.3:40705 I0113 03:19:45.832535 28468 master.cpp:5848] Status update TASK_RUNNING (UUID: fa660646-ff39-4dce-8d06-91d0be84a72b) for task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 from agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 at slave(589)@172.17.0.3:58437 (02fc61e719e7) I0113 03:19:45.832607 28468 master.cpp:5910] Forwarding status update TASK_RUNNING (UUID: fa660646-ff39-4dce-8d06-91d0be84a72b) for task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:45.832846 28468 master.cpp:7953] Updating the state of task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0113 03:19:45.833199 28477 sched.cpp:1041] Scheduler::statusUpdate took 185830ns I0113 03:19:45.833586 28467 master.cpp:4950] Processing ACKNOWLEDGE call fa660646-ff39-4dce-8d06-91d0be84a72b for task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 (default) at scheduler-f95214b2-e489-4104-9118-48e5a218815c@172.17.0.3:58437 on agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 I0113 03:19:45.833988 28479 status_update_manager.cpp:395] Received status update acknowledgement (UUID: fa660646-ff39-4dce-8d06-91d0be84a72b) for task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:45.834237 28468 slave.cpp:3042] Status update manager successfully handled status update acknowledgement (UUID: fa660646-ff39-4dce-8d06-91d0be84a72b) for task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:45.836844 28472 process.cpp:3676] Handling HTTP event for process 'slave(589)' with path: '/slave(589)/api/v1' I0113 03:19:45.838171 28466 http.cpp:304] HTTP POST for /slave(589)/api/v1 from 172.17.0.3:51948 I0113 03:19:45.838845 28466 http.cpp:465] Processing call LAUNCH_NESTED_CONTAINER_SESSION I0113 03:19:45.839362 28472 containerizer.cpp:1807] Starting nested container a89b5d67-e494-4e13-b04e-e295620cdceb.86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4 I0113 03:19:45.839651 28472 containerizer.cpp:1831] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7/slaves/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0/frameworks/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000/executors/004f0939-362a-4493-bd46-971b9bde3d6e/runs/a89b5d67-e494-4e13-b04e-e295620cdceb/containers/86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4' to user 'mesos' I0113 03:19:45.850565 28469 switchboard.cpp:570] Launching 'mesos-io-switchboard' with flags '--heartbeat_interval="30secs" --help="false" --socket_address="/tmp/mesos-io-switchboard-a08e7920-8c53-4637-bfe4-a7531cbbe83f" --stderr_from_fd="60" --stderr_to_fd="2" --stdin_to_fd="29" --stdout_from_fd="40" --stdout_to_fd="1" --tty="false" --wait_for_connection="true"' for container a89b5d67-e494-4e13-b04e-e295620cdceb.86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4 I0113 03:19:45.853981 28469 switchboard.cpp:600] Created I/O switchboard server (pid: 9355) listening on socket file '/tmp/mesos-io-switchboard-a08e7920-8c53-4637-bfe4-a7531cbbe83f' for container a89b5d67-e494-4e13-b04e-e295620cdceb.86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4 I0113 03:19:45.855999 28472 containerizer.cpp:1540] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"cat"},"environment":{},"err":{"fd":61,"type":"FD"},"in":{"fd":28,"type":"FD"},"out":{"fd":41,"type":"FD"},"user":"mesos"}" --pipe_read="29" --pipe_write="40" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_l6CqrP/containers/a89b5d67-e494-4e13-b04e-e295620cdceb/containers/86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4" --unshare_namespace_mnt="false"' I0113 03:19:45.859923 28472 launcher.cpp:133] Forked child with pid '9356' for container 'a89b5d67-e494-4e13-b04e-e295620cdceb.86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4' I0113 03:19:45.863466 28465 fetcher.cpp:349] Starting to fetch URIs for container: a89b5d67-e494-4e13-b04e-e295620cdceb.86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7/slaves/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0/frameworks/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000/executors/004f0939-362a-4493-bd46-971b9bde3d6e/runs/a89b5d67-e494-4e13-b04e-e295620cdceb/containers/86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4 **** DEADLOCK DETECTED! **** You are waiting on process __limiter__(613)@172.17.0.3:58437 that it is currently executing. W0113 03:19:45.958499 28473 http.cpp:2724] Launch nested container session connection for container a89b5d67-e494-4e13-b04e-e295620cdceb.86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4 closed I0113 03:19:45.958674 28471 containerizer.cpp:2118] Destroying container a89b5d67-e494-4e13-b04e-e295620cdceb.86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4 in RUNNING state I0113 03:19:45.958948 28471 launcher.cpp:149] Asked to destroy container a89b5d67-e494-4e13-b04e-e295620cdceb.86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4 I0113 03:19:45.974934 28477 http.cpp:2709] Received EOF attach response for a89b5d67-e494-4e13-b04e-e295620cdceb.86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4 I0113 03:19:46.012384 28478 switchboard.cpp:861] I/O switchboard server process for container a89b5d67-e494-4e13-b04e-e295620cdceb.86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4 has terminated (status=0) I0113 03:19:46.013077 28467 containerizer.cpp:2481] Container a89b5d67-e494-4e13-b04e-e295620cdceb.86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4 has exited I0113 03:19:46.014978 28476 provisioner.cpp:322] Ignoring destroy request for unknown container a89b5d67-e494-4e13-b04e-e295620cdceb.86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4 I0113 03:19:46.015147 28468 containerizer.cpp:2397] Checkpointing termination state to nested container's runtime directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_l6CqrP/containers/a89b5d67-e494-4e13-b04e-e295620cdceb/containers/86ffb5b0-c8ee-4dad-87b1-ef33b8724ed4/termination' I0113 03:19:46.015954 28446 sched.cpp:2021] Asked to stop the driver I0113 03:19:46.016067 28467 sched.cpp:1203] Stopping framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:46.016458 28471 master.cpp:7375] Processing TEARDOWN call for framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 (default) at scheduler-f95214b2-e489-4104-9118-48e5a218815c@172.17.0.3:58437 I0113 03:19:46.016497 28471 master.cpp:7387] Removing framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 (default) at scheduler-f95214b2-e489-4104-9118-48e5a218815c@172.17.0.3:58437 I0113 03:19:46.016736 28475 slave.cpp:2595] Asked to shut down framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 by master@172.17.0.3:58437 I0113 03:19:46.016811 28475 slave.cpp:2620] Shutting down framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:46.016896 28464 hierarchical.cpp:392] Deactivated framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:46.016938 28475 slave.cpp:5004] Shutting down executor '004f0939-362a-4493-bd46-971b9bde3d6e' of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 at executor(1)@172.17.0.3:40705 I0113 03:19:46.016811 28471 master.cpp:7953] Updating the state of task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0113 03:19:46.018124 28479 containerizer.cpp:2118] Destroying container a89b5d67-e494-4e13-b04e-e295620cdceb in RUNNING state I0113 03:19:46.018131 28466 hierarchical.cpp:1024] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 from framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:46.018533 28479 launcher.cpp:149] Asked to destroy container a89b5d67-e494-4e13-b04e-e295620cdceb I0113 03:19:46.023528 28471 master.cpp:8049] Removing task 004f0939-362a-4493-bd46-971b9bde3d6e with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 on agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 at slave(589)@172.17.0.3:58437 (02fc61e719e7) I0113 03:19:46.024637 28465 hierarchical.cpp:343] Removed framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:46.031790 28479 slave.cpp:4327] Got exited event for executor(1)@172.17.0.3:40705 I0113 03:19:46.114595 28469 containerizer.cpp:2481] Container a89b5d67-e494-4e13-b04e-e295620cdceb has exited I0113 03:19:46.117243 28466 provisioner.cpp:322] Ignoring destroy request for unknown container a89b5d67-e494-4e13-b04e-e295620cdceb I0113 03:19:46.118587 28465 slave.cpp:4690] Executor '004f0939-362a-4493-bd46-971b9bde3d6e' of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 terminated with signal Killed I0113 03:19:46.118667 28465 slave.cpp:4790] Cleaning up executor '004f0939-362a-4493-bd46-971b9bde3d6e' of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 at executor(1)@172.17.0.3:40705 I0113 03:19:46.118963 28467 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7/slaves/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0/frameworks/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000/executors/004f0939-362a-4493-bd46-971b9bde3d6e/runs/a89b5d67-e494-4e13-b04e-e295620cdceb' for gc 6.99999862465185days in the future I0113 03:19:46.119150 28465 slave.cpp:4878] Cleaning up framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:46.119151 28467 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7/slaves/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0/frameworks/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000/executors/004f0939-362a-4493-bd46-971b9bde3d6e' for gc 6.99999862187556days in the future I0113 03:19:46.119313 28471 status_update_manager.cpp:285] Closing status update streams for framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:46.119385 28471 status_update_manager.cpp:531] Cleaning up status update stream for task 004f0939-362a-4493-bd46-971b9bde3d6e of framework b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000 I0113 03:19:46.119463 28465 slave.cpp:796] Agent terminating I0113 03:19:46.119460 28467 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_rAyLw7/slaves/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0/frameworks/b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-0000' for gc 6.99999861848296days in the future I0113 03:19:46.119844 28475 master.cpp:1261] Agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 at slave(589)@172.17.0.3:58437 (02fc61e719e7) disconnected I0113 03:19:46.119879 28475 master.cpp:3051] Disconnecting agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 at slave(589)@172.17.0.3:58437 (02fc61e719e7) I0113 03:19:46.119937 28475 master.cpp:3070] Deactivating agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 at slave(589)@172.17.0.3:58437 (02fc61e719e7) I0113 03:19:46.120049 28469 hierarchical.cpp:590] Agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 deactivated I0113 03:19:46.124663 28474 master.cpp:1100] Master terminating I0113 03:19:46.125418 28470 hierarchical.cpp:523] Removed agent b8b3cf6e-e366-4bf4-9f54-d1f4a7040f76-S0 [ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/1 (603 ms) [ RUN ] ContentType/AgentAPITest.AttachContainerOutputFailure/0 I0113 03:19:46.140499 28475 slave.cpp:209] Mesos agent started on (590)@172.17.0.3:58437 I0113 03:19:46.140561 28475 slave.cpp:210] Flags at startup: --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_AjWXTL/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_AjWXTL/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_AjWXTL/http_credentials" --http_heartbeat_interval="30secs" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_AjWXTL" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_UFfXq2" I0113 03:19:46.140990 28475 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_AjWXTL/credential' I0113 03:19:46.141178 28475 slave.cpp:352] Agent using credential for: test-principal I0113 03:19:46.141203 28475 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_AjWXTL/http_credentials' I0113 03:19:46.141453 28475 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0113 03:19:46.141626 28475 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0113 03:19:46.142709 28475 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0113 03:19:46.142783 28475 slave.cpp:547] Agent attributes: [ ] I0113 03:19:46.142797 28475 slave.cpp:552] Agent hostname: 02fc61e719e7 I0113 03:19:46.144309 28478 state.cpp:60] Recovering state from '/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_UFfXq2/meta' I0113 03:19:46.144733 28471 status_update_manager.cpp:203] Recovering status update manager I0113 03:19:46.145098 28466 slave.cpp:5421] Finished recovery I0113 03:19:46.145668 28466 slave.cpp:5595] Querying resource estimator for oversubscribable resources I0113 03:19:46.145998 28479 slave.cpp:5609] Received oversubscribable resources {} from the resource estimator Build timed out (after 300 minutes). Marking the build as failed. Build was aborted ++ docker rmi mesos-1484274759-11282 Error response from daemon: conflict: unable to remove repository reference "mesos-1484274759-11282" (must force) - container 02fc61e719e7 is using its referenced image 6e58cbefd907