You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/12/16 07:31:23 UTC
Build failed in Jenkins: mesos-reviewbot #16469
See <https://builds.apache.org/job/mesos-reviewbot/16469/>
------------------------------------------
[...truncated 152262 lines...]
I1216 05:12:41.836294 29740 provisioner.cpp:324] Ignoring destroy request for unknown container 278ba765-dc62-488e-b27b-024e163d708c
I1216 05:12:41.837628 29735 slave.cpp:4673] Executor 'cefbe32c-0cdb-446c-9471-9168bc243245' of framework e6731eaf-0571-4a05-88d7-ed62de2964a5-0000 terminated with signal Killed
I1216 05:12:41.837712 29735 slave.cpp:4773] Cleaning up executor 'cefbe32c-0cdb-446c-9471-9168bc243245' of framework e6731eaf-0571-4a05-88d7-ed62de2964a5-0000 at executor(1)@172.17.0.2:54276
I1216 05:12:41.838132 29733 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_C4a8k0/slaves/e6731eaf-0571-4a05-88d7-ed62de2964a5-S0/frameworks/e6731eaf-0571-4a05-88d7-ed62de2964a5-0000/executors/cefbe32c-0cdb-446c-9471-9168bc243245/runs/278ba765-dc62-488e-b27b-024e163d708c' for gc 6.99999030127407days in the future
I1216 05:12:41.838356 29729 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_C4a8k0/slaves/e6731eaf-0571-4a05-88d7-ed62de2964a5-S0/frameworks/e6731eaf-0571-4a05-88d7-ed62de2964a5-0000/executors/cefbe32c-0cdb-446c-9471-9168bc243245' for gc 6.99999029784889days in the future
I1216 05:12:41.838369 29735 slave.cpp:4861] Cleaning up framework e6731eaf-0571-4a05-88d7-ed62de2964a5-0000
I1216 05:12:41.838577 29736 status_update_manager.cpp:285] Closing status update streams for framework e6731eaf-0571-4a05-88d7-ed62de2964a5-0000
I1216 05:12:41.838676 29736 status_update_manager.cpp:531] Cleaning up status update stream for task cefbe32c-0cdb-446c-9471-9168bc243245 of framework e6731eaf-0571-4a05-88d7-ed62de2964a5-0000
I1216 05:12:41.838805 29738 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_0_C4a8k0/slaves/e6731eaf-0571-4a05-88d7-ed62de2964a5-S0/frameworks/e6731eaf-0571-4a05-88d7-ed62de2964a5-0000' for gc 6.99999029383111days in the future
I1216 05:12:41.839089 29735 slave.cpp:796] Agent terminating
I1216 05:12:41.839565 29733 master.cpp:1258] Agent e6731eaf-0571-4a05-88d7-ed62de2964a5-S0 at slave(578)@172.17.0.2:50053 (e16bb4d6c206) disconnected
I1216 05:12:41.839606 29733 master.cpp:2978] Disconnecting agent e6731eaf-0571-4a05-88d7-ed62de2964a5-S0 at slave(578)@172.17.0.2:50053 (e16bb4d6c206)
I1216 05:12:41.839689 29733 master.cpp:2997] Deactivating agent e6731eaf-0571-4a05-88d7-ed62de2964a5-S0 at slave(578)@172.17.0.2:50053 (e16bb4d6c206)
I1216 05:12:41.839913 29733 hierarchical.cpp:589] Agent e6731eaf-0571-4a05-88d7-ed62de2964a5-S0 deactivated
I1216 05:12:41.847157 29728 master.cpp:1097] Master terminating
I1216 05:12:41.847961 29743 hierarchical.cpp:522] Removed agent e6731eaf-0571-4a05-88d7-ed62de2964a5-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/0 (604 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/1
I1216 05:12:41.856629 29709 cluster.cpp:160] Creating default 'local' authorizer
I1216 05:12:41.859041 29730 master.cpp:380] Master 54765064-6459-47f9-b698-396caaa1d970 (e16bb4d6c206) started on 172.17.0.2:50053
I1216 05:12:41.859062 29730 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/gjLUW4/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/gjLUW4/master" --zk_session_timeout="10secs"
I1216 05:12:41.859410 29730 master.cpp:432] Master only allowing authenticated frameworks to register
I1216 05:12:41.859431 29730 master.cpp:446] Master only allowing authenticated agents to register
I1216 05:12:41.859446 29730 master.cpp:459] Master only allowing authenticated HTTP frameworks to register
I1216 05:12:41.859485 29730 credentials.hpp:37] Loading credentials for authentication from '/tmp/gjLUW4/credentials'
I1216 05:12:41.859791 29730 master.cpp:504] Using default 'crammd5' authenticator
I1216 05:12:41.859932 29730 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I1216 05:12:41.860074 29730 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I1216 05:12:41.860200 29730 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I1216 05:12:41.860306 29730 master.cpp:584] Authorization enabled
I1216 05:12:41.860499 29736 whitelist_watcher.cpp:77] No whitelist given
I1216 05:12:41.860550 29731 hierarchical.cpp:149] Initialized hierarchical allocator process
I1216 05:12:41.862607 29743 master.cpp:2046] Elected as the leading master!
I1216 05:12:41.862638 29743 master.cpp:1568] Recovering from registrar
I1216 05:12:41.862834 29732 registrar.cpp:329] Recovering registrar
I1216 05:12:41.863373 29732 registrar.cpp:362] Successfully fetched the registry (0B) in 493056ns
I1216 05:12:41.863464 29732 registrar.cpp:461] Applied 1 operations in 19163ns; attempting to update the registry
I1216 05:12:41.864300 29732 registrar.cpp:506] Successfully updated the registry in 784896ns
I1216 05:12:41.864410 29732 registrar.cpp:392] Successfully recovered registrar
I1216 05:12:41.864866 29738 master.cpp:1684] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I1216 05:12:41.864884 29729 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover
I1216 05:12:41.867784 29709 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1216 05:12:41.868461 29709 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1216 05:12:41.868669 29709 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1216 05:12:41.873281 29709 cluster.cpp:446] Creating default 'local' authorizer
I1216 05:12:41.875243 29733 slave.cpp:209] Mesos agent started on (579)@172.17.0.2:50053
I1216 05:12:41.875277 29733 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_YM8wYI/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_YM8wYI/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_YM8wYI/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_YM8wYI" --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_FTqIXT"
I1216 05:12:41.875924 29733 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_YM8wYI/credential'
I1216 05:12:41.876161 29733 slave.cpp:352] Agent using credential for: test-principal
I1216 05:12:41.876194 29733 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_YM8wYI/http_credentials'
I1216 05:12:41.876610 29733 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1216 05:12:41.876705 29709 sched.cpp:232] Version: 1.2.0
I1216 05:12:41.876924 29733 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1216 05:12:41.877562 29731 sched.cpp:336] New master detected at master@172.17.0.2:50053
I1216 05:12:41.877682 29731 sched.cpp:402] Authenticating with master master@172.17.0.2:50053
I1216 05:12:41.877707 29731 sched.cpp:409] Using default CRAM-MD5 authenticatee
I1216 05:12:41.878006 29740 authenticatee.cpp:121] Creating new client SASL connection
I1216 05:12:41.878361 29739 master.cpp:6749] Authenticating scheduler-d15f907c-6964-4a39-aa81-02fa1dad6db7@172.17.0.2:50053
I1216 05:12:41.878511 29735 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1132)@172.17.0.2:50053
I1216 05:12:41.878636 29733 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1216 05:12:41.878793 29733 slave.cpp:547] Agent attributes: [ ]
I1216 05:12:41.878813 29728 authenticator.cpp:98] Creating new server SASL connection
I1216 05:12:41.878820 29733 slave.cpp:552] Agent hostname: e16bb4d6c206
I1216 05:12:41.879132 29734 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1216 05:12:41.879168 29734 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1216 05:12:41.879302 29734 authenticator.cpp:204] Received SASL authentication start
I1216 05:12:41.879391 29734 authenticator.cpp:326] Authentication requires more steps
I1216 05:12:41.879825 29741 authenticatee.cpp:259] Received SASL authentication step
I1216 05:12:41.880071 29737 authenticator.cpp:232] Received SASL authentication step
I1216 05:12:41.880125 29737 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e16bb4d6c206' server FQDN: 'e16bb4d6c206' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1216 05:12:41.880157 29737 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1216 05:12:41.880221 29737 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1216 05:12:41.880277 29737 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e16bb4d6c206' server FQDN: 'e16bb4d6c206' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1216 05:12:41.880311 29737 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1216 05:12:41.880333 29737 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1216 05:12:41.880368 29737 authenticator.cpp:318] Authentication success
I1216 05:12:41.880579 29730 authenticatee.cpp:299] Authentication success
I1216 05:12:41.880663 29739 master.cpp:6779] Successfully authenticated principal 'test-principal' at scheduler-d15f907c-6964-4a39-aa81-02fa1dad6db7@172.17.0.2:50053
I1216 05:12:41.880729 29738 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1132)@172.17.0.2:50053
I1216 05:12:41.881294 29734 state.cpp:57] Recovering state from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT/meta'
I1216 05:12:41.881319 29728 sched.cpp:508] Successfully authenticated with master master@172.17.0.2:50053
I1216 05:12:41.881353 29728 sched.cpp:826] Sending SUBSCRIBE call to master@172.17.0.2:50053
I1216 05:12:41.881717 29728 sched.cpp:859] Will retry registration in 1.868310627secs if necessary
I1216 05:12:41.881891 29729 status_update_manager.cpp:203] Recovering status update manager
I1216 05:12:41.881949 29737 master.cpp:2634] Received SUBSCRIBE call for framework 'default' at scheduler-d15f907c-6964-4a39-aa81-02fa1dad6db7@172.17.0.2:50053
I1216 05:12:41.882045 29737 master.cpp:2082] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1216 05:12:41.882655 29737 containerizer.cpp:594] Recovering containerizer
I1216 05:12:41.882725 29741 master.cpp:2710] Subscribing framework default with checkpointing disabled and capabilities [ ]
I1216 05:12:41.883472 29728 sched.cpp:749] Framework registered with 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:41.883519 29739 hierarchical.cpp:276] Added framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:41.883636 29739 hierarchical.cpp:1689] No allocations performed
I1216 05:12:41.883656 29728 sched.cpp:763] Scheduler::registered took 55893ns
I1216 05:12:41.883687 29739 hierarchical.cpp:1784] No inverse offers to send out!
I1216 05:12:41.883760 29739 hierarchical.cpp:1291] Performed allocation for 0 agents in 208488ns
I1216 05:12:41.884516 29729 provisioner.cpp:253] Provisioner recovery complete
I1216 05:12:41.885004 29742 slave.cpp:5408] Finished recovery
I1216 05:12:41.885532 29742 slave.cpp:5582] Querying resource estimator for oversubscribable resources
I1216 05:12:41.885865 29742 slave.cpp:924] New master detected at master@172.17.0.2:50053
I1216 05:12:41.885882 29730 status_update_manager.cpp:177] Pausing sending status updates
I1216 05:12:41.885898 29742 slave.cpp:983] Authenticating with master master@172.17.0.2:50053
I1216 05:12:41.885960 29742 slave.cpp:994] Using default CRAM-MD5 authenticatee
I1216 05:12:41.886113 29742 slave.cpp:956] Detecting new master
I1216 05:12:41.886245 29734 authenticatee.cpp:121] Creating new client SASL connection
I1216 05:12:41.886302 29742 slave.cpp:5596] Received oversubscribable resources {} from the resource estimator
I1216 05:12:41.886533 29729 master.cpp:6749] Authenticating slave(579)@172.17.0.2:50053
I1216 05:12:41.886659 29731 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1133)@172.17.0.2:50053
I1216 05:12:41.886920 29734 authenticator.cpp:98] Creating new server SASL connection
I1216 05:12:41.887230 29737 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1216 05:12:41.887272 29737 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1216 05:12:41.887388 29737 authenticator.cpp:204] Received SASL authentication start
I1216 05:12:41.887454 29737 authenticator.cpp:326] Authentication requires more steps
I1216 05:12:41.887562 29737 authenticatee.cpp:259] Received SASL authentication step
I1216 05:12:41.887734 29741 authenticator.cpp:232] Received SASL authentication step
I1216 05:12:41.887783 29741 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e16bb4d6c206' server FQDN: 'e16bb4d6c206' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1216 05:12:41.887804 29741 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1216 05:12:41.887845 29741 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1216 05:12:41.887892 29741 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e16bb4d6c206' server FQDN: 'e16bb4d6c206' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1216 05:12:41.887917 29741 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1216 05:12:41.887944 29741 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1216 05:12:41.887979 29741 authenticator.cpp:318] Authentication success
I1216 05:12:41.888100 29740 authenticatee.cpp:299] Authentication success
I1216 05:12:41.888198 29743 master.cpp:6779] Successfully authenticated principal 'test-principal' at slave(579)@172.17.0.2:50053
I1216 05:12:41.888291 29736 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1133)@172.17.0.2:50053
I1216 05:12:41.888483 29733 slave.cpp:1078] Successfully authenticated with master master@172.17.0.2:50053
I1216 05:12:41.888672 29733 slave.cpp:1492] Will retry registration in 5.553886ms if necessary
I1216 05:12:41.888929 29742 master.cpp:5162] Registering agent at slave(579)@172.17.0.2:50053 (e16bb4d6c206) with id 54765064-6459-47f9-b698-396caaa1d970-S0
I1216 05:12:41.889421 29736 registrar.cpp:461] Applied 1 operations in 58663ns; attempting to update the registry
I1216 05:12:41.891285 29736 registrar.cpp:506] Successfully updated the registry in 1.758208ms
I1216 05:12:41.892213 29736 slave.cpp:4264] Received ping from slave-observer(534)@172.17.0.2:50053
I1216 05:12:41.892298 29733 master.cpp:5233] Registered agent 54765064-6459-47f9-b698-396caaa1d970-S0 at slave(579)@172.17.0.2:50053 (e16bb4d6c206) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1216 05:12:41.892479 29736 slave.cpp:1124] Registered with master master@172.17.0.2:50053; given agent ID 54765064-6459-47f9-b698-396caaa1d970-S0
I1216 05:12:41.892511 29736 fetcher.cpp:90] Clearing fetcher cache
I1216 05:12:41.892705 29739 status_update_manager.cpp:184] Resuming sending status updates
I1216 05:12:41.892815 29731 hierarchical.cpp:490] Added agent 54765064-6459-47f9-b698-396caaa1d970-S0 (e16bb4d6c206) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I1216 05:12:41.893050 29736 slave.cpp:1147] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT/meta/slaves/54765064-6459-47f9-b698-396caaa1d970-S0/slave.info'
I1216 05:12:41.893534 29736 slave.cpp:1184] Forwarding total oversubscribed resources {}
I1216 05:12:41.893709 29736 master.cpp:5634] Received update of agent 54765064-6459-47f9-b698-396caaa1d970-S0 at slave(579)@172.17.0.2:50053 (e16bb4d6c206) with total oversubscribed resources {}
I1216 05:12:41.894219 29731 hierarchical.cpp:1784] No inverse offers to send out!
I1216 05:12:41.894309 29731 hierarchical.cpp:1314] Performed allocation for agent 54765064-6459-47f9-b698-396caaa1d970-S0 in 1.4409ms
I1216 05:12:41.894490 29731 hierarchical.cpp:560] Agent 54765064-6459-47f9-b698-396caaa1d970-S0 (e16bb4d6c206) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
I1216 05:12:41.894642 29731 hierarchical.cpp:1689] No allocations performed
I1216 05:12:41.894695 29731 hierarchical.cpp:1784] No inverse offers to send out!
I1216 05:12:41.894778 29731 hierarchical.cpp:1314] Performed allocation for agent 54765064-6459-47f9-b698-396caaa1d970-S0 in 233491ns
I1216 05:12:41.894817 29732 master.cpp:6578] Sending 1 offers to framework 54765064-6459-47f9-b698-396caaa1d970-0000 (default) at scheduler-d15f907c-6964-4a39-aa81-02fa1dad6db7@172.17.0.2:50053
I1216 05:12:41.895370 29742 sched.cpp:923] Scheduler::resourceOffers took 127190ns
I1216 05:12:41.897505 29735 master.cpp:3589] Processing ACCEPT call for offers: [ 54765064-6459-47f9-b698-396caaa1d970-O0 ] on agent 54765064-6459-47f9-b698-396caaa1d970-S0 at slave(579)@172.17.0.2:50053 (e16bb4d6c206) for framework 54765064-6459-47f9-b698-396caaa1d970-0000 (default) at scheduler-d15f907c-6964-4a39-aa81-02fa1dad6db7@172.17.0.2:50053
I1216 05:12:41.897617 29735 master.cpp:3176] Authorizing framework principal 'test-principal' to launch task 06bee625-84ce-4b88-85d6-a79809b9ce15
I1216 05:12:41.900266 29735 master.cpp:8502] Adding task 06bee625-84ce-4b88-85d6-a79809b9ce15 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 54765064-6459-47f9-b698-396caaa1d970-S0 (e16bb4d6c206)
I1216 05:12:41.900650 29735 master.cpp:4241] Launching task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000 (default) at scheduler-d15f907c-6964-4a39-aa81-02fa1dad6db7@172.17.0.2:50053 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 54765064-6459-47f9-b698-396caaa1d970-S0 at slave(579)@172.17.0.2:50053 (e16bb4d6c206)
I1216 05:12:41.901584 29738 slave.cpp:1556] Got assigned task '06bee625-84ce-4b88-85d6-a79809b9ce15' for framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:41.902463 29738 slave.cpp:1718] Launching task '06bee625-84ce-4b88-85d6-a79809b9ce15' for framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:41.903271 29738 paths.cpp:530] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT/slaves/54765064-6459-47f9-b698-396caaa1d970-S0/frameworks/54765064-6459-47f9-b698-396caaa1d970-0000/executors/06bee625-84ce-4b88-85d6-a79809b9ce15/runs/ad011215-ee09-4793-9cfd-09dfd6675be3' to user 'mesos'
I1216 05:12:41.912972 29738 slave.cpp:6335] Launching executor '06bee625-84ce-4b88-85d6-a79809b9ce15' of framework 54765064-6459-47f9-b698-396caaa1d970-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT/slaves/54765064-6459-47f9-b698-396caaa1d970-S0/frameworks/54765064-6459-47f9-b698-396caaa1d970-0000/executors/06bee625-84ce-4b88-85d6-a79809b9ce15/runs/ad011215-ee09-4793-9cfd-09dfd6675be3'
I1216 05:12:41.913950 29735 containerizer.cpp:986] Starting container ad011215-ee09-4793-9cfd-09dfd6675be3 for executor '06bee625-84ce-4b88-85d6-a79809b9ce15' of framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:41.913962 29738 slave.cpp:2040] Queued task '06bee625-84ce-4b88-85d6-a79809b9ce15' for executor '06bee625-84ce-4b88-85d6-a79809b9ce15' of framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:41.914085 29738 slave.cpp:877] Successfully attached file '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT/slaves/54765064-6459-47f9-b698-396caaa1d970-S0/frameworks/54765064-6459-47f9-b698-396caaa1d970-0000/executors/06bee625-84ce-4b88-85d6-a79809b9ce15/runs/ad011215-ee09-4793-9cfd-09dfd6675be3'
I1216 05:12:41.920181 29730 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:50053"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT\/slaves\/54765064-6459-47f9-b698-396caaa1d970-S0\/frameworks\/54765064-6459-47f9-b698-396caaa1d970-0000\/executors\/06bee625-84ce-4b88-85d6-a79809b9ce15\/runs\/ad011215-ee09-4793-9cfd-09dfd6675be3"},{"name":"MESOS_EXECUTOR_ID","value":"06bee625-84ce-4b88-85d6-a79809b9ce15"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"54765064-6459-47f9-b698-396caaa1d970-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"54765064-6459-47f9-b698-396caaa1d970-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(579)@172.17.0.2:50053"},{"name":"MESOS_SANDBOX","value":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT\/slaves\/54765064-6459-47f9-b698-396caaa1d970-S0\/frameworks\/54765064-6459-47f9-b698-396caaa1d970-0000\/executors\/06bee625-84ce-4b88-85d6-a79809b9ce15\/runs\/ad011215-ee09-4793-9cfd-09dfd6675be3"}]},"err":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT\/slaves\/54765064-6459-47f9-b698-396caaa1d970-S0\/frameworks\/54765064-6459-47f9-b698-396caaa1d970-0000\/executors\/06bee625-84ce-4b88-85d6-a79809b9ce15\/runs\/ad011215-ee09-4793-9cfd-09dfd6675be3\/stderr","type":"PATH"},"out":{"path":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT\/slaves\/54765064-6459-47f9-b698-396caaa1d970-S0\/frameworks\/54765064-6459-47f9-b698-396caaa1d970-0000\/executors\/06bee625-84ce-4b88-85d6-a79809b9ce15\/runs\/ad011215-ee09-4793-9cfd-09dfd6675be3\/stdout","type":"PATH"},"user":"mesos","working_directory":"\/tmp\/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT\/slaves\/54765064-6459-47f9-b698-396caaa1d970-S0\/frameworks\/54765064-6459-47f9-b698-396caaa1d970-0000\/executors\/06bee625-84ce-4b88-85d6-a79809b9ce15\/runs\/ad011215-ee09-4793-9cfd-09dfd6675be3"}" --pipe_read="22" --pipe_write="40" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_YM8wYI/containers/ad011215-ee09-4793-9cfd-09dfd6675be3" --unshare_namespace_mnt="false"'
I1216 05:12:41.924401 29730 launcher.cpp:133] Forked child with pid '10398' for container 'ad011215-ee09-4793-9cfd-09dfd6675be3'
I1216 05:12:41.928544 29743 fetcher.cpp:349] Starting to fetch URIs for container: ad011215-ee09-4793-9cfd-09dfd6675be3, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT/slaves/54765064-6459-47f9-b698-396caaa1d970-S0/frameworks/54765064-6459-47f9-b698-396caaa1d970-0000/executors/06bee625-84ce-4b88-85d6-a79809b9ce15/runs/ad011215-ee09-4793-9cfd-09dfd6675be3
I1216 05:12:42.116883 29740 slave.cpp:3309] Got registration for executor '06bee625-84ce-4b88-85d6-a79809b9ce15' of framework 54765064-6459-47f9-b698-396caaa1d970-0000 from executor(1)@172.17.0.2:37121
I1216 05:12:42.120445 29735 slave.cpp:2254] Sending queued task '06bee625-84ce-4b88-85d6-a79809b9ce15' to executor '06bee625-84ce-4b88-85d6-a79809b9ce15' of framework 54765064-6459-47f9-b698-396caaa1d970-0000 at executor(1)@172.17.0.2:37121
I1216 05:12:42.139873 29740 slave.cpp:3741] Handling status update TASK_RUNNING (UUID: 831a21ab-393f-470a-b401-f0f359d6c003) for task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000 from executor(1)@172.17.0.2:37121
I1216 05:12:42.142531 29741 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 831a21ab-393f-470a-b401-f0f359d6c003) for task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.142609 29741 status_update_manager.cpp:500] Creating StatusUpdate stream for task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.143259 29741 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 831a21ab-393f-470a-b401-f0f359d6c003) for task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000 to the agent
I1216 05:12:42.143616 29740 slave.cpp:4182] Forwarding the update TASK_RUNNING (UUID: 831a21ab-393f-470a-b401-f0f359d6c003) for task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000 to master@172.17.0.2:50053
I1216 05:12:42.143923 29740 slave.cpp:4076] Status update manager successfully handled status update TASK_RUNNING (UUID: 831a21ab-393f-470a-b401-f0f359d6c003) for task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.143987 29740 slave.cpp:4092] Sending acknowledgement for status update TASK_RUNNING (UUID: 831a21ab-393f-470a-b401-f0f359d6c003) for task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000 to executor(1)@172.17.0.2:37121
I1216 05:12:42.144124 29739 master.cpp:5770] Status update TASK_RUNNING (UUID: 831a21ab-393f-470a-b401-f0f359d6c003) for task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000 from agent 54765064-6459-47f9-b698-396caaa1d970-S0 at slave(579)@172.17.0.2:50053 (e16bb4d6c206)
I1216 05:12:42.144220 29739 master.cpp:5832] Forwarding status update TASK_RUNNING (UUID: 831a21ab-393f-470a-b401-f0f359d6c003) for task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.144508 29739 master.cpp:7868] Updating the state of task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1216 05:12:42.144907 29737 sched.cpp:1031] Scheduler::statusUpdate took 174741ns
I1216 05:12:42.145550 29741 master.cpp:4878] Processing ACKNOWLEDGE call 831a21ab-393f-470a-b401-f0f359d6c003 for task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000 (default) at scheduler-d15f907c-6964-4a39-aa81-02fa1dad6db7@172.17.0.2:50053 on agent 54765064-6459-47f9-b698-396caaa1d970-S0
I1216 05:12:42.146147 29728 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 831a21ab-393f-470a-b401-f0f359d6c003) for task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.146497 29735 slave.cpp:3029] Status update manager successfully handled status update acknowledgement (UUID: 831a21ab-393f-470a-b401-f0f359d6c003) for task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.149492 29736 process.cpp:3679] Handling HTTP event for process 'slave(579)' with path: '/slave(579)/api/v1'
I1216 05:12:42.150671 29740 http.cpp:301] HTTP POST for /slave(579)/api/v1 from 172.17.0.2:34944
I1216 05:12:42.151413 29740 http.cpp:462] Processing call LAUNCH_NESTED_CONTAINER_SESSION
I1216 05:12:42.152015 29737 containerizer.cpp:1802] Starting nested container ad011215-ee09-4793-9cfd-09dfd6675be3.729a36d1-4f3b-4978-ad81-43c015d036ac
I1216 05:12:42.152356 29737 containerizer.cpp:1826] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT/slaves/54765064-6459-47f9-b698-396caaa1d970-S0/frameworks/54765064-6459-47f9-b698-396caaa1d970-0000/executors/06bee625-84ce-4b88-85d6-a79809b9ce15/runs/ad011215-ee09-4793-9cfd-09dfd6675be3/containers/729a36d1-4f3b-4978-ad81-43c015d036ac' to user 'mesos'
I1216 05:12:42.165248 29736 switchboard.cpp:567] Launching 'mesos-io-switchboard' with flags '--heartbeat_interval="30secs" --help="false" --socket_address="/tmp/mesos-io-switchboard-82d61e81-5c19-476f-a490-d417b695c698" --stderr_from_fd="65" --stderr_to_fd="2" --stdin_to_fd="44" --stdout_from_fd="45" --stdout_to_fd="1" --tty="false" --wait_for_connection="true"' for container ad011215-ee09-4793-9cfd-09dfd6675be3.729a36d1-4f3b-4978-ad81-43c015d036ac
I1216 05:12:42.168969 29736 switchboard.cpp:597] Created I/O switchboard server (pid: 10459) listening on socket file '/tmp/mesos-io-switchboard-82d61e81-5c19-476f-a490-d417b695c698' for container ad011215-ee09-4793-9cfd-09dfd6675be3.729a36d1-4f3b-4978-ad81-43c015d036ac
I1216 05:12:42.171605 29743 containerizer.cpp:1535] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"cat"},"environment":{},"err":{"fd":66,"type":"FD"},"in":{"fd":43,"type":"FD"},"out":{"fd":46,"type":"FD"},"user":"mesos"}" --pipe_read="44" --pipe_write="45" --runtime_directory="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_YM8wYI/containers/ad011215-ee09-4793-9cfd-09dfd6675be3/containers/729a36d1-4f3b-4978-ad81-43c015d036ac" --unshare_namespace_mnt="false"'
I1216 05:12:42.175088 29743 launcher.cpp:133] Forked child with pid '10461' for container 'ad011215-ee09-4793-9cfd-09dfd6675be3.729a36d1-4f3b-4978-ad81-43c015d036ac'
I1216 05:12:42.178885 29736 fetcher.cpp:349] Starting to fetch URIs for container: ad011215-ee09-4793-9cfd-09dfd6675be3.729a36d1-4f3b-4978-ad81-43c015d036ac, directory: /tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT/slaves/54765064-6459-47f9-b698-396caaa1d970-S0/frameworks/54765064-6459-47f9-b698-396caaa1d970-0000/executors/06bee625-84ce-4b88-85d6-a79809b9ce15/runs/ad011215-ee09-4793-9cfd-09dfd6675be3/containers/729a36d1-4f3b-4978-ad81-43c015d036ac
**** DEADLOCK DETECTED! ****
You are waiting on process __limiter__(597)@172.17.0.2:50053 that it is currently executing.
W1216 05:12:42.273535 29731 http.cpp:2721] Launch nested container session connection for container ad011215-ee09-4793-9cfd-09dfd6675be3.729a36d1-4f3b-4978-ad81-43c015d036ac closed
I1216 05:12:42.273905 29738 containerizer.cpp:2113] Destroying container ad011215-ee09-4793-9cfd-09dfd6675be3.729a36d1-4f3b-4978-ad81-43c015d036ac in RUNNING state
I1216 05:12:42.274278 29738 launcher.cpp:149] Asked to destroy container ad011215-ee09-4793-9cfd-09dfd6675be3.729a36d1-4f3b-4978-ad81-43c015d036ac
I1216 05:12:42.289587 29743 http.cpp:2706] Received EOF attach response for ad011215-ee09-4793-9cfd-09dfd6675be3.729a36d1-4f3b-4978-ad81-43c015d036ac
I1216 05:12:42.339066 29741 switchboard.cpp:855] I/O switchboard server process for container ad011215-ee09-4793-9cfd-09dfd6675be3.729a36d1-4f3b-4978-ad81-43c015d036ac has terminated (status=0)
I1216 05:12:42.339759 29736 containerizer.cpp:2476] Container ad011215-ee09-4793-9cfd-09dfd6675be3.729a36d1-4f3b-4978-ad81-43c015d036ac has exited
I1216 05:12:42.341683 29738 provisioner.cpp:324] Ignoring destroy request for unknown container ad011215-ee09-4793-9cfd-09dfd6675be3.729a36d1-4f3b-4978-ad81-43c015d036ac
I1216 05:12:42.341919 29730 containerizer.cpp:2392] Checkpointing termination state to nested container's runtime directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_YM8wYI/containers/ad011215-ee09-4793-9cfd-09dfd6675be3/containers/729a36d1-4f3b-4978-ad81-43c015d036ac/termination'
I1216 05:12:42.342905 29709 sched.cpp:2008] Asked to stop the driver
I1216 05:12:42.343116 29737 sched.cpp:1193] Stopping framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.343502 29740 master.cpp:7288] Processing TEARDOWN call for framework 54765064-6459-47f9-b698-396caaa1d970-0000 (default) at scheduler-d15f907c-6964-4a39-aa81-02fa1dad6db7@172.17.0.2:50053
I1216 05:12:42.343552 29740 master.cpp:7300] Removing framework 54765064-6459-47f9-b698-396caaa1d970-0000 (default) at scheduler-d15f907c-6964-4a39-aa81-02fa1dad6db7@172.17.0.2:50053
I1216 05:12:42.343719 29731 hierarchical.cpp:391] Deactivated framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.343804 29740 master.cpp:7868] Updating the state of task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I1216 05:12:42.343837 29742 slave.cpp:2582] Asked to shut down framework 54765064-6459-47f9-b698-396caaa1d970-0000 by master@172.17.0.2:50053
I1216 05:12:42.343884 29742 slave.cpp:2607] Shutting down framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.343947 29742 slave.cpp:4987] Shutting down executor '06bee625-84ce-4b88-85d6-a79809b9ce15' of framework 54765064-6459-47f9-b698-396caaa1d970-0000 at executor(1)@172.17.0.2:37121
I1216 05:12:42.344583 29740 master.cpp:7964] Removing task 06bee625-84ce-4b88-85d6-a79809b9ce15 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 54765064-6459-47f9-b698-396caaa1d970-0000 on agent 54765064-6459-47f9-b698-396caaa1d970-S0 at slave(579)@172.17.0.2:50053 (e16bb4d6c206)
I1216 05:12:42.344784 29732 containerizer.cpp:2113] Destroying container ad011215-ee09-4793-9cfd-09dfd6675be3 in RUNNING state
I1216 05:12:42.344923 29733 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 54765064-6459-47f9-b698-396caaa1d970-S0 from framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.345051 29732 launcher.cpp:149] Asked to destroy container ad011215-ee09-4793-9cfd-09dfd6675be3
I1216 05:12:42.345293 29742 hierarchical.cpp:342] Removed framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.357872 29737 slave.cpp:4310] Got exited event for executor(1)@172.17.0.2:37121
I1216 05:12:42.441093 29742 containerizer.cpp:2476] Container ad011215-ee09-4793-9cfd-09dfd6675be3 has exited
I1216 05:12:42.443549 29730 provisioner.cpp:324] Ignoring destroy request for unknown container ad011215-ee09-4793-9cfd-09dfd6675be3
I1216 05:12:42.444947 29730 slave.cpp:4673] Executor '06bee625-84ce-4b88-85d6-a79809b9ce15' of framework 54765064-6459-47f9-b698-396caaa1d970-0000 terminated with signal Killed
I1216 05:12:42.445014 29730 slave.cpp:4773] Cleaning up executor '06bee625-84ce-4b88-85d6-a79809b9ce15' of framework 54765064-6459-47f9-b698-396caaa1d970-0000 at executor(1)@172.17.0.2:37121
I1216 05:12:42.445446 29741 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT/slaves/54765064-6459-47f9-b698-396caaa1d970-S0/frameworks/54765064-6459-47f9-b698-396caaa1d970-0000/executors/06bee625-84ce-4b88-85d6-a79809b9ce15/runs/ad011215-ee09-4793-9cfd-09dfd6675be3' for gc 6.99999484583407days in the future
I1216 05:12:42.445648 29730 slave.cpp:4861] Cleaning up framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.445665 29741 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT/slaves/54765064-6459-47f9-b698-396caaa1d970-S0/frameworks/54765064-6459-47f9-b698-396caaa1d970-0000/executors/06bee625-84ce-4b88-85d6-a79809b9ce15' for gc 6.99999484311407days in the future
I1216 05:12:42.445899 29737 status_update_manager.cpp:285] Closing status update streams for framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.445960 29737 status_update_manager.cpp:531] Cleaning up status update stream for task 06bee625-84ce-4b88-85d6-a79809b9ce15 of framework 54765064-6459-47f9-b698-396caaa1d970-0000
I1216 05:12:42.446024 29741 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionDisconnected_1_FTqIXT/slaves/54765064-6459-47f9-b698-396caaa1d970-S0/frameworks/54765064-6459-47f9-b698-396caaa1d970-0000' for gc 6.99999483974519days in the future
I1216 05:12:42.446039 29730 slave.cpp:796] Agent terminating
I1216 05:12:42.446347 29732 master.cpp:1258] Agent 54765064-6459-47f9-b698-396caaa1d970-S0 at slave(579)@172.17.0.2:50053 (e16bb4d6c206) disconnected
I1216 05:12:42.446382 29732 master.cpp:2978] Disconnecting agent 54765064-6459-47f9-b698-396caaa1d970-S0 at slave(579)@172.17.0.2:50053 (e16bb4d6c206)
I1216 05:12:42.446468 29732 master.cpp:2997] Deactivating agent 54765064-6459-47f9-b698-396caaa1d970-S0 at slave(579)@172.17.0.2:50053 (e16bb4d6c206)
I1216 05:12:42.446748 29740 hierarchical.cpp:589] Agent 54765064-6459-47f9-b698-396caaa1d970-S0 deactivated
I1216 05:12:42.453541 29728 master.cpp:1097] Master terminating
I1216 05:12:42.454391 29730 hierarchical.cpp:522] Removed agent 54765064-6459-47f9-b698-396caaa1d970-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionDisconnected/1 (605 ms)
[ RUN ] ContentType/AgentAPITest.AttachContainerOutputFailure/0
I1216 05:12:42.469635 29738 slave.cpp:209] Mesos agent started on (580)@172.17.0.2:50053
I1216 05:12:42.469663 29738 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_hE99tV/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_hE99tV/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_hE99tV/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_hE99tV" --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_I8jjU4"
I1216 05:12:42.470250 29738 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_hE99tV/credential'
I1216 05:12:42.470438 29738 slave.cpp:352] Agent using credential for: test-principal
I1216 05:12:42.470464 29738 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_hE99tV/http_credentials'
I1216 05:12:42.470755 29738 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1216 05:12:42.471114 29738 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1216 05:12:42.472693 29738 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1216 05:12:42.472800 29738 slave.cpp:547] Agent attributes: [ ]
I1216 05:12:42.472828 29738 slave.cpp:552] Agent hostname: e16bb4d6c206
I1216 05:12:42.474913 29730 state.cpp:57] Recovering state from '/tmp/ContentType_AgentAPITest_AttachContainerOutputFailure_0_I8jjU4/meta'
I1216 05:12:42.475271 29732 status_update_manager.cpp:203] Recovering status update manager
I1216 05:12:42.475774 29728 slave.cpp:5408] Finished recovery
I1216 05:12:42.476366 29728 slave.cpp:5582] Querying resource estimator for oversubscribable resources
I1216 05:12:42.476711 29738 slave.cpp:5596] Received oversubscribable resources {} from the resource estimator
Build timed out (after 180 minutes). Marking the build as failed.
Build was aborted
++ docker rmi mesos-1481862746-18078
Error response from daemon: conflict: unable to remove repository reference "mesos-1481862746-18078" (must force) - container e16bb4d6c206 is using its referenced image 1aa212852b11
Jenkins build is back to normal : mesos-reviewbot #16470
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/16470/changes>