You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/02/02 23:21:02 UTC
Build failed in Jenkins: Mesos-Reviewbot #16957
See <https://builds.apache.org/job/Mesos-Reviewbot/16957/changes>
Changes:
[yujie.jay] Implemented new http::Headers abstraction for WWW-Authenticate.
[yujie.jay] Fixed mesos test helper createBasicAuthHeaders() on http header.
[yujie.jay] Support 'Basic' auth docker registry on Unified Containerizer.
------------------------------------------
[...truncated 336726 lines...]
I0202 22:09:22.370645 30204 hierarchical.cpp:579] Agent 7316f203-d70f-4c2d-a99e-d2d316c3c972-S0 deactivated
I0202 22:09:22.374637 30178 master.cpp:1102] Master terminating
I0202 22:09:22.375808 30201 hierarchical.cpp:512] Removed agent 7316f203-d70f-4c2d-a99e-d2d316c3c972-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSession/1 (608 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/0
I0202 22:09:22.384631 30178 cluster.cpp:160] Creating default 'local' authorizer
I0202 22:09:22.387836 30200 master.cpp:383] Master 35c55442-a248-443b-ab4c-895e0a77a031 (cf3931586120) started on 172.17.0.2:48619
I0202 22:09:22.387862 30200 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/BlzEJq/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" --max_unreachable_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/BlzEJq/master" --zk_session_timeout="10secs"
I0202 22:09:22.388336 30200 master.cpp:435] Master only allowing authenticated frameworks to register
I0202 22:09:22.388353 30200 master.cpp:449] Master only allowing authenticated agents to register
I0202 22:09:22.388361 30200 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0202 22:09:22.388370 30200 credentials.hpp:37] Loading credentials for authentication from '/tmp/BlzEJq/credentials'
I0202 22:09:22.388730 30200 master.cpp:507] Using default 'crammd5' authenticator
I0202 22:09:22.388876 30200 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0202 22:09:22.389048 30200 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0202 22:09:22.389180 30200 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0202 22:09:22.389299 30200 master.cpp:587] Authorization enabled
I0202 22:09:22.389681 30203 whitelist_watcher.cpp:77] No whitelist given
I0202 22:09:22.389772 30202 hierarchical.cpp:152] Initialized hierarchical allocator process
I0202 22:09:22.393759 30198 master.cpp:2123] Elected as the leading master!
I0202 22:09:22.393810 30198 master.cpp:1645] Recovering from registrar
I0202 22:09:22.394078 30198 registrar.cpp:329] Recovering registrar
I0202 22:09:22.395488 30198 registrar.cpp:362] Successfully fetched the registry (0B) in 1.361152ms
I0202 22:09:22.395642 30198 registrar.cpp:461] Applied 1 operations in 39915ns; attempting to update the registry
I0202 22:09:22.397102 30198 registrar.cpp:506] Successfully updated the registry in 1.389056ms
I0202 22:09:22.397235 30198 registrar.cpp:392] Successfully recovered registrar
I0202 22:09:22.397790 30198 master.cpp:1761] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0202 22:09:22.398051 30198 hierarchical.cpp:179] Skipping recovery of hierarchical allocator: nothing to recover
I0202 22:09:22.401890 30178 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0202 22:09:22.402590 30178 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0202 22:09:22.402710 30178 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0202 22:09:22.402837 30178 provisioner.cpp:249] Using default backend 'copy'
I0202 22:09:22.407420 30178 cluster.cpp:446] Creating default 'local' authorizer
I0202 22:09:22.409787 30203 slave.cpp:209] Mesos agent started on (596)@172.17.0.2:48619
I0202 22:09:22.409811 30203 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_LaunchNestedContainerSessionWithTTY_0_3JCW78/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_LaunchNestedContainerSessionWithTTY_0_3JCW78/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_LaunchNestedContainerSessionWithTTY_0_3JCW78/http_credentials" --http_heartbeat_interval="30secs" --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_LaunchNestedContainerSessionWithTTY_0_3JCW78" --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_LaunchNestedContainerSessionWithTTY_0_DEbMVM"
I0202 22:09:22.410447 30203 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_3JCW78/credential'
I0202 22:09:22.410641 30203 slave.cpp:352] Agent using credential for: test-principal
I0202 22:09:22.410667 30203 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_3JCW78/http_credentials'
I0202 22:09:22.410959 30203 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0202 22:09:22.411113 30203 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0202 22:09:22.412215 30178 sched.cpp:232] Version: 1.2.0
I0202 22:09:22.412664 30203 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0202 22:09:22.412763 30203 slave.cpp:547] Agent attributes: [ ]
I0202 22:09:22.412780 30203 slave.cpp:552] Agent hostname: cf3931586120
I0202 22:09:22.413053 30201 status_update_manager.cpp:177] Pausing sending status updates
I0202 22:09:22.413280 30201 sched.cpp:336] New master detected at master@172.17.0.2:48619
I0202 22:09:22.413383 30201 sched.cpp:407] Authenticating with master master@172.17.0.2:48619
I0202 22:09:22.413403 30201 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0202 22:09:22.414033 30201 authenticatee.cpp:121] Creating new client SASL connection
I0202 22:09:22.414933 30205 state.cpp:60] Recovering state from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM/meta'
I0202 22:09:22.415226 30204 status_update_manager.cpp:203] Recovering status update manager
I0202 22:09:22.415386 30211 master.cpp:7018] Authenticating scheduler-c3ddbce1-eacd-44c3-ba15-2c435e50d10c@172.17.0.2:48619
I0202 22:09:22.415433 30204 containerizer.cpp:599] Recovering containerizer
I0202 22:09:22.415628 30212 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1160)@172.17.0.2:48619
I0202 22:09:22.416023 30211 authenticator.cpp:98] Creating new server SASL connection
I0202 22:09:22.416261 30204 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0202 22:09:22.416296 30204 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0202 22:09:22.416396 30204 authenticator.cpp:204] Received SASL authentication start
I0202 22:09:22.416473 30204 authenticator.cpp:326] Authentication requires more steps
I0202 22:09:22.416641 30204 authenticatee.cpp:259] Received SASL authentication step
I0202 22:09:22.416754 30204 authenticator.cpp:232] Received SASL authentication step
I0202 22:09:22.416787 30204 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cf3931586120' server FQDN: 'cf3931586120' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0202 22:09:22.416824 30204 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0202 22:09:22.416882 30204 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0202 22:09:22.417052 30204 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cf3931586120' server FQDN: 'cf3931586120' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0202 22:09:22.417146 30204 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0202 22:09:22.417307 30204 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0202 22:09:22.417276 30212 provisioner.cpp:410] Provisioner recovery complete
I0202 22:09:22.417454 30204 authenticator.cpp:318] Authentication success
I0202 22:09:22.417906 30201 authenticatee.cpp:299] Authentication success
I0202 22:09:22.417994 30201 master.cpp:7048] Successfully authenticated principal 'test-principal' at scheduler-c3ddbce1-eacd-44c3-ba15-2c435e50d10c@172.17.0.2:48619
I0202 22:09:22.418197 30205 slave.cpp:5422] Finished recovery
I0202 22:09:22.418468 30210 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1160)@172.17.0.2:48619
I0202 22:09:22.418751 30205 slave.cpp:5596] Querying resource estimator for oversubscribable resources
I0202 22:09:22.419224 30204 slave.cpp:929] New master detected at master@172.17.0.2:48619
I0202 22:09:22.419558 30204 slave.cpp:964] Detecting new master
I0202 22:09:22.419348 30203 status_update_manager.cpp:177] Pausing sending status updates
I0202 22:09:22.419957 30204 slave.cpp:5610] Received oversubscribable resources {} from the resource estimator
I0202 22:09:22.420322 30201 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:48619
I0202 22:09:22.420475 30201 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:48619
I0202 22:09:22.420696 30201 sched.cpp:869] Will retry registration in 797.184161ms if necessary
I0202 22:09:22.420843 30211 master.cpp:2736] Received SUBSCRIBE call for framework 'default' at scheduler-c3ddbce1-eacd-44c3-ba15-2c435e50d10c@172.17.0.2:48619
I0202 22:09:22.420904 30211 master.cpp:2159] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0202 22:09:22.421396 30201 master.cpp:2812] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0202 22:09:22.421464 30211 slave.cpp:991] Authenticating with master master@172.17.0.2:48619
I0202 22:09:22.421530 30211 slave.cpp:1002] Using default CRAM-MD5 authenticatee
I0202 22:09:22.421736 30197 authenticatee.cpp:121] Creating new client SASL connection
I0202 22:09:22.422050 30200 sched.cpp:759] Framework registered with 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.422049 30201 master.cpp:7018] Authenticating slave(596)@172.17.0.2:48619
I0202 22:09:22.422078 30203 hierarchical.cpp:272] Added framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.422106 30200 sched.cpp:773] Scheduler::registered took 25368ns
I0202 22:09:22.422168 30198 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1161)@172.17.0.2:48619
I0202 22:09:22.422267 30203 hierarchical.cpp:1697] No allocations performed
I0202 22:09:22.422307 30203 hierarchical.cpp:1786] No inverse offers to send out!
I0202 22:09:22.422351 30203 hierarchical.cpp:1322] Performed allocation for 0 agents in 128117ns
I0202 22:09:22.422364 30200 authenticator.cpp:98] Creating new server SASL connection
I0202 22:09:22.422552 30212 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0202 22:09:22.422580 30212 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0202 22:09:22.422672 30197 authenticator.cpp:204] Received SASL authentication start
I0202 22:09:22.422745 30197 authenticator.cpp:326] Authentication requires more steps
I0202 22:09:22.422850 30212 authenticatee.cpp:259] Received SASL authentication step
I0202 22:09:22.422957 30197 authenticator.cpp:232] Received SASL authentication step
I0202 22:09:22.422991 30197 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cf3931586120' server FQDN: 'cf3931586120' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0202 22:09:22.423008 30197 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0202 22:09:22.423050 30197 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0202 22:09:22.423079 30197 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cf3931586120' server FQDN: 'cf3931586120' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0202 22:09:22.423096 30197 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0202 22:09:22.423111 30197 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0202 22:09:22.423133 30197 authenticator.cpp:318] Authentication success
I0202 22:09:22.423215 30212 authenticatee.cpp:299] Authentication success
I0202 22:09:22.423255 30198 master.cpp:7048] Successfully authenticated principal 'test-principal' at slave(596)@172.17.0.2:48619
I0202 22:09:22.423458 30208 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1161)@172.17.0.2:48619
I0202 22:09:22.423527 30201 slave.cpp:1086] Successfully authenticated with master master@172.17.0.2:48619
I0202 22:09:22.423801 30201 slave.cpp:1508] Will retry registration in 8.201015ms if necessary
I0202 22:09:22.423991 30204 master.cpp:5303] Registering agent at slave(596)@172.17.0.2:48619 (cf3931586120) with id 35c55442-a248-443b-ab4c-895e0a77a031-S0
I0202 22:09:22.424501 30208 registrar.cpp:461] Applied 1 operations in 62164ns; attempting to update the registry
I0202 22:09:22.425848 30208 registrar.cpp:506] Successfully updated the registry in 1.284096ms
I0202 22:09:22.426527 30210 slave.cpp:4286] Received ping from slave-observer(547)@172.17.0.2:48619
I0202 22:09:22.426715 30204 slave.cpp:1132] Registered with master master@172.17.0.2:48619; given agent ID 35c55442-a248-443b-ab4c-895e0a77a031-S0
I0202 22:09:22.426653 30211 master.cpp:5377] Registered agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 at slave(596)@172.17.0.2:48619 (cf3931586120) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0202 22:09:22.426738 30204 fetcher.cpp:90] Clearing fetcher cache
I0202 22:09:22.426903 30200 status_update_manager.cpp:184] Resuming sending status updates
I0202 22:09:22.427114 30204 slave.cpp:1160] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM/meta/slaves/35c55442-a248-443b-ab4c-895e0a77a031-S0/slave.info'
I0202 22:09:22.427170 30209 hierarchical.cpp:479] Added agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 (cf3931586120) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0202 22:09:22.427448 30204 slave.cpp:1198] Forwarding total oversubscribed resources {}
I0202 22:09:22.427598 30200 master.cpp:5876] Received update of agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 at slave(596)@172.17.0.2:48619 (cf3931586120) with total oversubscribed resources {}
I0202 22:09:22.428607 30209 hierarchical.cpp:1786] No inverse offers to send out!
I0202 22:09:22.428663 30209 hierarchical.cpp:1322] Performed allocation for 1 agents in 1.348696ms
I0202 22:09:22.428827 30209 hierarchical.cpp:550] Agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 (cf3931586120) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I0202 22:09:22.429078 30209 hierarchical.cpp:1697] No allocations performed
I0202 22:09:22.429126 30209 hierarchical.cpp:1786] No inverse offers to send out!
I0202 22:09:22.429172 30209 hierarchical.cpp:1322] Performed allocation for 1 agents in 174983ns
I0202 22:09:22.429857 30212 master.cpp:6847] Sending 1 offers to framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 (default) at scheduler-c3ddbce1-eacd-44c3-ba15-2c435e50d10c@172.17.0.2:48619
I0202 22:09:22.430636 30203 sched.cpp:933] Scheduler::resourceOffers took 149004ns
I0202 22:09:22.432893 30200 master.cpp:3730] Processing ACCEPT call for offers: [ 35c55442-a248-443b-ab4c-895e0a77a031-O0 ] on agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 at slave(596)@172.17.0.2:48619 (cf3931586120) for framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 (default) at scheduler-c3ddbce1-eacd-44c3-ba15-2c435e50d10c@172.17.0.2:48619
I0202 22:09:22.433015 30200 master.cpp:3318] Authorizing framework principal 'test-principal' to launch task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a
I0202 22:09:22.436800 30202 master.cpp:8902] Adding task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 at slave(596)@172.17.0.2:48619 (cf3931586120)
I0202 22:09:22.437602 30202 master.cpp:4380] Launching task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 (default) at scheduler-c3ddbce1-eacd-44c3-ba15-2c435e50d10c@172.17.0.2:48619 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 at slave(596)@172.17.0.2:48619 (cf3931586120)
I0202 22:09:22.438709 30207 slave.cpp:1576] Got assigned task '9ea15113-b6bd-4534-b7b4-a6b9ec79686a' for framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.439512 30207 slave.cpp:1736] Launching task '9ea15113-b6bd-4534-b7b4-a6b9ec79686a' for framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.440080 30207 paths.cpp:547] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM/slaves/35c55442-a248-443b-ab4c-895e0a77a031-S0/frameworks/35c55442-a248-443b-ab4c-895e0a77a031-0000/executors/9ea15113-b6bd-4534-b7b4-a6b9ec79686a/runs/ce5d37fd-b130-4737-a12a-70df197baf9b' to user 'mesos'
I0202 22:09:22.440333 30207 slave.cpp:6331] Launching executor '9ea15113-b6bd-4534-b7b4-a6b9ec79686a' of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM/slaves/35c55442-a248-443b-ab4c-895e0a77a031-S0/frameworks/35c55442-a248-443b-ab4c-895e0a77a031-0000/executors/9ea15113-b6bd-4534-b7b4-a6b9ec79686a/runs/ce5d37fd-b130-4737-a12a-70df197baf9b'
I0202 22:09:22.440850 30204 containerizer.cpp:992] Starting container ce5d37fd-b130-4737-a12a-70df197baf9b for executor '9ea15113-b6bd-4534-b7b4-a6b9ec79686a' of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.440992 30207 slave.cpp:2058] Queued task '9ea15113-b6bd-4534-b7b4-a6b9ec79686a' for executor '9ea15113-b6bd-4534-b7b4-a6b9ec79686a' of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.441064 30207 slave.cpp:882] Successfully attached file '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM/slaves/35c55442-a248-443b-ab4c-895e0a77a031-S0/frameworks/35c55442-a248-443b-ab4c-895e0a77a031-0000/executors/9ea15113-b6bd-4534-b7b4-a6b9ec79686a/runs/ce5d37fd-b130-4737-a12a-70df197baf9b'
I0202 22:09:22.445987 30212 containerizer.cpp:1541] 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:48619"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM\/slaves\/35c55442-a248-443b-ab4c-895e0a77a031-S0\/frameworks\/35c55442-a248-443b-ab4c-895e0a77a031-0000\/executors\/9ea15113-b6bd-4534-b7b4-a6b9ec79686a\/runs\/ce5d37fd-b130-4737-a12a-70df197baf9b"},{"name":"MESOS_EXECUTOR_ID","value":"9ea15113-b6bd-4534-b7b4-a6b9ec79686a"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"35c55442-a248-443b-ab4c-895e0a77a031-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"35c55442-a248-443b-ab4c-895e0a77a031-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(596)@172.17.0.2:48619"},{"name":"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM\/slaves\/35c55442-a248-443b-ab4c-895e0a77a031-S0\/frameworks\/35c55442-a248-443b-ab4c-895e0a77a031-0000\/executors\/9ea15113-b6bd-4534-b7b4-a6b9ec79686a\/runs\/ce5d37fd-b130-4737-a12a-70df197baf9b"}]},"err":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM\/slaves\/35c55442-a248-443b-ab4c-895e0a77a031-S0\/frameworks\/35c55442-a248-443b-ab4c-895e0a77a031-0000\/executors\/9ea15113-b6bd-4534-b7b4-a6b9ec79686a\/runs\/ce5d37fd-b130-4737-a12a-70df197baf9b\/stderr","type":"PATH"},"out":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM\/slaves\/35c55442-a248-443b-ab4c-895e0a77a031-S0\/frameworks\/35c55442-a248-443b-ab4c-895e0a77a031-0000\/executors\/9ea15113-b6bd-4534-b7b4-a6b9ec79686a\/runs\/ce5d37fd-b130-4737-a12a-70df197baf9b\/stdout","type":"PATH"},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM\/slaves\/35c55442-a248-443b-ab4c-895e0a77a031-S0\/frameworks\/35c55442-a248-443b-ab4c-895e0a77a031-0000\/executors\/9ea15113-b6bd-4534-b7b4-a6b9ec79686a\/runs\/ce5d37fd-b130-4737-a12a-70df197baf9b"}" --pipe_read="22" --pipe_write="24" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_3JCW78/containers/ce5d37fd-b130-4737-a12a-70df197baf9b" --unshare_namespace_mnt="false"'
I0202 22:09:22.449970 30212 launcher.cpp:135] Forked child with pid '10215' for container 'ce5d37fd-b130-4737-a12a-70df197baf9b'
I0202 22:09:22.471895 30197 fetcher.cpp:349] Starting to fetch URIs for container: ce5d37fd-b130-4737-a12a-70df197baf9b, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM/slaves/35c55442-a248-443b-ab4c-895e0a77a031-S0/frameworks/35c55442-a248-443b-ab4c-895e0a77a031-0000/executors/9ea15113-b6bd-4534-b7b4-a6b9ec79686a/runs/ce5d37fd-b130-4737-a12a-70df197baf9b
I0202 22:09:22.633586 30199 slave.cpp:3325] Got registration for executor '9ea15113-b6bd-4534-b7b4-a6b9ec79686a' of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 from executor(1)@172.17.0.2:49989
I0202 22:09:22.636085 30206 slave.cpp:2271] Sending queued task '9ea15113-b6bd-4534-b7b4-a6b9ec79686a' to executor '9ea15113-b6bd-4534-b7b4-a6b9ec79686a' of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 at executor(1)@172.17.0.2:49989
I0202 22:09:22.653358 30208 slave.cpp:3756] Handling status update TASK_RUNNING (UUID: 90ca828b-afe5-4654-b917-4b1375d8361d) for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 from executor(1)@172.17.0.2:49989
I0202 22:09:22.655469 30198 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 90ca828b-afe5-4654-b917-4b1375d8361d) for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.655541 30198 status_update_manager.cpp:500] Creating StatusUpdate stream for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.656162 30198 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 90ca828b-afe5-4654-b917-4b1375d8361d) for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 to the agent
I0202 22:09:22.656492 30209 slave.cpp:4196] Forwarding the update TASK_RUNNING (UUID: 90ca828b-afe5-4654-b917-4b1375d8361d) for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 to master@172.17.0.2:48619
I0202 22:09:22.656797 30209 slave.cpp:4090] Status update manager successfully handled status update TASK_RUNNING (UUID: 90ca828b-afe5-4654-b917-4b1375d8361d) for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.656955 30209 slave.cpp:4106] Sending acknowledgement for status update TASK_RUNNING (UUID: 90ca828b-afe5-4654-b917-4b1375d8361d) for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 to executor(1)@172.17.0.2:49989
I0202 22:09:22.657127 30197 master.cpp:6021] Status update TASK_RUNNING (UUID: 90ca828b-afe5-4654-b917-4b1375d8361d) for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 from agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 at slave(596)@172.17.0.2:48619 (cf3931586120)
I0202 22:09:22.658069 30197 master.cpp:6089] Forwarding status update TASK_RUNNING (UUID: 90ca828b-afe5-4654-b917-4b1375d8361d) for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.658325 30197 master.cpp:8157] Updating the state of task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0202 22:09:22.658617 30205 sched.cpp:1041] Scheduler::statusUpdate took 182087ns
I0202 22:09:22.659059 30211 master.cpp:5017] Processing ACKNOWLEDGE call 90ca828b-afe5-4654-b917-4b1375d8361d for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 (default) at scheduler-c3ddbce1-eacd-44c3-ba15-2c435e50d10c@172.17.0.2:48619 on agent 35c55442-a248-443b-ab4c-895e0a77a031-S0
I0202 22:09:22.659405 30209 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 90ca828b-afe5-4654-b917-4b1375d8361d) for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.659945 30200 slave.cpp:3045] Status update manager successfully handled status update acknowledgement (UUID: 90ca828b-afe5-4654-b917-4b1375d8361d) for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.663030 30204 process.cpp:3697] Handling HTTP event for process 'slave(596)' with path: '/slave(596)/api/v1'
I0202 22:09:22.664072 30205 http.cpp:303] HTTP POST for /slave(596)/api/v1 from 172.17.0.2:49835
I0202 22:09:22.664705 30205 http.cpp:517] Processing call LAUNCH_NESTED_CONTAINER_SESSION
I0202 22:09:22.665432 30212 containerizer.cpp:1808] Starting nested container ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b
I0202 22:09:22.665716 30212 containerizer.cpp:1832] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM/slaves/35c55442-a248-443b-ab4c-895e0a77a031-S0/frameworks/35c55442-a248-443b-ab4c-895e0a77a031-0000/executors/9ea15113-b6bd-4534-b7b4-a6b9ec79686a/runs/ce5d37fd-b130-4737-a12a-70df197baf9b/containers/748283dc-d81b-41bd-bfe2-d434dd76562b' to user 'mesos'
I0202 22:09:22.668174 30199 switchboard.cpp:434] Allocated pseudo terminal '/dev/pts/1' for container ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b
I0202 22:09:22.668530 30199 switchboard.cpp:571] Launching 'mesos-io-switchboard' with flags '--heartbeat_interval="30secs" --help="false" --socket_address="/tmp/mesos-io-switchboard-a7cefd92-5ce6-407c-8a5c-c4cd362701ad" --stderr_from_fd="28" --stderr_to_fd="2" --stdin_to_fd="28" --stdout_from_fd="28" --stdout_to_fd="1" --tty="true" --wait_for_connection="true"' for container ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b
I0202 22:09:22.671608 30199 switchboard.cpp:601] Created I/O switchboard server (pid: 10274) listening on socket file '/tmp/mesos-io-switchboard-a7cefd92-5ce6-407c-8a5c-c4cd362701ad' for container ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b
I0202 22:09:22.673394 30198 containerizer.cpp:1541] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"printf output && printf error 1>&2"},"environment":{},"err":{"fd":29,"type":"FD"},"in":{"fd":29,"type":"FD"},"out":{"fd":29,"type":"FD"},"tty_slave_path":"\/dev\/pts\/1","user":"mesos"}" --pipe_read="28" --pipe_write="41" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_3JCW78/containers/ce5d37fd-b130-4737-a12a-70df197baf9b/containers/748283dc-d81b-41bd-bfe2-d434dd76562b" --unshare_namespace_mnt="false"'
I0202 22:09:22.676550 30198 launcher.cpp:135] Forked child with pid '10275' for container 'ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b'
I0202 22:09:22.680045 30212 fetcher.cpp:349] Starting to fetch URIs for container: ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM/slaves/35c55442-a248-443b-ab4c-895e0a77a031-S0/frameworks/35c55442-a248-443b-ab4c-895e0a77a031-0000/executors/9ea15113-b6bd-4534-b7b4-a6b9ec79686a/runs/ce5d37fd-b130-4737-a12a-70df197baf9b/containers/748283dc-d81b-41bd-bfe2-d434dd76562b
**** DEADLOCK DETECTED! ****
You are waiting on process __limiter__(617)@172.17.0.2:48619 that it is currently executing.
I0202 22:09:22.768595 30199 http.cpp:2699] Received EOF attach response for ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b
I0202 22:09:22.769150 30204 containerizer.cpp:2119] Destroying container ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b in RUNNING state
W0202 22:09:22.769405 30207 http.cpp:2714] Launch nested container session connection for container ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b closed
I0202 22:09:22.769479 30204 launcher.cpp:151] Asked to destroy container ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b
I0202 22:09:22.770571 30178 sched.cpp:2021] Asked to stop the driver
I0202 22:09:22.770690 30208 sched.cpp:1203] Stopping framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.771098 30208 master.cpp:7560] Processing TEARDOWN call for framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 (default) at scheduler-c3ddbce1-eacd-44c3-ba15-2c435e50d10c@172.17.0.2:48619
I0202 22:09:22.771138 30208 master.cpp:7572] Removing framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 (default) at scheduler-c3ddbce1-eacd-44c3-ba15-2c435e50d10c@172.17.0.2:48619
I0202 22:09:22.771517 30208 master.cpp:3080] Deactivating framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 (default) at scheduler-c3ddbce1-eacd-44c3-ba15-2c435e50d10c@172.17.0.2:48619
I0202 22:09:22.772161 30212 hierarchical.cpp:387] Deactivated framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.772999 30209 slave.cpp:2598] Asked to shut down framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 by master@172.17.0.2:48619
I0202 22:09:22.773049 30209 slave.cpp:2623] Shutting down framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.773243 30209 slave.cpp:5005] Shutting down executor '9ea15113-b6bd-4534-b7b4-a6b9ec79686a' of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 at executor(1)@172.17.0.2:49989
I0202 22:09:22.772305 30208 master.cpp:8157] Updating the state of task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0202 22:09:22.774974 30208 master.cpp:8251] Removing task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 on agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 at slave(596)@172.17.0.2:48619 (cf3931586120)
I0202 22:09:22.775171 30210 hierarchical.cpp:1013] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 from framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.775655 30209 hierarchical.cpp:338] Removed framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.778672 30206 containerizer.cpp:2119] Destroying container ce5d37fd-b130-4737-a12a-70df197baf9b in RUNNING state
I0202 22:09:22.834559 30203 slave.cpp:3756] Handling status update TASK_KILLED (UUID: fa34e83a-1a61-4e59-ae90-6b096f387d11) for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 from executor(1)@172.17.0.2:49989
W0202 22:09:22.834681 30203 slave.cpp:3825] Ignoring status update TASK_KILLED (UUID: fa34e83a-1a61-4e59-ae90-6b096f387d11) for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 for terminating framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
E0202 22:09:22.863412 30210 switchboard.cpp:889] Unexpected termination of I/O switchboard server: 'IOSwitchboard' exited with status 1 for container ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b
I0202 22:09:22.863811 30197 containerizer.cpp:2482] Container ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b has exited
I0202 22:09:22.865551 30205 provisioner.cpp:484] Ignoring destroy request for unknown container ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b
I0202 22:09:22.865732 30208 containerizer.cpp:2398] Checkpointing termination state to nested container's runtime directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_3JCW78/containers/ce5d37fd-b130-4737-a12a-70df197baf9b/containers/748283dc-d81b-41bd-bfe2-d434dd76562b/termination'
I0202 22:09:22.866547 30208 launcher.cpp:151] Asked to destroy container ce5d37fd-b130-4737-a12a-70df197baf9b
I0202 22:09:22.876947 30212 slave.cpp:4328] Got exited event for executor(1)@172.17.0.2:49989
I0202 22:09:22.965767 30202 containerizer.cpp:2482] Container ce5d37fd-b130-4737-a12a-70df197baf9b has exited
I0202 22:09:22.968153 30206 provisioner.cpp:484] Ignoring destroy request for unknown container ce5d37fd-b130-4737-a12a-70df197baf9b
I0202 22:09:22.969233 30211 slave.cpp:4691] Executor '9ea15113-b6bd-4534-b7b4-a6b9ec79686a' of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 terminated with signal Killed
I0202 22:09:22.969305 30211 slave.cpp:4791] Cleaning up executor '9ea15113-b6bd-4534-b7b4-a6b9ec79686a' of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000 at executor(1)@172.17.0.2:49989
I0202 22:09:22.969611 30200 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM/slaves/35c55442-a248-443b-ab4c-895e0a77a031-S0/frameworks/35c55442-a248-443b-ab4c-895e0a77a031-0000/executors/9ea15113-b6bd-4534-b7b4-a6b9ec79686a/runs/ce5d37fd-b130-4737-a12a-70df197baf9b' for gc 6.99998877879704days in the future
I0202 22:09:22.969779 30211 slave.cpp:4879] Cleaning up framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.969784 30200 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM/slaves/35c55442-a248-443b-ab4c-895e0a77a031-S0/frameworks/35c55442-a248-443b-ab4c-895e0a77a031-0000/executors/9ea15113-b6bd-4534-b7b4-a6b9ec79686a' for gc 6.99998877643556days in the future
W0202 22:09:22.969931 30197 containerizer.cpp:2107] Attempted to destroy unknown container ce5d37fd-b130-4737-a12a-70df197baf9b.748283dc-d81b-41bd-bfe2-d434dd76562b
I0202 22:09:22.970001 30201 status_update_manager.cpp:285] Closing status update streams for framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.970062 30201 status_update_manager.cpp:531] Cleaning up status update stream for task 9ea15113-b6bd-4534-b7b4-a6b9ec79686a of framework 35c55442-a248-443b-ab4c-895e0a77a031-0000
I0202 22:09:22.970799 30199 slave.cpp:801] Agent terminating
I0202 22:09:22.970824 30200 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionWithTTY_0_DEbMVM/slaves/35c55442-a248-443b-ab4c-895e0a77a031-S0/frameworks/35c55442-a248-443b-ab4c-895e0a77a031-0000' for gc 6.99998877407407days in the future
I0202 22:09:22.971011 30210 master.cpp:1263] Agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 at slave(596)@172.17.0.2:48619 (cf3931586120) disconnected
I0202 22:09:22.971051 30210 master.cpp:3117] Disconnecting agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 at slave(596)@172.17.0.2:48619 (cf3931586120)
I0202 22:09:22.971127 30210 master.cpp:3136] Deactivating agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 at slave(596)@172.17.0.2:48619 (cf3931586120)
I0202 22:09:22.971267 30202 hierarchical.cpp:579] Agent 35c55442-a248-443b-ab4c-895e0a77a031-S0 deactivated
I0202 22:09:22.975388 30212 master.cpp:1102] Master terminating
I0202 22:09:22.976110 30204 hierarchical.cpp:512] Removed agent 35c55442-a248-443b-ab4c-895e0a77a031-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/0 (596 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionWithTTY/1
I0202 22:09:22.982547 30178 cluster.cpp:160] Creating default 'local' authorizer
I0202 22:09:22.984952 30212 master.cpp:383] Master e6c0cda2-4970-4c0e-8bb9-8537c87f8980 (cf3931586120) started on 172.17.0.2:48619
I0202 22:09:22.984988 30212 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/71v1Hs/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" --max_unreachable_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/71v1Hs/master" --zk_session_timeout="10secs"
I0202 22:09:22.985483 30212 master.cpp:435] Master only allowing authenticated frameworks to register
I0202 22:09:22.985496 30212 master.cpp:449] Master only allowing authenticated agents to register
I0202 22:09:22.985501 30212 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0202 22:09:22.985507 30212 credentials.hpp:37] Loading credentials for authentication from '/tmp/71v1Hs/credentials'
I0202 22:09:22.985836 30212 master.cpp:507] Using default 'crammd5' authenticator
I0202 22:09:22.985971 30212 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0202 22:09:22.986227 30212 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0202 22:09:22.986347 30212 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0202 22:09:22.986486 30212 master.cpp:587] Authorization enabled
I0202 22:09:22.986698 30208 hierarchical.cpp:152] Initialized hierarchical allocator process
I0202 22:09:22.986702 30205 whitelist_watcher.cpp:77] No whitelist given
I0202 22:09:22.988872 30211 master.cpp:2123] Elected as the leading master!
I0202 22:09:22.988899 30211 master.cpp:1645] Recovering from registrar
I0202 22:09:22.989073 30199 registrar.cpp:329] Recovering registrar
I0202 22:09:22.989841 30199 registrar.cpp:362] Successfully fetched the registry (0B) in 715008ns
I0202 22:09:22.989939 30199 registrar.cpp:461] Applied 1 operations in 20822ns; attempting to update the registry
I0202 22:09:22.990742 30211 registrar.cpp:506] Successfully updated the registry in 747008ns
I0202 22:09:22.990869 30211 registrar.cpp:392] Successfully recovered registrar
I0202 22:09:22.991354 30210 hierarchical.cpp:179] Skipping recovery of hierarchical allocator: nothing to recover
I0202 22:09:22.991346 30203 master.cpp:1761] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
Build timed out (after 180 minutes). Marking the build as failed.
Build was aborted
Jenkins build is back to normal : Mesos-Reviewbot #16958
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/16958/changes>