You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@mesos.apache.org by "Benjamin Bannier (JIRA)" <ji...@apache.org> on 2017/02/06 19:24:41 UTC
[jira] [Commented] (MESOS-6913)
AgentAPIStreamingTest.AttachInputToNestedContainerSession fails on Mac OS.
[ https://issues.apache.org/jira/browse/MESOS-6913?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15854584#comment-15854584 ]
Benjamin Bannier commented on MESOS-6913:
-----------------------------------------
This is still failing for me as of {{30f7f3e1965885406d6d3e49348bee7e7d0df9d5}},
{noformat}
[ RUN ] ContentType/AgentAPIStreamingTest.AttachInputToNestedContainerSession/1
I0206 20:08:32.666107 3349771200 process.cpp:3055] Spawned process files@192.168.1.5:52882
I0206 20:08:32.666124 256000000 process.cpp:3065] Resuming files@192.168.1.5:52882 at 2017-02-06 19:08:32.666141952+00:00
I0206 20:08:32.666283 253853696 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.666297088+00:00
I0206 20:08:32.666293 256536576 process.cpp:3065] Resuming metrics@192.168.1.5:52882 at 2017-02-06 19:08:32.666309120+00:00
I0206 20:08:32.666494 3349771200 process.cpp:3055] Spawned process hierarchical-allocator(62)@192.168.1.5:52882
I0206 20:08:32.666499 256536576 process.cpp:3065] Resuming hierarchical-allocator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.666512896+00:00
I0206 20:08:32.666527 3349771200 cluster.cpp:160] Creating default 'local' authorizer
I0206 20:08:32.666630 3349771200 process.cpp:3055] Spawned process local-authorizer(123)@192.168.1.5:52882
I0206 20:08:32.666672 256536576 process.cpp:3065] Resuming local-authorizer(123)@192.168.1.5:52882 at 2017-02-06 19:08:32.666689024+00:00
I0206 20:08:32.666692 254926848 process.cpp:3065] Resuming standalone-master-detector(184)@192.168.1.5:52882 at 2017-02-06 19:08:32.666707968+00:00
I0206 20:08:32.666728 3349771200 process.cpp:3055] Spawned process standalone-master-detector(184)@192.168.1.5:52882
I0206 20:08:32.666848 3349771200 process.cpp:3055] Spawned process in-memory-storage(62)@192.168.1.5:52882
I0206 20:08:32.666854 254926848 process.cpp:3065] Resuming in-memory-storage(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.666865920+00:00
I0206 20:08:32.666960 256000000 process.cpp:3065] Resuming metrics@192.168.1.5:52882 at 2017-02-06 19:08:32.666972928+00:00
I0206 20:08:32.667125 3349771200 process.cpp:3055] Spawned process registrar(62)@192.168.1.5:52882
I0206 20:08:32.667134 256000000 process.cpp:3065] Resuming registrar(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.667143936+00:00
I0206 20:08:32.667261 256000000 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.667273984+00:00
I0206 20:08:32.667476 252780544 process.cpp:3065] Resuming metrics@192.168.1.5:52882 at 2017-02-06 19:08:32.667490048+00:00
I0206 20:08:32.668769 253317120 process.cpp:3065] Resuming standalone-master-detector(184)@192.168.1.5:52882 at 2017-02-06 19:08:32.668783104+00:00
I0206 20:08:32.668931 3349771200 process.cpp:3055] Spawned process master@192.168.1.5:52882
I0206 20:08:32.668936 253853696 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:32.668948992+00:00
I0206 20:08:32.668972 253853696 master.cpp:383] Master 7740c960-cc1e-4fb0-8d23-e2c70df099ac (192.168.1.5) started on 192.168.1.5:52882
I0206 20:08:32.669042 252780544 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.669056000+00:00
I0206 20:08:32.669046 253317120 process.cpp:3065] Resuming __latch__(916)@192.168.1.5:52882 at 2017-02-06 19:08:32.669058048+00:00
I0206 20:08:32.669095 3349771200 process.cpp:3055] Spawned process __latch__(916)@192.168.1.5:52882
I0206 20:08:32.669133 252780544 process.cpp:3065] Resuming __waiter__(448)@192.168.1.5:52882 at 2017-02-06 19:08:32.669145088+00:00
I0206 20:08:32.669188 3349771200 process.cpp:3055] Spawned process __waiter__(448)@192.168.1.5:52882
I0206 20:08:32.669008 253853696 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="/private/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/jXTfKU/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="/usr/local/share/mesos/webui" --work_dir="/private/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/jXTfKU/master" --zk_session_timeout="10secs"
I0206 20:08:32.669265 253853696 master.cpp:435] Master only allowing authenticated frameworks to register
I0206 20:08:32.669278 253853696 master.cpp:449] Master only allowing authenticated agents to register
I0206 20:08:32.669288 253853696 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0206 20:08:32.669297 253853696 credentials.hpp:37] Loading credentials for authentication from '/private/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/jXTfKU/credentials'
I0206 20:08:32.669544 253853696 master.cpp:507] Using default 'crammd5' authenticator
I0206 20:08:32.669670 253853696 process.cpp:3055] Spawned process crammd5-authenticator(62)@192.168.1.5:52882
I0206 20:08:32.669682 252780544 process.cpp:3065] Resuming crammd5-authenticator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.669694976+00:00
I0206 20:08:32.669697 253853696 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0206 20:08:32.669781 253853696 process.cpp:3055] Spawned process __basic_authenticator__(306)@192.168.1.5:52882
I0206 20:08:32.669788 256000000 process.cpp:3065] Resuming __basic_authenticator__(306)@192.168.1.5:52882 at 2017-02-06 19:08:32.669799936+00:00
I0206 20:08:32.669876 253853696 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0206 20:08:32.669870 252780544 process.cpp:3065] Resuming __authentication_router__(1)@192.168.1.5:52882 at 2017-02-06 19:08:32.669880064+00:00
I0206 20:08:32.669955 253853696 process.cpp:3055] Spawned process __basic_authenticator__(307)@192.168.1.5:52882
I0206 20:08:32.669998 254926848 process.cpp:3065] Resuming __basic_authenticator__(307)@192.168.1.5:52882 at 2017-02-06 19:08:32.670009088+00:00
I0206 20:08:32.670003 256536576 process.cpp:3065] Resuming __authentication_router__(1)@192.168.1.5:52882 at 2017-02-06 19:08:32.670013952+00:00
I0206 20:08:32.670058 253853696 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0206 20:08:32.670101 252780544 process.cpp:3065] Resuming __basic_authenticator__(308)@192.168.1.5:52882 at 2017-02-06 19:08:32.670113024+00:00
I0206 20:08:32.670131 253853696 process.cpp:3055] Spawned process __basic_authenticator__(308)@192.168.1.5:52882
I0206 20:08:32.670166 255463424 process.cpp:3065] Resuming __authentication_router__(1)@192.168.1.5:52882 at 2017-02-06 19:08:32.670179072+00:00
I0206 20:08:32.670209 253853696 master.cpp:587] Authorization enabled
I0206 20:08:32.670289 254390272 process.cpp:3065] Resuming __basic_authenticator__(303)@192.168.1.5:52882 at 2017-02-06 19:08:32.670298880+00:00
I0206 20:08:32.670313 254390272 process.cpp:3170] Cleaning up __basic_authenticator__(303)@192.168.1.5:52882
I0206 20:08:32.670331 254926848 process.cpp:3065] Resuming hierarchical-allocator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.670342912+00:00
I0206 20:08:32.670348 256536576 process.cpp:3065] Resuming whitelist(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.670359040+00:00
I0206 20:08:32.670354 252780544 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.670363904+00:00
I0206 20:08:32.670373 256536576 whitelist_watcher.cpp:77] No whitelist given
I0206 20:08:32.670375 253853696 process.cpp:3055] Spawned process whitelist(62)@192.168.1.5:52882
I0206 20:08:32.670387 254926848 hierarchical.cpp:161] Initialized hierarchical allocator process
I0206 20:08:32.670593 256000000 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.670603008+00:00
I0206 20:08:32.671182 253317120 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.671194112+00:00
I0206 20:08:32.671279 253317120 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.671293952+00:00
I0206 20:08:32.672313 256000000 process.cpp:3065] Resuming standalone-master-detector(184)@192.168.1.5:52882 at 2017-02-06 19:08:32.672325888+00:00
I0206 20:08:32.672456 256000000 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:32.672468992+00:00
I0206 20:08:32.672515 256000000 master.cpp:2123] Elected as the leading master!
I0206 20:08:32.672533 256000000 master.cpp:1645] Recovering from registrar
I0206 20:08:32.672590 252780544 process.cpp:3065] Resuming registrar(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.672602880+00:00
I0206 20:08:32.672638 252780544 registrar.cpp:329] Recovering registrar
I0206 20:08:32.672684 255463424 process.cpp:3065] Resuming standalone-master-detector(184)@192.168.1.5:52882 at 2017-02-06 19:08:32.672698112+00:00
I0206 20:08:32.672693 253317120 process.cpp:3065] Resuming in-memory-storage(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.672705024+00:00
I0206 20:08:32.672813 253853696 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.672826112+00:00
I0206 20:08:32.672818 256000000 process.cpp:3065] Resuming __latch__(917)@192.168.1.5:52882 at 2017-02-06 19:08:32.672830976+00:00
I0206 20:08:32.672870 252780544 process.cpp:3055] Spawned process __latch__(917)@192.168.1.5:52882
I0206 20:08:32.672950 256000000 process.cpp:3065] Resuming __latch__(917)@192.168.1.5:52882 at 2017-02-06 19:08:32.672963072+00:00
I0206 20:08:32.672984 256000000 process.cpp:3170] Cleaning up __latch__(917)@192.168.1.5:52882
I0206 20:08:32.673043 255463424 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.673054976+00:00
I0206 20:08:32.673053 254926848 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.673063936+00:00
I0206 20:08:32.673079 252780544 registrar.cpp:362] Successfully fetched the registry (0B) in 418816ns
I0206 20:08:32.673218 252780544 registrar.cpp:461] Applied 1 operations in 61us; attempting to update the registry
I0206 20:08:32.673349 253853696 process.cpp:3065] Resuming in-memory-storage(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.673361920+00:00
I0206 20:08:32.673569 256536576 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.673582080+00:00
I0206 20:08:32.673604 253853696 process.cpp:3065] Resuming __latch__(918)@192.168.1.5:52882 at 2017-02-06 19:08:32.673615872+00:00
I0206 20:08:32.673645 252780544 process.cpp:3055] Spawned process __latch__(918)@192.168.1.5:52882
I0206 20:08:32.673753 256000000 process.cpp:3065] Resuming __latch__(918)@192.168.1.5:52882 at 2017-02-06 19:08:32.673765888+00:00
I0206 20:08:32.673789 256000000 process.cpp:3170] Cleaning up __latch__(918)@192.168.1.5:52882
I0206 20:08:32.673868 253853696 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.673881088+00:00
I0206 20:08:32.673877 254390272 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.673888000+00:00
I0206 20:08:32.673885 252780544 registrar.cpp:506] Successfully updated the registry in 594944ns
I0206 20:08:32.674001 252780544 registrar.cpp:392] Successfully recovered registrar
I0206 20:08:32.674131 256000000 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:32.674144000+00:00
I0206 20:08:32.674235 252780544 process.cpp:3065] Resuming __latch__(916)@192.168.1.5:52882 at 2017-02-06 19:08:32.674246912+00:00
I0206 20:08:32.674263 252780544 process.cpp:3170] Cleaning up __latch__(916)@192.168.1.5:52882
I0206 20:08:32.674325 256536576 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.674339072+00:00
I0206 20:08:32.674340 255463424 process.cpp:3065] Resuming hierarchical-allocator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.674373888+00:00
I0206 20:08:32.674350 256000000 master.cpp:1761] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0206 20:08:32.674355 253853696 process.cpp:3065] Resuming __waiter__(448)@192.168.1.5:52882 at 2017-02-06 19:08:32.674394112+00:00
I0206 20:08:32.674407 254926848 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.674416128+00:00
I0206 20:08:32.674450 255463424 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover
I0206 20:08:32.674497 253853696 process.cpp:3170] Cleaning up __waiter__(448)@192.168.1.5:52882
I0206 20:08:32.674548 252780544 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.674560000+00:00
I0206 20:08:32.674573 3349771200 clock.cpp:329] Clock paused at 2017-02-06 19:08:32.674565888+00:00
I0206 20:08:32.674618 3349771200 clock.cpp:359] Clock resumed at 2017-02-06 19:08:32.674565888+00:00
I0206 20:08:32.676337 3349771200 process.cpp:3055] Spawned process fetcher(62)@192.168.1.5:52882
I0206 20:08:32.676345 253853696 process.cpp:3065] Resuming fetcher(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.676357120+00:00
I0206 20:08:32.676494 3349771200 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix
I0206 20:08:32.676874 3349771200 process.cpp:3055] Spawned process copy-provisioner-backend(62)@192.168.1.5:52882
I0206 20:08:32.676882 253317120 process.cpp:3065] Resuming copy-provisioner-backend(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.676893952+00:00
I0206 20:08:32.676915 3349771200 provisioner.cpp:249] Using default backend 'copy'
I0206 20:08:32.677006 256000000 process.cpp:3065] Resuming metrics@192.168.1.5:52882 at 2017-02-06 19:08:32.677020928+00:00
I0206 20:08:32.677012 252780544 process.cpp:3065] Resuming mesos-provisioner(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.677025024+00:00
I0206 20:08:32.677047 3349771200 process.cpp:3055] Spawned process mesos-provisioner(62)@192.168.1.5:52882
I0206 20:08:32.677271 3349771200 process.cpp:3055] Spawned process posix-filesystem-isolator(62)@192.168.1.5:52882
I0206 20:08:32.677279 253317120 process.cpp:3065] Resuming posix-filesystem-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.677293056+00:00
I0206 20:08:32.677364 3349771200 process.cpp:3055] Spawned process posix-cpu-isolator(62)@192.168.1.5:52882
I0206 20:08:32.677368 252780544 process.cpp:3065] Resuming posix-cpu-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.677379072+00:00
I0206 20:08:32.677426 256536576 process.cpp:3065] Resuming posix-mem-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.677440000+00:00
I0206 20:08:32.677454 3349771200 process.cpp:3055] Spawned process posix-mem-isolator(62)@192.168.1.5:52882
I0206 20:08:32.677539 3349771200 process.cpp:3055] Spawned process sandbox-logger(62)@192.168.1.5:52882
I0206 20:08:32.677546 252780544 process.cpp:3065] Resuming sandbox-logger(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.677558016+00:00
I0206 20:08:32.677765 3349771200 process.cpp:3055] Spawned process (62)@192.168.1.5:52882
I0206 20:08:32.677773 256000000 process.cpp:3065] Resuming (62)@192.168.1.5:52882 at 2017-02-06 19:08:32.677784064+00:00
I0206 20:08:32.677989 253853696 process.cpp:3065] Resuming mesos-containerizer(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.677999104+00:00
I0206 20:08:32.677985 253317120 process.cpp:3065] Resuming metrics@192.168.1.5:52882 at 2017-02-06 19:08:32.677999104+00:00
I0206 20:08:32.678025 3349771200 process.cpp:3055] Spawned process mesos-containerizer(62)@192.168.1.5:52882
I0206 20:08:32.678772 3349771200 process.cpp:3055] Spawned process standalone-master-detector(185)@192.168.1.5:52882
I0206 20:08:32.678782 252780544 process.cpp:3065] Resuming standalone-master-detector(185)@192.168.1.5:52882 at 2017-02-06 19:08:32.678796032+00:00
I0206 20:08:32.680348 3349771200 cluster.cpp:446] Creating default 'local' authorizer
I0206 20:08:32.680413 3349771200 process.cpp:3055] Spawned process local-authorizer(124)@192.168.1.5:52882
I0206 20:08:32.680423 256000000 process.cpp:3065] Resuming local-authorizer(124)@192.168.1.5:52882 at 2017-02-06 19:08:32.680436992+00:00
I0206 20:08:32.680528 3349771200 process.cpp:3055] Spawned process agent-garbage-collector(62)@192.168.1.5:52882
I0206 20:08:32.680534 254926848 process.cpp:3065] Resuming agent-garbage-collector(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.680549120+00:00
I0206 20:08:32.680719 3349771200 process.cpp:3055] Spawned process status-update-manager(62)@192.168.1.5:52882
I0206 20:08:32.680727 256000000 process.cpp:3065] Resuming status-update-manager(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.680740096+00:00
I0206 20:08:32.680929 3349771200 process.cpp:3055] Spawned process __limiter__(62)@192.168.1.5:52882
I0206 20:08:32.680938 253853696 process.cpp:3065] Resuming __limiter__(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.680950016+00:00
I0206 20:08:32.681072 253317120 process.cpp:3065] Resuming metrics@192.168.1.5:52882 at 2017-02-06 19:08:32.681086976+00:00
I0206 20:08:32.681500 3349771200 process.cpp:3055] Spawned process slave(62)@192.168.1.5:52882
I0206 20:08:32.681507 256000000 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.681520128+00:00
I0206 20:08:32.681537 256000000 slave.cpp:211] Mesos agent started on (62)@192.168.1.5:52882
I0206 20:08:32.681556 256000000 slave.cpp:212] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_zcmFFK/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="/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/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="/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_zcmFFK/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="/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_zcmFFK/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/Users/bbannier/src/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --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]" --runtime_dir="/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_zcmFFK" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --version="false" --work_dir="/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m"
I0206 20:08:32.681897 256000000 credentials.hpp:86] Loading credential for authentication from '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_zcmFFK/credential'
I0206 20:08:32.682013 3349771200 process.cpp:3055] Spawned process __latch__(919)@192.168.1.5:52882
I0206 20:08:32.682008 252780544 process.cpp:3065] Resuming __latch__(919)@192.168.1.5:52882 at 2017-02-06 19:08:32.682022912+00:00
I0206 20:08:32.682006 253853696 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.682025984+00:00
I0206 20:08:32.682039 256000000 slave.cpp:354] Agent using credential for: test-principal
I0206 20:08:32.682078 256000000 credentials.hpp:37] Loading credentials for authentication from '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_zcmFFK/http_credentials'
I0206 20:08:32.682291 256000000 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0206 20:08:32.682358 256000000 process.cpp:3055] Spawned process __basic_authenticator__(309)@192.168.1.5:52882
I0206 20:08:32.682366 254390272 process.cpp:3065] Resuming __basic_authenticator__(309)@192.168.1.5:52882 at 2017-02-06 19:08:32.682381056+00:00
I0206 20:08:32.682440 256000000 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0206 20:08:32.682441 252780544 process.cpp:3065] Resuming __authentication_router__(1)@192.168.1.5:52882 at 2017-02-06 19:08:32.682456064+00:00
I0206 20:08:32.682518 256000000 process.cpp:3055] Spawned process __basic_authenticator__(310)@192.168.1.5:52882
I0206 20:08:32.682557 254390272 process.cpp:3065] Resuming __basic_authenticator__(310)@192.168.1.5:52882 at 2017-02-06 19:08:32.682567936+00:00
I0206 20:08:32.682566 255463424 process.cpp:3065] Resuming __authentication_router__(1)@192.168.1.5:52882 at 2017-02-06 19:08:32.682579968+00:00
I0206 20:08:32.682624 253853696 process.cpp:3065] Resuming noop-resource-estimator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.682635008+00:00
I0206 20:08:32.682675 256000000 process.cpp:3055] Spawned process noop-resource-estimator(62)@192.168.1.5:52882
I0206 20:08:32.682765 256000000 process.cpp:3055] Spawned process qos-noop-controller(62)@192.168.1.5:52882
I0206 20:08:32.682771 253317120 process.cpp:3065] Resuming qos-noop-controller(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.682781952+00:00
I0206 20:08:32.682978 3349771200 process.cpp:3055] Spawned process standalone-master-detector(186)@192.168.1.5:52882
I0206 20:08:32.682987 253853696 process.cpp:3065] Resuming standalone-master-detector(186)@192.168.1.5:52882 at 2017-02-06 19:08:32.683000064+00:00
I0206 20:08:32.683292 252780544 process.cpp:3065] Resuming metrics@192.168.1.5:52882 at 2017-02-06 19:08:32.683305984+00:00
I0206 20:08:32.683321 3349771200 sched.cpp:232] Version: 1.2.0
I0206 20:08:32.683389 3349771200 process.cpp:3055] Spawned process scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882
I0206 20:08:32.683399 255463424 process.cpp:3065] Resuming scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882 at 2017-02-06 19:08:32.683419904+00:00
I0206 20:08:32.683491 253853696 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.683505920+00:00
I0206 20:08:32.683498 254926848 process.cpp:3065] Resuming __latch__(920)@192.168.1.5:52882 at 2017-02-06 19:08:32.683511040+00:00
I0206 20:08:32.683527 254390272 process.cpp:3065] Resuming standalone-master-detector(186)@192.168.1.5:52882 at 2017-02-06 19:08:32.683536896+00:00
I0206 20:08:32.683542 3349771200 process.cpp:3055] Spawned process __latch__(920)@192.168.1.5:52882
I0206 20:08:32.683653 3349771200 process.cpp:3055] Spawned process __waiter__(449)@192.168.1.5:52882
I0206 20:08:32.683631 256000000 slave.cpp:541] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0206 20:08:32.683660 253853696 process.cpp:3065] Resuming __waiter__(449)@192.168.1.5:52882 at 2017-02-06 19:08:32.683672064+00:00
I0206 20:08:32.683666 255463424 process.cpp:3065] Resuming scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882 at 2017-02-06 19:08:32.683678976+00:00
I0206 20:08:32.683694 256000000 slave.cpp:549] Agent attributes: [ ]
I0206 20:08:32.683727 256000000 slave.cpp:554] Agent hostname: 192.168.1.5
I0206 20:08:32.683743 255463424 sched.cpp:336] New master detected at master@192.168.1.5:52882
I0206 20:08:32.683784 252780544 process.cpp:3065] Resuming status-update-manager(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.683796992+00:00
I0206 20:08:32.683800 255463424 sched.cpp:407] Authenticating with master master@192.168.1.5:52882
I0206 20:08:32.683847 255463424 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0206 20:08:32.683850 252780544 status_update_manager.cpp:177] Pausing sending status updates
I0206 20:08:32.683914 255463424 process.cpp:3055] Spawned process crammd5-authenticatee(123)@192.168.1.5:52882
I0206 20:08:32.683924 253853696 process.cpp:3065] Resuming crammd5-authenticatee(123)@192.168.1.5:52882 at 2017-02-06 19:08:32.683934976+00:00
I0206 20:08:32.683930 253317120 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.683942912+00:00
I0206 20:08:32.684006 253853696 authenticatee.cpp:121] Creating new client SASL connection
I0206 20:08:32.684022 252780544 process.cpp:3065] Resuming standalone-master-detector(186)@192.168.1.5:52882 at 2017-02-06 19:08:32.684034048+00:00
I0206 20:08:32.684062 253317120 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:32.684073984+00:00
I0206 20:08:32.684151 253317120 master.cpp:7103] Authenticating scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882
I0206 20:08:32.684211 253853696 process.cpp:3065] Resuming crammd5-authenticator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.684225024+00:00
I0206 20:08:32.684258 253853696 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(123)@192.168.1.5:52882
I0206 20:08:32.684260 252780544 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.684272896+00:00
I0206 20:08:32.684324 253853696 process.cpp:3055] Spawned process crammd5-authenticator-session(123)@192.168.1.5:52882
I0206 20:08:32.684334 255463424 process.cpp:3065] Resuming crammd5-authenticator-session(123)@192.168.1.5:52882 at 2017-02-06 19:08:32.684347904+00:00
I0206 20:08:32.684391 253317120 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.684403968+00:00
I0206 20:08:32.684407 255463424 authenticator.cpp:98] Creating new server SASL connection
I0206 20:08:32.684500 253853696 process.cpp:3065] Resuming crammd5-authenticatee(123)@192.168.1.5:52882 at 2017-02-06 19:08:32.684513024+00:00
I0206 20:08:32.684553 253853696 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0206 20:08:32.684582 253853696 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0206 20:08:32.684628 253853696 process.cpp:3065] Resuming crammd5-authenticator-session(123)@192.168.1.5:52882 at 2017-02-06 19:08:32.684640000+00:00
I0206 20:08:32.684636 254926848 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.684647936+00:00
I0206 20:08:32.684676 253853696 authenticator.cpp:204] Received SASL authentication start
I0206 20:08:32.684684 256000000 process.cpp:3055] Spawned process __async_executor__(62)@192.168.1.5:52882
I0206 20:08:32.684702 254390272 process.cpp:3065] Resuming __async_executor__(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.684715008+00:00
I0206 20:08:32.684736 253853696 authenticator.cpp:326] Authentication requires more steps
I0206 20:08:32.684782 254390272 state.cpp:60] Recovering state from '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m/meta'
I0206 20:08:32.684769 255463424 process.cpp:3065] Resuming crammd5-authenticatee(123)@192.168.1.5:52882 at 2017-02-06 19:08:32.684790016+00:00
I0206 20:08:32.684844 255463424 authenticatee.cpp:259] Received SASL authentication step
I0206 20:08:32.684875 252780544 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.684888064+00:00
I0206 20:08:32.684901 254390272 process.cpp:3170] Cleaning up __async_executor__(62)@192.168.1.5:52882
I0206 20:08:32.684911 254926848 process.cpp:3065] Resuming crammd5-authenticator-session(123)@192.168.1.5:52882 at 2017-02-06 19:08:32.684922880+00:00
I0206 20:08:32.684937 256000000 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.684950016+00:00
I0206 20:08:32.684948 253853696 process.cpp:3065] Resuming status-update-manager(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.684960000+00:00
I0206 20:08:32.684959 254926848 authenticator.cpp:232] Received SASL authentication step
I0206 20:08:32.684999 254926848 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'flux.local' server FQDN: 'flux.local' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0206 20:08:32.685011 253853696 status_update_manager.cpp:203] Recovering status update manager
I0206 20:08:32.685003 256536576 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.685016064+00:00
I0206 20:08:32.685019 254926848 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0206 20:08:32.685070 254926848 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0206 20:08:32.685091 254926848 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'flux.local' server FQDN: 'flux.local' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0206 20:08:32.685109 254926848 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0206 20:08:32.685087 255463424 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.685099008+00:00
I0206 20:08:32.685123 254926848 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0206 20:08:32.685158 254926848 authenticator.cpp:318] Authentication success
I0206 20:08:32.685178 253317120 process.cpp:3065] Resuming mesos-containerizer(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.685188096+00:00
I0206 20:08:32.685220 253317120 containerizer.cpp:599] Recovering containerizer
I0206 20:08:32.685195 255463424 process.cpp:3065] Resuming crammd5-authenticatee(123)@192.168.1.5:52882 at 2017-02-06 19:08:32.685220096+00:00
I0206 20:08:32.685214 256000000 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:32.685222912+00:00
I0206 20:08:32.685258 254390272 process.cpp:3065] Resuming crammd5-authenticator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.685272064+00:00
I0206 20:08:32.685303 255463424 authenticatee.cpp:299] Authentication success
I0206 20:08:32.685322 256000000 master.cpp:7133] Successfully authenticated principal 'test-principal' at scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882
I0206 20:08:32.685343 254390272 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(123)@192.168.1.5:52882
I0206 20:08:32.685359 253853696 process.cpp:3065] Resuming scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882 at 2017-02-06 19:08:32.685371904+00:00
I0206 20:08:32.685382 252780544 process.cpp:3065] Resuming crammd5-authenticator-session(123)@192.168.1.5:52882 at 2017-02-06 19:08:32.685393920+00:00
I0206 20:08:32.685415 252780544 process.cpp:3170] Cleaning up crammd5-authenticator-session(123)@192.168.1.5:52882
I0206 20:08:32.685422 256536576 process.cpp:3065] Resuming posix-filesystem-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.685433088+00:00
I0206 20:08:32.685487 255463424 process.cpp:3065] Resuming crammd5-authenticatee(123)@192.168.1.5:52882 at 2017-02-06 19:08:32.685498880+00:00
I0206 20:08:32.685493 254926848 process.cpp:3065] Resuming posix-cpu-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.685506048+00:00
I0206 20:08:32.685521 255463424 process.cpp:3170] Cleaning up crammd5-authenticatee(123)@192.168.1.5:52882
I0206 20:08:32.685520 256536576 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.685529088+00:00
I0206 20:08:32.685549 256000000 process.cpp:3065] Resuming posix-mem-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.685561088+00:00
I0206 20:08:32.685593 254390272 process.cpp:3065] Resuming (62)@192.168.1.5:52882 at 2017-02-06 19:08:32.685605120+00:00
I0206 20:08:32.685653 253853696 sched.cpp:513] Successfully authenticated with master master@192.168.1.5:52882
I0206 20:08:32.685663 253317120 process.cpp:3055] Spawned process __collect__(306)@192.168.1.5:52882
I0206 20:08:32.685655 252780544 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.685669120+00:00
I0206 20:08:32.685672 253853696 sched.cpp:836] Sending SUBSCRIBE call to master@192.168.1.5:52882
I0206 20:08:32.685669 256000000 process.cpp:3065] Resuming __collect__(306)@192.168.1.5:52882 at 2017-02-06 19:08:32.685682944+00:00
I0206 20:08:32.685793 253853696 sched.cpp:869] Will retry registration in 640.282285ms if necessary
I0206 20:08:32.685789 252780544 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:32.685803008+00:00
I0206 20:08:32.685869 253317120 process.cpp:3065] Resuming mesos-containerizer(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.685882880+00:00
I0206 20:08:32.685889 256000000 process.cpp:3170] Cleaning up __collect__(306)@192.168.1.5:52882
I0206 20:08:32.685895 252780544 master.cpp:2736] Received SUBSCRIBE call for framework 'default' at scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882
I0206 20:08:32.685925 252780544 master.cpp:2159] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0206 20:08:32.685956 253853696 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.685967872+00:00
I0206 20:08:32.685968 253317120 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.685979904+00:00
I0206 20:08:32.685968 254926848 process.cpp:3065] Resuming mesos-provisioner(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.685981952+00:00
I0206 20:08:32.686002 255463424 process.cpp:3065] Resuming local-authorizer(123)@192.168.1.5:52882 at 2017-02-06 19:08:32.686012928+00:00
I0206 20:08:32.686199 256000000 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.686213888+00:00
I0206 20:08:32.686197 253853696 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:32.686219008+00:00
I0206 20:08:32.686230 252780544 process.cpp:3065] Resuming __collect__(307)@192.168.1.5:52882 at 2017-02-06 19:08:32.686244096+00:00
I0206 20:08:32.686274 253853696 master.cpp:2812] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0206 20:08:32.686275 254926848 process.cpp:3055] Spawned process __collect__(307)@192.168.1.5:52882
I0206 20:08:32.686326 252780544 process.cpp:3170] Cleaning up __collect__(307)@192.168.1.5:52882
I0206 20:08:32.686364 253317120 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.686376960+00:00
I0206 20:08:32.686374 254926848 provisioner.cpp:410] Provisioner recovery complete
I0206 20:08:32.686380 256536576 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.686393088+00:00
I0206 20:08:32.686417 256000000 process.cpp:3065] Resuming hierarchical-allocator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.686428928+00:00
I0206 20:08:32.686457 252780544 process.cpp:3065] Resuming mesos-containerizer(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.686469120+00:00
I0206 20:08:32.686517 256536576 process.cpp:3065] Resuming metrics@192.168.1.5:52882 at 2017-02-06 19:08:32.686529024+00:00
I0206 20:08:32.686537 255463424 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.686547968+00:00
I0206 20:08:32.686574 256000000 hierarchical.cpp:286] Added framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:32.686588 254926848 process.cpp:3065] Resuming scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882 at 2017-02-06 19:08:32.686599936+00:00
I0206 20:08:32.686668 254926848 sched.cpp:759] Framework registered with 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:32.686681 256000000 hierarchical.cpp:1820] No allocations performed
I0206 20:08:32.686698 255463424 slave.cpp:5499] Finished recovery
I0206 20:08:32.686703 256000000 hierarchical.cpp:1910] No inverse offers to send out!
I0206 20:08:32.686723 254926848 sched.cpp:773] Scheduler::registered took 36us
I0206 20:08:32.686736 256000000 hierarchical.cpp:1404] Performed allocation for 0 agents in 84us
I0206 20:08:32.687330 255463424 slave.cpp:5673] Querying resource estimator for oversubscribable resources
I0206 20:08:32.687328 253317120 process.cpp:3065] Resuming standalone-master-detector(185)@192.168.1.5:52882 at 2017-02-06 19:08:32.687342080+00:00
I0206 20:08:32.687418 254390272 process.cpp:3065] Resuming noop-resource-estimator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.687429120+00:00
I0206 20:08:32.687459 254926848 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.687472128+00:00
I0206 20:08:32.687546 255463424 process.cpp:3065] Resuming status-update-manager(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.687557888+00:00
I0206 20:08:32.687602 255463424 status_update_manager.cpp:177] Pausing sending status updates
I0206 20:08:32.687608 254926848 slave.cpp:931] New master detected at master@192.168.1.5:52882
I0206 20:08:32.687660 254926848 slave.cpp:966] Detecting new master
I0206 20:08:32.687721 253317120 process.cpp:3065] Resuming standalone-master-detector(185)@192.168.1.5:52882 at 2017-02-06 19:08:32.687732992+00:00
I0206 20:08:32.687742 254926848 slave.cpp:5687] Received oversubscribable resources {} from the resource estimator
I0206 20:08:32.691491 252780544 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.691504896+00:00
I0206 20:08:32.691539 252780544 slave.cpp:993] Authenticating with master master@192.168.1.5:52882
I0206 20:08:32.691572 252780544 slave.cpp:1004] Using default CRAM-MD5 authenticatee
I0206 20:08:32.691643 252780544 process.cpp:3055] Spawned process crammd5-authenticatee(124)@192.168.1.5:52882
I0206 20:08:32.691649 256000000 process.cpp:3065] Resuming crammd5-authenticatee(124)@192.168.1.5:52882 at 2017-02-06 19:08:32.691661056+00:00
I0206 20:08:32.691714 256000000 authenticatee.cpp:121] Creating new client SASL connection
I0206 20:08:32.691789 253317120 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:32.691802112+00:00
I0206 20:08:32.691869 253317120 master.cpp:7103] Authenticating slave(62)@192.168.1.5:52882
I0206 20:08:32.692044 252780544 process.cpp:3065] Resuming crammd5-authenticator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.692054016+00:00
I0206 20:08:32.692081 252780544 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(124)@192.168.1.5:52882
I0206 20:08:32.692137 252780544 process.cpp:3055] Spawned process crammd5-authenticator-session(124)@192.168.1.5:52882
I0206 20:08:32.692147 253853696 process.cpp:3065] Resuming crammd5-authenticator-session(124)@192.168.1.5:52882 at 2017-02-06 19:08:32.692158976+00:00
I0206 20:08:32.692226 253853696 authenticator.cpp:98] Creating new server SASL connection
I0206 20:08:32.692318 253317120 process.cpp:3065] Resuming crammd5-authenticatee(124)@192.168.1.5:52882 at 2017-02-06 19:08:32.692328960+00:00
I0206 20:08:32.692374 253317120 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0206 20:08:32.692402 253317120 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0206 20:08:32.692451 253317120 process.cpp:3065] Resuming crammd5-authenticator-session(124)@192.168.1.5:52882 at 2017-02-06 19:08:32.692462080+00:00
I0206 20:08:32.692493 253317120 authenticator.cpp:204] Received SASL authentication start
I0206 20:08:32.692538 253317120 authenticator.cpp:326] Authentication requires more steps
I0206 20:08:32.692579 253317120 process.cpp:3065] Resuming crammd5-authenticatee(124)@192.168.1.5:52882 at 2017-02-06 19:08:32.692590080+00:00
I0206 20:08:32.692620 253317120 authenticatee.cpp:259] Received SASL authentication step
I0206 20:08:32.692695 252780544 process.cpp:3065] Resuming crammd5-authenticator-session(124)@192.168.1.5:52882 at 2017-02-06 19:08:32.692709120+00:00
I0206 20:08:32.692749 252780544 authenticator.cpp:232] Received SASL authentication step
I0206 20:08:32.692772 252780544 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'flux.local' server FQDN: 'flux.local' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0206 20:08:32.692790 252780544 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0206 20:08:32.692811 252780544 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0206 20:08:32.692828 252780544 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'flux.local' server FQDN: 'flux.local' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0206 20:08:32.692842 252780544 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0206 20:08:32.692852 252780544 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0206 20:08:32.692868 252780544 authenticator.cpp:318] Authentication success
I0206 20:08:32.692925 253853696 process.cpp:3065] Resuming crammd5-authenticatee(124)@192.168.1.5:52882 at 2017-02-06 19:08:32.692936960+00:00
I0206 20:08:32.692931 256000000 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:32.692944896+00:00
I0206 20:08:32.692972 253853696 authenticatee.cpp:299] Authentication success
I0206 20:08:32.692980 253317120 process.cpp:3065] Resuming crammd5-authenticator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.692989952+00:00
I0206 20:08:32.692991 256000000 master.cpp:7133] Successfully authenticated principal 'test-principal' at slave(62)@192.168.1.5:52882
I0206 20:08:32.693007 255463424 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.693019904+00:00
I0206 20:08:32.693083 253317120 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(124)@192.168.1.5:52882
I0206 20:08:32.693127 254926848 process.cpp:3065] Resuming crammd5-authenticator-session(124)@192.168.1.5:52882 at 2017-02-06 19:08:32.693137920+00:00
I0206 20:08:32.693122 254390272 process.cpp:3065] Resuming crammd5-authenticatee(124)@192.168.1.5:52882 at 2017-02-06 19:08:32.693137920+00:00
I0206 20:08:32.693203 254926848 process.cpp:3170] Cleaning up crammd5-authenticator-session(124)@192.168.1.5:52882
I0206 20:08:32.693239 254390272 process.cpp:3170] Cleaning up crammd5-authenticatee(124)@192.168.1.5:52882
I0206 20:08:32.693266 252780544 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.693278976+00:00
I0206 20:08:32.693338 255463424 slave.cpp:1088] Successfully authenticated with master master@192.168.1.5:52882
I0206 20:08:32.693441 255463424 slave.cpp:1515] Will retry registration in 12.972972ms if necessary
I0206 20:08:32.693441 253853696 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:32.693455872+00:00
I0206 20:08:32.693568 253853696 master.cpp:5318] Registering agent at slave(62)@192.168.1.5:52882 (192.168.1.5) with id 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0
I0206 20:08:32.693658 256000000 process.cpp:3065] Resuming registrar(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.693670912+00:00
I0206 20:08:32.693838 256000000 registrar.cpp:461] Applied 1 operations in 51us; attempting to update the registry
I0206 20:08:32.693971 253317120 process.cpp:3065] Resuming in-memory-storage(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.693984000+00:00
I0206 20:08:32.694175 252780544 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.694187008+00:00
I0206 20:08:32.694180 253853696 process.cpp:3065] Resuming __latch__(921)@192.168.1.5:52882 at 2017-02-06 19:08:32.694192128+00:00
I0206 20:08:32.694211 256000000 process.cpp:3055] Spawned process __latch__(921)@192.168.1.5:52882
I0206 20:08:32.694447 253853696 process.cpp:3065] Resuming __latch__(921)@192.168.1.5:52882 at 2017-02-06 19:08:32.694458880+00:00
I0206 20:08:32.694478 253853696 process.cpp:3170] Cleaning up __latch__(921)@192.168.1.5:52882
I0206 20:08:32.694525 256000000 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.694533888+00:00
I0206 20:08:32.694521 252780544 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.694534912+00:00
I0206 20:08:32.694608 252780544 process.cpp:3065] Resuming registrar(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.694619904+00:00
I0206 20:08:32.694667 252780544 registrar.cpp:506] Successfully updated the registry in 788992ns
I0206 20:08:32.694804 253853696 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:32.694818048+00:00
I0206 20:08:32.695036 253853696 process.cpp:3055] Spawned process slave-observer(62)@192.168.1.5:52882
I0206 20:08:32.695044 256000000 process.cpp:3065] Resuming slave-observer(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.695055104+00:00
I0206 20:08:32.695091 254926848 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.695099904+00:00
I0206 20:08:32.695153 254926848 slave.cpp:4301] Received ping from slave-observer(62)@192.168.1.5:52882
I0206 20:08:32.695178 254390272 process.cpp:3065] Resuming hierarchical-allocator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.695190016+00:00
I0206 20:08:32.695207 255463424 process.cpp:3065] Resuming slave-observer(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.695220992+00:00
I0206 20:08:32.695214 253853696 master.cpp:5392] Registered agent 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0 at slave(62)@192.168.1.5:52882 (192.168.1.5) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0206 20:08:32.695266 254926848 slave.cpp:1134] Registered with master master@192.168.1.5:52882; given agent ID 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0
I0206 20:08:32.695300 254926848 fetcher.cpp:90] Clearing fetcher cache
I0206 20:08:32.695423 254390272 hierarchical.cpp:515] Added agent 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0 (192.168.1.5) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0206 20:08:32.695438 253317120 process.cpp:3065] Resuming status-update-manager(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.695449088+00:00
I0206 20:08:32.695479 253317120 status_update_manager.cpp:184] Resuming sending status updates
I0206 20:08:32.695657 254926848 slave.cpp:1162] Checkpointing SlaveInfo to '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m/meta/slaves/7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0/slave.info'
I0206 20:08:32.695734 254390272 hierarchical.cpp:1790] Allocating cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0 to role * of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:32.696020 254926848 slave.cpp:1200] Forwarding total oversubscribed resources {}
I0206 20:08:32.696080 254926848 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:32.696091904+00:00
I0206 20:08:32.696143 254926848 master.cpp:5949] Received update of agent 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0 at slave(62)@192.168.1.5:52882 (192.168.1.5) with total oversubscribed resources {}
I0206 20:08:32.696202 254390272 hierarchical.cpp:1910] No inverse offers to send out!
I0206 20:08:32.696233 254390272 hierarchical.cpp:1404] Performed allocation for 1 agents in 730us
I0206 20:08:32.696316 254390272 hierarchical.cpp:588] Agent 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0 (192.168.1.5) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000])
I0206 20:08:32.696430 254390272 hierarchical.cpp:1820] No allocations performed
I0206 20:08:32.696455 254390272 hierarchical.cpp:1910] No inverse offers to send out!
I0206 20:08:32.696477 254390272 hierarchical.cpp:1404] Performed allocation for 1 agents in 92us
I0206 20:08:32.696544 254926848 master.cpp:6932] Sending 1 offers to framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 (default) at scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882
I0206 20:08:32.696619 252780544 process.cpp:3065] Resuming scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882 at 2017-02-06 19:08:32.696632064+00:00
I0206 20:08:32.696750 252780544 sched.cpp:909] Received 1 offers
I0206 20:08:32.696892 252780544 sched.cpp:933] Scheduler::resourceOffers took 107us
I0206 20:08:32.696893 253317120 process.cpp:3065] Resuming __latch__(920)@192.168.1.5:52882 at 2017-02-06 19:08:32.696907008+00:00
I0206 20:08:32.696939 253317120 process.cpp:3170] Cleaning up __latch__(920)@192.168.1.5:52882
I0206 20:08:32.697062 252780544 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.697075968+00:00
I0206 20:08:32.697075 254926848 process.cpp:3065] Resuming __waiter__(449)@192.168.1.5:52882 at 2017-02-06 19:08:32.697086976+00:00
I0206 20:08:32.697125 252780544 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.697137920+00:00
I0206 20:08:32.697178 254926848 process.cpp:3170] Cleaning up __waiter__(449)@192.168.1.5:52882
I0206 20:08:32.697253 253317120 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.697265920+00:00
I0206 20:08:32.697518 252780544 process.cpp:3065] Resuming scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882 at 2017-02-06 19:08:32.697530112+00:00
I0206 20:08:32.697599 3349771200 process.cpp:3055] Spawned process __latch__(922)@192.168.1.5:52882
I0206 20:08:32.697602 253853696 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.697614080+00:00
I0206 20:08:32.697624 256000000 process.cpp:3065] Resuming __latch__(922)@192.168.1.5:52882 at 2017-02-06 19:08:32.697637888+00:00
I0206 20:08:32.697648 3349771200 process.cpp:3055] Spawned process __waiter__(450)@192.168.1.5:52882
I0206 20:08:32.697657 254390272 process.cpp:3065] Resuming __waiter__(450)@192.168.1.5:52882 at 2017-02-06 19:08:32.697667072+00:00
I0206 20:08:32.697713 254926848 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:32.697739008+00:00
I0206 20:08:32.698190 254926848 master.cpp:3745] Processing ACCEPT call for offers: [ 7740c960-cc1e-4fb0-8d23-e2c70df099ac-O0 ] on agent 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0 at slave(62)@192.168.1.5:52882 (192.168.1.5) for framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 (default) at scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882
I0206 20:08:32.698256 254926848 master.cpp:3318] Authorizing framework principal 'test-principal' to launch task 54d51886-a750-4a09-8e51-814939be2412
I0206 20:08:32.698369 253853696 process.cpp:3065] Resuming local-authorizer(123)@192.168.1.5:52882 at 2017-02-06 19:08:32.698380032+00:00
I0206 20:08:32.698482 254926848 process.cpp:3055] Spawned process __await__(489)@192.168.1.5:52882
I0206 20:08:32.698473 253317120 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.698486016+00:00
I0206 20:08:32.698478 256000000 process.cpp:3065] Resuming __await__(489)@192.168.1.5:52882 at 2017-02-06 19:08:32.698488064+00:00
I0206 20:08:32.698621 252780544 process.cpp:3065] Resuming __await__(489)@192.168.1.5:52882 at 2017-02-06 19:08:32.698633984+00:00
I0206 20:08:32.698685 252780544 process.cpp:3170] Cleaning up __await__(489)@192.168.1.5:52882
I0206 20:08:32.698787 256536576 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.698800128+00:00
I0206 20:08:32.698818 255463424 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.698831104+00:00
I0206 20:08:32.699882 254926848 master.cpp:8993] Adding task 54d51886-a750-4a09-8e51-814939be2412 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0 at slave(62)@192.168.1.5:52882 (192.168.1.5)
I0206 20:08:32.700112 254926848 master.cpp:4395] Launching task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 (default) at scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0 at slave(62)@192.168.1.5:52882 (192.168.1.5)
I0206 20:08:32.700281 253853696 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.700295936+00:00
I0206 20:08:32.700440 253317120 process.cpp:3065] Resuming hierarchical-allocator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.700453888+00:00
I0206 20:08:32.700685 253853696 slave.cpp:1583] Got assigned task '54d51886-a750-4a09-8e51-814939be2412' for framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:32.701252 253853696 slave.cpp:1743] Launching task '54d51886-a750-4a09-8e51-814939be2412' for framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:32.701938 253853696 paths.cpp:547] Trying to chown '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m/slaves/7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0/frameworks/7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000/executors/54d51886-a750-4a09-8e51-814939be2412/runs/956490c9-696d-46fa-98fe-a4b66c358a21' to user 'bbannier'
I0206 20:08:32.702162 253853696 slave.cpp:6414] Launching executor '54d51886-a750-4a09-8e51-814939be2412' of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m/slaves/7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0/frameworks/7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000/executors/54d51886-a750-4a09-8e51-814939be2412/runs/956490c9-696d-46fa-98fe-a4b66c358a21'
I0206 20:08:32.702267 256000000 process.cpp:3065] Resuming files@192.168.1.5:52882 at 2017-02-06 19:08:32.702280960+00:00
I0206 20:08:32.702625 252780544 process.cpp:3065] Resuming mesos-containerizer(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.702638080+00:00
I0206 20:08:32.702694 252780544 containerizer.cpp:992] Starting container 956490c9-696d-46fa-98fe-a4b66c358a21 for executor '54d51886-a750-4a09-8e51-814939be2412' of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:32.702782 253853696 slave.cpp:2073] Queued task '54d51886-a750-4a09-8e51-814939be2412' for executor '54d51886-a750-4a09-8e51-814939be2412' of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:32.702853 253853696 slave.cpp:884] Successfully attached file '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m/slaves/7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0/frameworks/7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000/executors/54d51886-a750-4a09-8e51-814939be2412/runs/956490c9-696d-46fa-98fe-a4b66c358a21'
I0206 20:08:32.702960 252780544 process.cpp:3055] Spawned process mesos-container-status-updates(123)@192.168.1.5:52882
I0206 20:08:32.702970 253317120 process.cpp:3065] Resuming mesos-container-status-updates(123)@192.168.1.5:52882 at 2017-02-06 19:08:32.702981888+00:00
I0206 20:08:32.703523 256000000 process.cpp:3065] Resuming posix-filesystem-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.703536896+00:00
I0206 20:08:32.704212 253853696 process.cpp:3065] Resuming posix-cpu-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.704226048+00:00
I0206 20:08:32.704915 256000000 process.cpp:3065] Resuming posix-mem-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.704928000+00:00
I0206 20:08:32.705690 253317120 process.cpp:3065] Resuming (62)@192.168.1.5:52882 at 2017-02-06 19:08:32.705703936+00:00
I0206 20:08:32.705862 253853696 process.cpp:3065] Resuming sandbox-logger(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.705875968+00:00
I0206 20:08:32.706806 256000000 process.cpp:3065] Resuming mesos-containerizer(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.706820096+00:00
I0206 20:08:32.707393 256000000 containerizer.cpp:1541] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/Users\/bbannier\/src\/mesos\/build\/src"],"shell":false,"value":"\/Users\/bbannier\/src\/mesos\/build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","value":"192.168.1.5:52882"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/var\/folders\/6t\/yp_xgc8d6k32rpp0bsbfqm9m0000gp\/T\/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m\/slaves\/7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0\/frameworks\/7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000\/executors\/54d51886-a750-4a09-8e51-814939be2412\/runs\/956490c9-696d-46fa-98fe-a4b66c358a21"},{"name":"MESOS_EXECUTOR_ID","value":"54d51886-a750-4a09-8e51-814939be2412"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0"},{"name":"MESOS_SLAVE_PID","value":"slave(62)@192.168.1.5:52882"},{"name":"MESOS_SANDBOX","value":"\/var\/folders\/6t\/yp_xgc8d6k32rpp0bsbfqm9m0000gp\/T\/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m\/slaves\/7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0\/frameworks\/7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000\/executors\/54d51886-a750-4a09-8e51-814939be2412\/runs\/956490c9-696d-46fa-98fe-a4b66c358a21"}]},"err":{"path":"\/var\/folders\/6t\/yp_xgc8d6k32rpp0bsbfqm9m0000gp\/T\/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m\/slaves\/7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0\/frameworks\/7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000\/executors\/54d51886-a750-4a09-8e51-814939be2412\/runs\/956490c9-696d-46fa-98fe-a4b66c358a21\/stderr","type":"PATH"},"out":{"path":"\/var\/folders\/6t\/yp_xgc8d6k32rpp0bsbfqm9m0000gp\/T\/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m\/slaves\/7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0\/frameworks\/7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000\/executors\/54d51886-a750-4a09-8e51-814939be2412\/runs\/956490c9-696d-46fa-98fe-a4b66c358a21\/stdout","type":"PATH"},"user":"bbannier","working_directory":"\/var\/folders\/6t\/yp_xgc8d6k32rpp0bsbfqm9m0000gp\/T\/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m\/slaves\/7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0\/frameworks\/7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000\/executors\/54d51886-a750-4a09-8e51-814939be2412\/runs\/956490c9-696d-46fa-98fe-a4b66c358a21"}" --pipe_read="8" --pipe_write="10" --runtime_directory="/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_zcmFFK/containers/956490c9-696d-46fa-98fe-a4b66c358a21"'
I0206 20:08:32.711328 256000000 launcher.cpp:135] Forked child with pid '51900' for container '956490c9-696d-46fa-98fe-a4b66c358a21'
I0206 20:08:32.711328 253853696 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:32.711410944+00:00
I0206 20:08:32.712049 252780544 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:32.712073984+00:00
I0206 20:08:32.712096 253853696 process.cpp:3065] Resuming posix-filesystem-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.712114944+00:00
I0206 20:08:32.712200 256536576 process.cpp:3065] Resuming posix-cpu-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.712230912+00:00
I0206 20:08:32.712288 254926848 process.cpp:3065] Resuming posix-mem-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.712328960+00:00
I0206 20:08:32.712319 253317120 process.cpp:3065] Resuming (62)@192.168.1.5:52882 at 2017-02-06 19:08:32.712342016+00:00
I0206 20:08:32.712424 253853696 process.cpp:3065] Resuming posix-filesystem-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.712440064+00:00
I0206 20:08:32.712451 255463424 process.cpp:3065] Resuming posix-cpu-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.712471040+00:00
I0206 20:08:32.712517 254926848 process.cpp:3065] Resuming posix-mem-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.712530944+00:00
I0206 20:08:32.712605 253853696 process.cpp:3065] Resuming (62)@192.168.1.5:52882 at 2017-02-06 19:08:32.712620032+00:00
I0206 20:08:32.712728 256000000 process.cpp:3055] Spawned process __collect__(308)@192.168.1.5:52882
I0206 20:08:32.712716 252780544 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.712738048+00:00
I0206 20:08:32.712772 253853696 process.cpp:3065] Resuming __collect__(308)@192.168.1.5:52882 at 2017-02-06 19:08:32.712786944+00:00
I0206 20:08:32.713079 253853696 process.cpp:3170] Cleaning up __collect__(308)@192.168.1.5:52882
I0206 20:08:32.713160 253317120 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:32.713174016+00:00
I0206 20:08:32.713179 254390272 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:32.713251072+00:00
I0206 20:08:32.714447 252780544 process.cpp:3065] Resuming fetcher(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.714464000+00:00
I0206 20:08:32.714598 252780544 fetcher.cpp:349] Starting to fetch URIs for container: 956490c9-696d-46fa-98fe-a4b66c358a21, directory: /var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m/slaves/7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0/frameworks/7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000/executors/54d51886-a750-4a09-8e51-814939be2412/runs/956490c9-696d-46fa-98fe-a4b66c358a21
I0206 20:08:32.717633 253317120 process.cpp:3065] Resuming mesos-containerizer(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.717651968+00:00
I0206 20:08:32.718170 253853696 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:32.718189056+00:00
I0206 20:08:32.750087 256000000 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:32.750561024+00:00
I0206 20:08:32.853807 254390272 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:32.853840896+00:00
I0206 20:08:32.963506 252780544 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:32.963542016+00:00
I0206 20:08:33.065943 253317120 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:33.065971968+00:00
I0206 20:08:33.133617 257073152 process.cpp:3008] Dropping event for process status-update-manager(60)@192.168.1.5:52882
I0206 20:08:33.169142 254926848 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:33.169172992+00:00
I0206 20:08:33.271790 254390272 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:33.271830016+00:00
I0206 20:08:33.335047 253853696 process.cpp:3065] Resuming scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882 at 2017-02-06 19:08:33.335075072+00:00
I0206 20:08:33.376741 256000000 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:33.376771072+00:00
I0206 20:08:33.482584 256536576 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:33.482631936+00:00
I0206 20:08:33.557320 257073152 process.cpp:3008] Dropping event for process hierarchical-allocator(61)@192.168.1.5:52882
I0206 20:08:33.588927 252780544 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:33.588961024+00:00
I0206 20:08:33.672042 254926848 process.cpp:3065] Resuming hierarchical-allocator(62)@192.168.1.5:52882 at 2017-02-06 19:08:33.672070912+00:00
I0206 20:08:33.672333 254926848 hierarchical.cpp:1820] No allocations performed
I0206 20:08:33.672364 254926848 hierarchical.cpp:1910] No inverse offers to send out!
I0206 20:08:33.672391 254926848 hierarchical.cpp:1404] Performed allocation for 1 agents in 163us
I0206 20:08:33.692342 253853696 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:33.692372992+00:00
I0206 20:08:33.797628 254390272 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:33.797661952+00:00
I0206 20:08:33.906143 256000000 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:33.906172928+00:00
I0206 20:08:34.014340 256536576 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:34.014381056+00:00
I0206 20:08:34.119691 253317120 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:34.119719936+00:00
I0206 20:08:34.150497 257073152 process.cpp:3008] Dropping event for process slave(60)@192.168.1.5:52882
I0206 20:08:34.226409 252780544 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:34.226456064+00:00
I0206 20:08:34.333536 254390272 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:34.333570048+00:00
I0206 20:08:34.437924 254926848 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:34.437949952+00:00
I0206 20:08:34.511584 257073152 process.cpp:3008] Dropping event for process __waiter__(215)@192.168.1.5:52882
I0206 20:08:34.548573 255463424 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:34.548605952+00:00
I0206 20:08:34.553159 257073152 process.cpp:3008] Dropping event for process scheduler-2e6351fb-cd78-412e-9217-521fa91c2193@192.168.1.5:52882
I0206 20:08:34.553378 256000000 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:34.553395968+00:00
I0206 20:08:34.568120 257073152 process.cpp:3008] Dropping event for process slave(61)@192.168.1.5:52882
I0206 20:08:34.568197 253853696 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:34.568210944+00:00
I0206 20:08:34.655549 256536576 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:34.655579904+00:00
I0206 20:08:34.677624 253317120 process.cpp:3065] Resuming hierarchical-allocator(62)@192.168.1.5:52882 at 2017-02-06 19:08:34.677654016+00:00
I0206 20:08:34.677863 253317120 hierarchical.cpp:1820] No allocations performed
I0206 20:08:34.677892 253317120 hierarchical.cpp:1910] No inverse offers to send out!
I0206 20:08:34.677918 253317120 hierarchical.cpp:1404] Performed allocation for 1 agents in 126us
I0206 20:08:34.758828 256000000 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:34.758857984+00:00
I0206 20:08:34.867928 255463424 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:34.867958016+00:00
I0206 20:08:34.909939 257073152 process.cpp:3008] Dropping event for process __waiter__(424)@192.168.1.5:52882
I0206 20:08:34.972697 254926848 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:34.972731904+00:00
I0206 20:08:35.082386 254390272 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:35.082416128+00:00
I0206 20:08:35.189824 256536576 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:35.189857024+00:00
I0206 20:08:35.295410 256000000 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:35.295443968+00:00
I0206 20:08:35.404183 253317120 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:35.404214016+00:00
I0206 20:08:35.441107 257073152 process.cpp:3008] Dropping event for process __waiter__(426)@192.168.1.5:52882
I0206 20:08:35.441193 257073152 process.cpp:3008] Dropping event for process slave(59)@192.168.1.5:52882
I0206 20:08:35.450682 257073152 process.cpp:3008] Dropping event for process slave-observer(59)@192.168.1.5:52882
I0206 20:08:35.452268 257073152 process.cpp:3008] Dropping event for process __waiter__(427)@192.168.1.5:52882
I0206 20:08:35.511276 255463424 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:35.511309056+00:00
I0206 20:08:35.617331 253853696 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:35.617366016+00:00
I0206 20:08:35.686364 252780544 process.cpp:3065] Resuming hierarchical-allocator(62)@192.168.1.5:52882 at 2017-02-06 19:08:35.686396928+00:00
I0206 20:08:35.686622 252780544 hierarchical.cpp:1820] No allocations performed
I0206 20:08:35.686652 252780544 hierarchical.cpp:1910] No inverse offers to send out!
I0206 20:08:35.686677 252780544 hierarchical.cpp:1404] Performed allocation for 1 agents in 130us
I0206 20:08:35.726083 254390272 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:35.726117120+00:00
I0206 20:08:35.763453 257073152 process.cpp:3008] Dropping event for process __waiter__(428)@192.168.1.5:52882
I0206 20:08:35.834419 255463424 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:35.834447872+00:00
I0206 20:08:35.883088 257073152 process.cpp:3008] Dropping event for process __waiter__(429)@192.168.1.5:52882
I0206 20:08:35.883196 257073152 process.cpp:3008] Dropping event for process __waiter__(430)@192.168.1.5:52882
I0206 20:08:35.944469 254926848 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:35.944496896+00:00
I0206 20:08:36.053995 253317120 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:36.054028032+00:00
I0206 20:08:36.155059 252780544 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:36.155092992+00:00
I0206 20:08:36.256882 255463424 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:36.256920064+00:00
I0206 20:08:36.366370 253853696 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:36.366405120+00:00
I0206 20:08:36.475328 254926848 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:36.475361024+00:00
I0206 20:08:36.579376 257073152 process.cpp:3008] Dropping event for process slave(50)@192.168.1.5:52882
I0206 20:08:36.579499 255463424 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:36.579516928+00:00
I0206 20:08:36.600044 257073152 process.cpp:3008] Dropping event for process slave(50)@192.168.1.5:52882
I0206 20:08:36.685356 252780544 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:36.685388032+00:00
I0206 20:08:36.688264 256000000 process.cpp:3065] Resuming hierarchical-allocator(62)@192.168.1.5:52882 at 2017-02-06 19:08:36.688285952+00:00
I0206 20:08:36.688473 256000000 hierarchical.cpp:1820] No allocations performed
I0206 20:08:36.688501 256000000 hierarchical.cpp:1910] No inverse offers to send out!
I0206 20:08:36.688530 256000000 hierarchical.cpp:1404] Performed allocation for 1 agents in 129us
I0206 20:08:36.751693 257073152 process.cpp:3008] Dropping event for process __waiter__(431)@192.168.1.5:52882
I0206 20:08:36.787034 253853696 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:36.787065088+00:00
I0206 20:08:36.890287 253317120 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:36.890319104+00:00
I0206 20:08:36.992894 254390272 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:36.992924928+00:00
I0206 20:08:37.101044 254926848 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:37.101074944+00:00
I0206 20:08:37.202697 253853696 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:37.202728960+00:00
I0206 20:08:37.249075 257073152 process.cpp:3008] Dropping event for process slave(61)@192.168.1.5:52882
I0206 20:08:37.304581 255463424 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:37.304617984+00:00
I0206 20:08:37.405513 256000000 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:37.405543936+00:00
I0206 20:08:37.451805 257073152 process.cpp:3008] Dropping event for process slave(47)@192.168.1.5:52882
I0206 20:08:37.513120 256536576 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:37.513155072+00:00
I0206 20:08:37.519172 257073152 process.cpp:3008] Dropping event for process __waiter__(432)@192.168.1.5:52882
I0206 20:08:37.618099 252780544 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:37.618130944+00:00
I0206 20:08:37.685957 254390272 process.cpp:3065] Resuming scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882 at 2017-02-06 19:08:37.685989120+00:00
I0206 20:08:37.685979 253853696 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:37.686001920+00:00
I0206 20:08:37.690649 256536576 process.cpp:3065] Resuming hierarchical-allocator(62)@192.168.1.5:52882 at 2017-02-06 19:08:37.690674944+00:00
I0206 20:08:37.690958 256536576 hierarchical.cpp:1820] No allocations performed
I0206 20:08:37.690994 256536576 hierarchical.cpp:1910] No inverse offers to send out!
I0206 20:08:37.691030 256536576 hierarchical.cpp:1404] Performed allocation for 1 agents in 209us
I0206 20:08:37.693737 255463424 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:37.693757952+00:00
I0206 20:08:37.693743 256000000 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:37.693762048+00:00
I0206 20:08:37.721231 252780544 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:37.721255936+00:00
I0206 20:08:37.823546 253853696 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:37.823577088+00:00
I0206 20:08:37.844753 257073152 process.cpp:3008] Dropping event for process __waiter__(434)@192.168.1.5:52882
I0206 20:08:37.844820 257073152 process.cpp:3008] Dropping event for process slave(60)@192.168.1.5:52882
I0206 20:08:37.848731 257073152 process.cpp:3008] Dropping event for process slave-observer(60)@192.168.1.5:52882
I0206 20:08:37.853348 257073152 process.cpp:3008] Dropping event for process __waiter__(435)@192.168.1.5:52882
I0206 20:08:37.926800 254926848 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:37.926840064+00:00
I0206 20:08:38.032796 254390272 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:38.032825088+00:00
I0206 20:08:38.033037 252780544 process.cpp:3065] Resuming mesos-containerizer(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.033053952+00:00
I0206 20:08:38.033314 252780544 containerizer.cpp:2482] Container 956490c9-696d-46fa-98fe-a4b66c358a21 has exited
I0206 20:08:38.033359 252780544 containerizer.cpp:2119] Destroying container 956490c9-696d-46fa-98fe-a4b66c358a21 in RUNNING state
I0206 20:08:38.033517 252780544 launcher.cpp:151] Asked to destroy container 956490c9-696d-46fa-98fe-a4b66c358a21
I0206 20:08:38.068395 253317120 process.cpp:3065] Resuming __reaper__(1)@192.168.1.5:52882 at 2017-02-06 19:08:38.068429056+00:00
I0206 20:08:38.068599 255463424 process.cpp:3065] Resuming mesos-containerizer(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.068616960+00:00
I0206 20:08:38.068820 254390272 process.cpp:3065] Resuming (62)@192.168.1.5:52882 at 2017-02-06 19:08:38.068836096+00:00
I0206 20:08:38.068909 256536576 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:38.068923904+00:00
I0206 20:08:38.068934 255463424 process.cpp:3055] Spawned process __await__(490)@192.168.1.5:52882
I0206 20:08:38.068940 253853696 process.cpp:3065] Resuming __await__(490)@192.168.1.5:52882 at 2017-02-06 19:08:38.068954112+00:00
I0206 20:08:38.069403 254390272 process.cpp:3065] Resuming posix-mem-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.069419008+00:00
I0206 20:08:38.069423 256536576 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:38.069436928+00:00
I0206 20:08:38.069484 253853696 process.cpp:3055] Spawned process __await__(491)@192.168.1.5:52882
I0206 20:08:38.069507 255463424 process.cpp:3065] Resuming __await__(491)@192.168.1.5:52882 at 2017-02-06 19:08:38.069520896+00:00
I0206 20:08:38.069564 253853696 process.cpp:3170] Cleaning up __await__(490)@192.168.1.5:52882
I0206 20:08:38.069639 253317120 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:38.069651968+00:00
I0206 20:08:38.069651 256536576 process.cpp:3065] Resuming posix-cpu-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.069665024+00:00
I0206 20:08:38.069656 254390272 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:38.069669120+00:00
I0206 20:08:38.069727 253317120 process.cpp:3065] Resuming __await__(492)@192.168.1.5:52882 at 2017-02-06 19:08:38.069736960+00:00
I0206 20:08:38.069739 255463424 process.cpp:3055] Spawned process __await__(492)@192.168.1.5:52882
I0206 20:08:38.069834 255463424 process.cpp:3170] Cleaning up __await__(491)@192.168.1.5:52882
I0206 20:08:38.069844 256536576 process.cpp:3065] Resuming posix-filesystem-isolator(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.069857024+00:00
I0206 20:08:38.069869 254390272 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:38.069882112+00:00
I0206 20:08:38.069900 253317120 process.cpp:3055] Spawned process __await__(493)@192.168.1.5:52882
I0206 20:08:38.069881 254926848 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:38.069903104+00:00
I0206 20:08:38.069913 256000000 process.cpp:3065] Resuming __await__(493)@192.168.1.5:52882 at 2017-02-06 19:08:38.069925888+00:00
I0206 20:08:38.069986 253317120 process.cpp:3170] Cleaning up __await__(492)@192.168.1.5:52882
I0206 20:08:38.070039 253853696 process.cpp:3065] Resuming mesos-containerizer(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.070051072+00:00
I0206 20:08:38.070047 255463424 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:38.070056960+00:00
I0206 20:08:38.070063 254390272 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:38.070074880+00:00
I0206 20:08:38.070113 256000000 process.cpp:3170] Cleaning up __await__(493)@192.168.1.5:52882
I0206 20:08:38.070132 255463424 process.cpp:3065] Resuming mesos-provisioner(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.070144000+00:00
I0206 20:08:38.070145 256536576 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:38.070158080+00:00
I0206 20:08:38.070173 255463424 provisioner.cpp:484] Ignoring destroy request for unknown container 956490c9-696d-46fa-98fe-a4b66c358a21
I0206 20:08:38.070210 253317120 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:38.070222080+00:00
I0206 20:08:38.070258 254390272 process.cpp:3065] Resuming mesos-containerizer(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.070268928+00:00
I0206 20:08:38.070834 253853696 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.070846976+00:00
I0206 20:08:38.070883 253853696 slave.cpp:4706] Executor '54d51886-a750-4a09-8e51-814939be2412' of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 exited with status 1
I0206 20:08:38.070940 252780544 process.cpp:3065] Resuming mesos-container-status-updates(123)@192.168.1.5:52882 at 2017-02-06 19:08:38.070950912+00:00
I0206 20:08:38.070972 252780544 process.cpp:3170] Cleaning up mesos-container-status-updates(123)@192.168.1.5:52882
I0206 20:08:38.070966 253853696 slave.cpp:3771] Handling status update TASK_FAILED (UUID: a4032559-6767-46f3-a2c3-01ec2cdd32d4) for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 from @0.0.0.0:0
I0206 20:08:38.071022 254926848 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:38.071033088+00:00
I0206 20:08:38.071252 255463424 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.071265024+00:00
I0206 20:08:38.071460 253317120 process.cpp:3065] Resuming mesos-containerizer(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.071472896+00:00
W0206 20:08:38.071507 253317120 containerizer.cpp:1934] Ignoring update for unknown container 956490c9-696d-46fa-98fe-a4b66c358a21
I0206 20:08:38.071616 256536576 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.071628032+00:00
I0206 20:08:38.071740 254390272 process.cpp:3065] Resuming status-update-manager(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.071753984+00:00
I0206 20:08:38.071791 254390272 status_update_manager.cpp:323] Received status update TASK_FAILED (UUID: a4032559-6767-46f3-a2c3-01ec2cdd32d4) for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:38.071830 254390272 status_update_manager.cpp:500] Creating StatusUpdate stream for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:38.072136 254390272 status_update_manager.cpp:377] Forwarding update TASK_FAILED (UUID: a4032559-6767-46f3-a2c3-01ec2cdd32d4) for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 to the agent
I0206 20:08:38.072247 254926848 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.072260096+00:00
I0206 20:08:38.072319 254926848 slave.cpp:4211] Forwarding the update TASK_FAILED (UUID: a4032559-6767-46f3-a2c3-01ec2cdd32d4) for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 to master@192.168.1.5:52882
I0206 20:08:38.072425 255463424 process.cpp:3065] Resuming master@192.168.1.5:52882 at 2017-02-06 19:08:38.072438016+00:00
I0206 20:08:38.072448 254926848 slave.cpp:4105] Status update manager successfully handled status update TASK_FAILED (UUID: a4032559-6767-46f3-a2c3-01ec2cdd32d4) for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:38.072568 255463424 master.cpp:6094] Status update TASK_FAILED (UUID: a4032559-6767-46f3-a2c3-01ec2cdd32d4) for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 from agent 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0 at slave(62)@192.168.1.5:52882 (192.168.1.5)
I0206 20:08:38.072613 255463424 master.cpp:6162] Forwarding status update TASK_FAILED (UUID: a4032559-6767-46f3-a2c3-01ec2cdd32d4) for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:38.072706 256000000 process.cpp:3065] Resuming scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882 at 2017-02-06 19:08:38.072719872+00:00
I0206 20:08:38.072724 255463424 master.cpp:8242] Updating the state of task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 (latest state: TASK_FAILED, status update state: TASK_FAILED)
I0206 20:08:38.072814 256000000 sched.cpp:1002] Received status update TASK_FAILED (UUID: a4032559-6767-46f3-a2c3-01ec2cdd32d4) for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 from slave(62)@192.168.1.5:52882
I0206 20:08:38.072877 253317120 process.cpp:3065] Resuming hierarchical-allocator(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.072890112+00:00
I0206 20:08:38.072993 253853696 process.cpp:3065] Resuming __latch__(922)@192.168.1.5:52882 at 2017-02-06 19:08:38.073008128+00:00
I0206 20:08:38.072993 256000000 sched.cpp:1041] Scheduler::statusUpdate took 115us
I0206 20:08:38.073032 253853696 process.cpp:3170] Cleaning up __latch__(922)@192.168.1.5:52882
I0206 20:08:38.073045 256000000 sched.cpp:1060] Sending ACK for status update TASK_FAILED (UUID: a4032559-6767-46f3-a2c3-01ec2cdd32d4) for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 to master@192.168.1.5:52882
I0206 20:08:38.073071 254390272 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:38.073081088+00:00
I0206 20:08:38.073098 254926848 process.cpp:3065] Resuming __waiter__(450)@192.168.1.5:52882 at 2017-02-06 19:08:38.073112064+00:00
I0206 20:08:38.073112 253853696 process.cpp:3065] Resuming __gc__@192.168.1.5:52882 at 2017-02-06 19:08:38.073123072+00:00
I0206 20:08:38.073191 254926848 process.cpp:3170] Cleaning up __waiter__(450)@192.168.1.5:52882
I0206 20:08:38.073201 254390272 process.cpp:3065] Resuming metrics@192.168.1.5:52882 at 2017-02-06 19:08:38.073213952+00:00
I0206 20:08:38.073252 256536576 process.cpp:3065] Resuming help@192.168.1.5:52882 at 2017-02-06 19:08:38.073264128+00:00
I0206 20:08:38.073272 255463424 master.cpp:5032] Processing ACKNOWLEDGE call a4032559-6767-46f3-a2c3-01ec2cdd32d4 for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 (default) at scheduler-207666ba-2d74-45bd-8b05-1a1138746318@192.168.1.5:52882 on agent 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0
I0206 20:08:38.073318 255463424 master.cpp:8336] Removing task 54d51886-a750-4a09-8e51-814939be2412 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000 on agent 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0 at slave(62)@192.168.1.5:52882 (192.168.1.5)
I0206 20:08:38.073504 253853696 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.073517056+00:00
I0206 20:08:38.073506 253317120 hierarchical.cpp:1082] Recovered cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0 from framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:38.073611 253317120 process.cpp:3065] Resuming status-update-manager(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.073623040+00:00
I0206 20:08:38.073657 253317120 status_update_manager.cpp:395] Received status update acknowledgement (UUID: a4032559-6767-46f3-a2c3-01ec2cdd32d4) for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:38.073757 253317120 status_update_manager.cpp:531] Cleaning up status update stream for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:38.073930 255463424 process.cpp:3065] Resuming slave(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.073943040+00:00
I0206 20:08:38.073976 255463424 slave.cpp:3060] Status update manager successfully handled status update acknowledgement (UUID: a4032559-6767-46f3-a2c3-01ec2cdd32d4) for task 54d51886-a750-4a09-8e51-814939be2412 of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:38.074069 255463424 slave.cpp:6804] Completing task 54d51886-a750-4a09-8e51-814939be2412
I0206 20:08:38.074101 255463424 slave.cpp:4806] Cleaning up executor '54d51886-a750-4a09-8e51-814939be2412' of framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:38.074259 256536576 process.cpp:3065] Resuming agent-garbage-collector(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.074272000+00:00
I0206 20:08:38.074303 256536576 gc.cpp:55] Scheduling '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m/slaves/7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0/frameworks/7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000/executors/54d51886-a750-4a09-8e51-814939be2412/runs/956490c9-696d-46fa-98fe-a4b66c358a21' for gc 6.99999914096days in the future
I0206 20:08:38.074363 255463424 slave.cpp:4894] Cleaning up framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
I0206 20:08:38.074395 256536576 gc.cpp:55] Scheduling '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m/slaves/7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0/frameworks/7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000/executors/54d51886-a750-4a09-8e51-814939be2412' for gc 6.99999913959407days in the future
I0206 20:08:38.074419 253317120 process.cpp:3065] Resuming status-update-manager(62)@192.168.1.5:52882 at 2017-02-06 19:08:38.074443008+00:00
I0206 20:08:38.074487 256536576 gc.cpp:55] Scheduling '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachInputToNestedContainerSession_1_w9n98m/slaves/7740c960-cc1e-4fb0-8d23-e2c70df099ac-S0/frameworks/7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000' for gc 6.99999913822815days in the future
I0206 20:08:38.074503 253317120 status_update_manager.cpp:285] Closing status update streams for framework 7740c960-cc1e-4fb0-8d23-e2c70df099ac-0000
../../src/tests/api_tests.cpp:5080: Failure
Value of: status->state()
Actual: TASK_FAILED
Expected: TASK_RUNNING
*** Aborted at 1486408118 (unix time) try "date -d @1486408118" if you are using GNU date ***
PC: @ 0x10fef3091 testing::UnitTest::AddTestPartResult()
*** SIGSEGV (@0x0) received by PID 47307 (TID 0x7fffc7a973c0) stack trace: ***
@ 0x7fffbee57bba _sigtramp
@ 0x8 (unknown)
@ 0x10fef2897 testing::internal::AssertHelper::operator=()
@ 0x10d95403d mesos::internal::tests::AgentAPIStreamingTest_AttachInputToNestedContainerSession_Test::TestBody()
@ 0x10ff74d3e testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x10ff0a8bb testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x10ff0a756 testing::Test::Run()
@ 0x10ff0d55d testing::TestInfo::Run()
@ 0x10ff0f25c testing::TestCase::Run()
@ 0x10ff2833c testing::internal::UnitTestImpl::RunAllTests()
@ 0x10ff77e5e testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x10ff27d6b testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x10ff27c38 testing::UnitTest::Run()
@ 0x10e9baa91 RUN_ALL_TESTS()
@ 0x10e9b5e40 main
@ 0x7fffbec4a255 start
@ 0x5 (unknown)
{noformat}
> AgentAPIStreamingTest.AttachInputToNestedContainerSession fails on Mac OS.
> --------------------------------------------------------------------------
>
> Key: MESOS-6913
> URL: https://issues.apache.org/jira/browse/MESOS-6913
> Project: Mesos
> Issue Type: Bug
> Affects Versions: 1.2.0
> Environment: Mac OS 10.11.6 with Apple clang-703.0.31
> Reporter: Alexander Rukletsov
> Assignee: Benjamin Hindman
> Priority: Critical
> Labels: mesosphere
> Fix For: 1.2.0
>
>
> {noformat}
> [ RUN ] ContentType/AgentAPIStreamingTest.AttachInputToNestedContainerSession/0
> make[3]: *** [check-local] Illegal instruction: 4
> make[2]: *** [check-am] Error 2
> make[1]: *** [check] Error 2
> make: *** [check-recursive] Error 1
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.3.15#6346)