[
https://issues.apache.org/jira/browse/MESOS-6780?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16331599#comment-16331599
]
Gilbert Song commented on MESOS-6780:
-------------------------------------
Downgrade this issue to `MAJOR`, since it is a bug in the unit test based on
the comments above.
> ContentType/AgentAPIStreamingTest.AttachContainerInput test fails reliably
> --------------------------------------------------------------------------
>
> Key: MESOS-6780
> URL: https://issues.apache.org/jira/browse/MESOS-6780
> Project: Mesos
> Issue Type: Bug
> Environment: Mac OS 10.12, clang version 4.0.0
> (http://llvm.org/git/clang 88800602c0baafb8739cb838c2fa3f5fb6cc6968)
> (http://llvm.org/git/llvm 25801f0f22e178343ee1eadfb4c6cc058628280e),
> libc++-513447dbb91dd555ea08297dbee6a1ceb6abdc46
> Reporter: Benjamin Bannier
> Assignee: Kevin Klues
> Priority: Major
> Labels: mesosphere
> Attachments: attach_container_input_no_ssl.log
>
>
> The test {{ContentType/AgentAPIStreamingTest.AttachContainerInput}} (both
> {{/0}} and {{/1}}) fail consistently for me in an SSL-enabled, optimized
> build.
> {code}
> [==========] Running 1 test from 1 test case.
> [----------] Global test environment set-up.
> [----------] 1 test from ContentType/AgentAPIStreamingTest
> [ RUN ] ContentType/AgentAPIStreamingTest.AttachContainerInput/0
> I1212 17:11:12.371175 3971208128 cluster.cpp:160] Creating default 'local'
> authorizer
> I1212 17:11:12.393844 17362944 master.cpp:380] Master
> c752777c-d947-4a86-b382-643463866472 (172.18.8.114) started on
> 172.18.8.114:51059
> I1212 17:11:12.393899 17362944 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="/private/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/F46yYV/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="/usr/local/share/mesos/webui"
> --work_dir="/private/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/F46yYV/master"
> --zk_session_timeout="10secs"
> I1212 17:11:12.394670 17362944 master.cpp:432] Master only allowing
> authenticated frameworks to register
> I1212 17:11:12.394682 17362944 master.cpp:446] Master only allowing
> authenticated agents to register
> I1212 17:11:12.394691 17362944 master.cpp:459] Master only allowing
> authenticated HTTP frameworks to register
> I1212 17:11:12.394701 17362944 credentials.hpp:37] Loading credentials for
> authentication from
> '/private/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/F46yYV/credentials'
> I1212 17:11:12.394959 17362944 master.cpp:504] Using default 'crammd5'
> authenticator
> I1212 17:11:12.394996 17362944 authenticator.cpp:519] Initializing server SASL
> I1212 17:11:12.411406 17362944 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-master-readonly'
> I1212 17:11:12.411571 17362944 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-master-readwrite'
> I1212 17:11:12.411682 17362944 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-master-scheduler'
> I1212 17:11:12.411775 17362944 master.cpp:584] Authorization enabled
> I1212 17:11:12.413318 16289792 master.cpp:2045] Elected as the leading master!
> I1212 17:11:12.413377 16289792 master.cpp:1568] Recovering from registrar
> I1212 17:11:12.417582 14143488 registrar.cpp:362] Successfully fetched the
> registry (0B) in 4.131072ms
> I1212 17:11:12.417667 14143488 registrar.cpp:461] Applied 1 operations in
> 27us; attempting to update the registry
> I1212 17:11:12.421799 14143488 registrar.cpp:506] Successfully updated the
> registry in 4.10496ms
> I1212 17:11:12.421835 14143488 registrar.cpp:392] Successfully recovered
> registrar
> I1212 17:11:12.421998 17362944 master.cpp:1684] Recovered 0 agents from the
> registry (136B); allowing 10mins for agents to re-register
> I1212 17:11:12.422780 3971208128 containerizer.cpp:220] Using isolation:
> posix/cpu,posix/mem,filesystem/posix
> I1212 17:11:12.424154 3971208128 cluster.cpp:446] Creating default 'local'
> authorizer
> I1212 17:11:12.424693 17899520 slave.cpp:209] Mesos agent started on
> (1)@172.18.8.114:51059
> I1212 17:11:12.424726 17899520 slave.cpp:210] 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_AttachContainerInput_0_p3or5f/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_AttachContainerInput_0_p3or5f/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_AttachContainerInput_0_p3or5f/http_credentials"
> --image_provisioner_backend="copy" --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_AttachContainerInput_0_p3or5f"
> --sandbox_directory="/mnt/mesos/sandbox" --strict="true"
> --switch_user="true" --version="false"
> --work_dir="/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_0_i1KBnm"
> I1212 17:11:12.424830 17899520 credentials.hpp:86] Loading credential for
> authentication from
> '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_0_p3or5f/credential'
> I1212 17:11:12.424904 17899520 slave.cpp:347] Agent using credential for:
> test-principal
> I1212 17:11:12.424919 17899520 credentials.hpp:37] Loading credentials for
> authentication from
> '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_0_p3or5f/http_credentials'
> I1212 17:11:12.424979 17899520 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-agent-readonly'
> I1212 17:11:12.425025 17899520 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-agent-readwrite'
> I1212 17:11:12.425668 17899520 slave.cpp:534] Agent resources: cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000]
> I1212 17:11:12.425700 17899520 slave.cpp:542] Agent attributes: [ ]
> I1212 17:11:12.425709 17899520 slave.cpp:547] Agent hostname: 172.18.8.114
> I1212 17:11:12.425770 3971208128 sched.cpp:232] Version: 1.2.0
> I1212 17:11:12.425947 16826368 sched.cpp:336] New master detected at
> master@172.18.8.114:51059
> I1212 17:11:12.425967 16826368 sched.cpp:402] Authenticating with master
> master@172.18.8.114:51059
> I1212 17:11:12.425976 16826368 sched.cpp:409] Using default CRAM-MD5
> authenticatee
> I1212 17:11:12.426043 15753216 authenticatee.cpp:97] Initializing client SASL
> I1212 17:11:12.426118 14680064 state.cpp:57] Recovering state from
> '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_0_i1KBnm/meta'
> I1212 17:11:12.426251 16289792 status_update_manager.cpp:203] Recovering
> status update manager
> I1212 17:11:12.426367 15216640 containerizer.cpp:594] Recovering containerizer
> I1212 17:11:12.426802 17362944 provisioner.cpp:253] Provisioner recovery
> complete
> I1212 17:11:12.426914 16826368 slave.cpp:5415] Finished recovery
> I1212 17:11:12.427336 17362944 status_update_manager.cpp:177] Pausing sending
> status updates
> I1212 17:11:12.427335 16826368 slave.cpp:919] New master detected at
> master@172.18.8.114:51059
> I1212 17:11:12.427371 16826368 slave.cpp:978] Authenticating with master
> master@172.18.8.114:51059
> I1212 17:11:12.427386 16826368 slave.cpp:989] Using default CRAM-MD5
> authenticatee
> I1212 17:11:12.427419 16826368 slave.cpp:951] Detecting new master
> I1212 17:11:12.428241 15753216 authenticatee.cpp:121] Creating new client
> SASL connection
> I1212 17:11:12.428242 14680064 authenticatee.cpp:121] Creating new client
> SASL connection
> I1212 17:11:12.428308 15216640 master.cpp:6748] Authenticating
> scheduler-c6d9a3b8-d683-4703-a309-b504ef5747ce@172.18.8.114:51059
> I1212 17:11:12.428380 15216640 master.cpp:6748] Authenticating
> slave(1)@172.18.8.114:51059
> I1212 17:11:12.428431 14680064 authenticator.cpp:98] Creating new server SASL
> connection
> I1212 17:11:12.428475 17362944 authenticator.cpp:98] Creating new server SASL
> connection
> I1212 17:11:12.428491 16289792 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> I1212 17:11:12.428515 16289792 authenticatee.cpp:239] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I1212 17:11:12.428524 14143488 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> I1212 17:11:12.428539 14143488 authenticatee.cpp:239] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I1212 17:11:12.428551 15753216 authenticator.cpp:204] Received SASL
> authentication start
> I1212 17:11:12.428582 15216640 authenticator.cpp:204] Received SASL
> authentication start
> I1212 17:11:12.428587 15753216 authenticator.cpp:326] Authentication requires
> more steps
> I1212 17:11:12.428606 15216640 authenticator.cpp:326] Authentication requires
> more steps
> I1212 17:11:12.428627 16289792 authenticatee.cpp:259] Received SASL
> authentication step
> I1212 17:11:12.428642 15753216 authenticatee.cpp:259] Received SASL
> authentication step
> I1212 17:11:12.428678 16826368 authenticator.cpp:232] Received SASL
> authentication step
> I1212 17:11:12.428697 16826368 authenticator.cpp:318] Authentication success
> I1212 17:11:12.428697 16289792 authenticator.cpp:232] Received SASL
> authentication step
> I1212 17:11:12.428719 16289792 authenticator.cpp:318] Authentication success
> I1212 17:11:12.428741 15753216 authenticatee.cpp:299] Authentication success
> I1212 17:11:12.428757 17362944 authenticatee.cpp:299] Authentication success
> I1212 17:11:12.428784 15216640 master.cpp:6778] Successfully authenticated
> principal 'test-principal' at
> scheduler-c6d9a3b8-d683-4703-a309-b504ef5747ce@172.18.8.114:51059
> I1212 17:11:12.428802 15216640 master.cpp:6778] Successfully authenticated
> principal 'test-principal' at slave(1)@172.18.8.114:51059
> I1212 17:11:12.428895 17899520 sched.cpp:508] Successfully authenticated with
> master master@172.18.8.114:51059
> I1212 17:11:12.428910 14680064 slave.cpp:1073] Successfully authenticated
> with master master@172.18.8.114:51059
> I1212 17:11:12.428977 16826368 master.cpp:2633] Received SUBSCRIBE call for
> framework 'default' at
> scheduler-c6d9a3b8-d683-4703-a309-b504ef5747ce@172.18.8.114:51059
> I1212 17:11:12.428987 16826368 master.cpp:2081] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1212 17:11:12.429064 16826368 master.cpp:5161] Registering agent at
> slave(1)@172.18.8.114:51059 (172.18.8.114) with id
> c752777c-d947-4a86-b382-643463866472-S0
> I1212 17:11:12.429138 16826368 master.cpp:2709] Subscribing framework default
> with checkpointing disabled and capabilities [ ]
> I1212 17:11:12.429144 16289792 registrar.cpp:461] Applied 1 operations in
> 18us; attempting to update the registry
> I1212 17:11:12.429236 17362944 hierarchical.cpp:276] Added framework
> c752777c-d947-4a86-b382-643463866472-0000
> I1212 17:11:12.429277 17899520 sched.cpp:749] Framework registered with
> c752777c-d947-4a86-b382-643463866472-0000
> I1212 17:11:12.433480 15753216 registrar.cpp:506] Successfully updated the
> registry in 4.315136ms
> I1212 17:11:12.433706 14143488 master.cpp:5232] Registered agent
> c752777c-d947-4a86-b382-643463866472-S0 at slave(1)@172.18.8.114:51059
> (172.18.8.114) with cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000]
> I1212 17:11:12.433748 16826368 slave.cpp:1119] Registered with master
> master@172.18.8.114:51059; given agent ID
> c752777c-d947-4a86-b382-643463866472-S0
> I1212 17:11:12.433768 17899520 hierarchical.cpp:490] Added agent
> c752777c-d947-4a86-b382-643463866472-S0 (172.18.8.114) with cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
> I1212 17:11:12.433831 17362944 status_update_manager.cpp:184] Resuming
> sending status updates
> I1212 17:11:12.434078 15216640 master.cpp:6577] Sending 1 offers to framework
> c752777c-d947-4a86-b382-643463866472-0000 (default) at
> scheduler-c6d9a3b8-d683-4703-a309-b504ef5747ce@172.18.8.114:51059
> I1212 17:11:12.434176 16826368 slave.cpp:1179] Forwarding total
> oversubscribed resources {}
> I1212 17:11:12.434238 16826368 master.cpp:5633] Received update of agent
> c752777c-d947-4a86-b382-643463866472-S0 at slave(1)@172.18.8.114:51059
> (172.18.8.114) with total oversubscribed resources {}
> I1212 17:11:12.434347 16289792 hierarchical.cpp:560] Agent
> c752777c-d947-4a86-b382-643463866472-S0 (172.18.8.114) updated with
> oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000])
> I1212 17:11:12.434772 15216640 master.cpp:3588] Processing ACCEPT call for
> offers: [ c752777c-d947-4a86-b382-643463866472-O0 ] on agent
> c752777c-d947-4a86-b382-643463866472-S0 at slave(1)@172.18.8.114:51059
> (172.18.8.114) for framework c752777c-d947-4a86-b382-643463866472-0000
> (default) at scheduler-c6d9a3b8-d683-4703-a309-b504ef5747ce@172.18.8.114:51059
> I1212 17:11:12.434798 15216640 master.cpp:3175] Authorizing framework
> principal 'test-principal' to launch task b737e40d-298f-4540-bd53-08f2f90483ea
> I1212 17:11:12.435257 15216640 master.cpp:8501] Adding task
> b737e40d-298f-4540-bd53-08f2f90483ea with resources cpus(*):2; mem(*):1024;
> disk(*):1024; ports(*):[31000-32000] on agent
> c752777c-d947-4a86-b382-643463866472-S0 (172.18.8.114)
> I1212 17:11:12.435309 15216640 master.cpp:4240] Launching task
> b737e40d-298f-4540-bd53-08f2f90483ea of framework
> c752777c-d947-4a86-b382-643463866472-0000 (default) at
> scheduler-c6d9a3b8-d683-4703-a309-b504ef5747ce@172.18.8.114:51059 with
> resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on
> agent c752777c-d947-4a86-b382-643463866472-S0 at slave(1)@172.18.8.114:51059
> (172.18.8.114)
> I1212 17:11:12.435467 16826368 slave.cpp:1551] Got assigned task
> 'b737e40d-298f-4540-bd53-08f2f90483ea' for framework
> c752777c-d947-4a86-b382-643463866472-0000
> I1212 17:11:12.435624 16826368 slave.cpp:1713] Launching task
> 'b737e40d-298f-4540-bd53-08f2f90483ea' for framework
> c752777c-d947-4a86-b382-643463866472-0000
> I1212 17:11:12.439905 16826368 paths.cpp:530] Trying to chown
> '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_0_i1KBnm/slaves/c752777c-d947-4a86-b382-643463866472-S0/frameworks/c752777c-d947-4a86-b382-643463866472-0000/executors/b737e40d-298f-4540-bd53-08f2f90483ea/runs/4c5af2b0-e7b0-43c7-bdae-e8c9d3eb6b6a'
> to user 'bbannier'
> I1212 17:11:12.447335 16826368 slave.cpp:6342] Launching executor
> 'b737e40d-298f-4540-bd53-08f2f90483ea' of framework
> c752777c-d947-4a86-b382-643463866472-0000 with resources cpus(*):0.1;
> mem(*):32 in work directory
> '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_0_i1KBnm/slaves/c752777c-d947-4a86-b382-643463866472-S0/frameworks/c752777c-d947-4a86-b382-643463866472-0000/executors/b737e40d-298f-4540-bd53-08f2f90483ea/runs/4c5af2b0-e7b0-43c7-bdae-e8c9d3eb6b6a'
> I1212 17:11:12.447726 16289792 containerizer.cpp:986] Starting container
> 4c5af2b0-e7b0-43c7-bdae-e8c9d3eb6b6a for executor
> 'b737e40d-298f-4540-bd53-08f2f90483ea' of framework
> c752777c-d947-4a86-b382-643463866472-0000
> I1212 17:11:12.447757 16826368 slave.cpp:2035] Queued task
> 'b737e40d-298f-4540-bd53-08f2f90483ea' for executor
> 'b737e40d-298f-4540-bd53-08f2f90483ea' of framework
> c752777c-d947-4a86-b382-643463866472-0000
> I1212 17:11:12.450546 15216640 launcher.cpp:133] Forked child with pid
> '70329' for container '4c5af2b0-e7b0-43c7-bdae-e8c9d3eb6b6a'
> I1212 17:11:12.528198 17899520 slave.cpp:3309] Got registration for executor
> 'b737e40d-298f-4540-bd53-08f2f90483ea' of framework
> c752777c-d947-4a86-b382-643463866472-0000 from executor(1)@172.18.8.114:51060
> I1212 17:11:12.528815 14680064 slave.cpp:2251] Sending queued task
> 'b737e40d-298f-4540-bd53-08f2f90483ea' to executor
> 'b737e40d-298f-4540-bd53-08f2f90483ea' of framework
> c752777c-d947-4a86-b382-643463866472-0000 at executor(1)@172.18.8.114:51060
> I1212 17:11:12.538859 17362944 slave.cpp:3744] Handling status update
> TASK_RUNNING (UUID: 31e88139-5942-4566-b479-fcb34adc41f6) for task
> b737e40d-298f-4540-bd53-08f2f90483ea of framework
> c752777c-d947-4a86-b382-643463866472-0000 from executor(1)@172.18.8.114:51060
> I1212 17:11:12.539412 15216640 status_update_manager.cpp:323] Received status
> update TASK_RUNNING (UUID: 31e88139-5942-4566-b479-fcb34adc41f6) for task
> b737e40d-298f-4540-bd53-08f2f90483ea of framework
> c752777c-d947-4a86-b382-643463866472-0000
> I1212 17:11:12.539566 15753216 slave.cpp:4185] Forwarding the update
> TASK_RUNNING (UUID: 31e88139-5942-4566-b479-fcb34adc41f6) for task
> b737e40d-298f-4540-bd53-08f2f90483ea of framework
> c752777c-d947-4a86-b382-643463866472-0000 to master@172.18.8.114:51059
> I1212 17:11:12.539677 15216640 master.cpp:5769] Status update TASK_RUNNING
> (UUID: 31e88139-5942-4566-b479-fcb34adc41f6) for task
> b737e40d-298f-4540-bd53-08f2f90483ea of framework
> c752777c-d947-4a86-b382-643463866472-0000 from agent
> c752777c-d947-4a86-b382-643463866472-S0 at slave(1)@172.18.8.114:51059
> (172.18.8.114)
> I1212 17:11:12.539679 15753216 slave.cpp:4095] Sending acknowledgement for
> status update TASK_RUNNING (UUID: 31e88139-5942-4566-b479-fcb34adc41f6) for
> task b737e40d-298f-4540-bd53-08f2f90483ea of framework
> c752777c-d947-4a86-b382-643463866472-0000 to executor(1)@172.18.8.114:51060
> I1212 17:11:12.539698 15216640 master.cpp:5831] Forwarding status update
> TASK_RUNNING (UUID: 31e88139-5942-4566-b479-fcb34adc41f6) for task
> b737e40d-298f-4540-bd53-08f2f90483ea of framework
> c752777c-d947-4a86-b382-643463866472-0000
> I1212 17:11:12.539746 15216640 master.cpp:7867] Updating the state of task
> b737e40d-298f-4540-bd53-08f2f90483ea of framework
> c752777c-d947-4a86-b382-643463866472-0000 (latest state: TASK_RUNNING, status
> update state: TASK_RUNNING)
> I1212 17:11:12.539916 15753216 master.cpp:4877] Processing ACKNOWLEDGE call
> 31e88139-5942-4566-b479-fcb34adc41f6 for task
> b737e40d-298f-4540-bd53-08f2f90483ea of framework
> c752777c-d947-4a86-b382-643463866472-0000 (default) at
> scheduler-c6d9a3b8-d683-4703-a309-b504ef5747ce@172.18.8.114:51059 on agent
> c752777c-d947-4a86-b382-643463866472-S0
> I1212 17:11:12.540098 17362944 status_update_manager.cpp:395] Received status
> update acknowledgement (UUID: 31e88139-5942-4566-b479-fcb34adc41f6) for task
> b737e40d-298f-4540-bd53-08f2f90483ea of framework
> c752777c-d947-4a86-b382-643463866472-0000
> I1212 17:11:12.541115 15216640 http.cpp:301] HTTP POST for /slave(1)/api/v1
> from 172.18.8.114:51063
> I1212 17:11:12.541209 15216640 http.cpp:462] Processing call
> LAUNCH_NESTED_CONTAINER
> I1212 17:11:12.541774 14680064 containerizer.cpp:1789] Starting nested
> container
> 4c5af2b0-e7b0-43c7-bdae-e8c9d3eb6b6a.e722e09b-a26d-4856-aa3f-f45c33a7c673
> I1212 17:11:12.541926 14680064 containerizer.cpp:1813] Trying to chown
> '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_0_i1KBnm/slaves/c752777c-d947-4a86-b382-643463866472-S0/frameworks/c752777c-d947-4a86-b382-643463866472-0000/executors/b737e40d-298f-4540-bd53-08f2f90483ea/runs/4c5af2b0-e7b0-43c7-bdae-e8c9d3eb6b6a/containers/e722e09b-a26d-4856-aa3f-f45c33a7c673'
> to user 'bbannier'
> I1212 17:11:12.549746 17362944 switchboard.cpp:427] Allocated pseudo terminal
> '/dev/ttys005' for container
> 4c5af2b0-e7b0-43c7-bdae-e8c9d3eb6b6a.e722e09b-a26d-4856-aa3f-f45c33a7c673
> I1212 17:11:12.556056 17362944 switchboard.cpp:593] Created I/O switchboard
> server (pid: 70372) listening on socket file
> '/tmp/mesos-io-switchboard-cfdf17da-2e61-44ee-860b-3f81ebcd003b' for
> container
> 4c5af2b0-e7b0-43c7-bdae-e8c9d3eb6b6a.e722e09b-a26d-4856-aa3f-f45c33a7c673
> I1212 17:11:12.557566 15753216 launcher.cpp:133] Forked child with pid
> '70373' for container
> '4c5af2b0-e7b0-43c7-bdae-e8c9d3eb6b6a.e722e09b-a26d-4856-aa3f-f45c33a7c673'
> I1212 17:11:12.561508 15753216 http.cpp:301] HTTP POST for /slave(1)/api/v1
> from 172.18.8.114:51064
> I1212 17:11:12.561626 15753216 http.cpp:462] Processing call
> ATTACH_CONTAINER_OUTPUT
> I1212 17:11:12.599273 16289792 http.cpp:301] HTTP POST for /slave(1)/api/v1
> from 172.18.8.114:51066
> I1212 17:11:12.599447 15216640 http.cpp:462] Processing call
> ATTACH_CONTAINER_INPUT
> ../../src/tests/api_tests.cpp:4563: Failure
> Value of: stdoutReceived
> Actual: "Lorem ipsum dolor sit amet, consectetur adipisicing elit, sed do
> eiusmod tempor incididunt ut labore et dolore magna aliqua. Ut enim ad minim
> veniam, quis nostrud exercitation ullamco laboris nisi ut aliquip ex ea
> commodo consequat. Duis aute irure dolor in reprehenderit in voluptate velit
> esse cillum dolore eu fugiat nulla pariatur. Excepteur sint occaecat
> cupidatat non proident, sunt in culpa qui officia deserunt mollit anim id est
> laborum.\r\nLorem ipsum dolor sit amet, consectetur adipisicing elit, sed do
> eiusmod tempor incididunt ut labore et dolore magna aliqua. Ut enim ad minim
> veniam, quis nostrud exercitation ullamco laboris nisi ut aliquip ex ea
> commodo consequat. Duis aute irure dolor in reprehenderit in voluptate velit
> esse cillum dolore eu fugiat nulla pariatur. Excepteur sint occaecat
> cupidatat non proident, sunt in culpa qui officia deserunt mollit anim id est
> laborum.\r\n^D\b\b"
> Expected: stdoutExpected + stdoutExpected
> Which is: "Lorem ipsum dolor sit amet, consectetur adipisicing elit, sed do
> eiusmod tempor incididunt ut labore et dolore magna aliqua. Ut enim ad minim
> veniam, quis nostrud exercitation ullamco laboris nisi ut aliquip ex ea
> commodo consequat. Duis aute irure dolor in reprehenderit in voluptate velit
> esse cillum dolore eu fugiat nulla pariatur. Excepteur sint occaecat
> cupidatat non proident, sunt in culpa qui officia deserunt mollit anim id est
> laborum.\r\nLorem ipsum dolor sit amet, consectetur adipisicing elit, sed do
> eiusmod tempor incididunt ut labore et dolore magna aliqua. Ut enim ad minim
> veniam, quis nostrud exercitation ullamco laboris nisi ut aliquip ex ea
> commodo consequat. Duis aute irure dolor in reprehenderit in voluptate velit
> esse cillum dolore eu fugiat nulla pariatur. Excepteur sint occaecat
> cupidatat non proident, sunt in culpa qui officia deserunt mollit anim id est
> laborum.\r\n"
> *** Aborted at 1481559072 (unix time) try "date -d @1481559072" if you are
> using GNU date ***
> PC: @ 0x10f8e2928 testing::UnitTest::AddTestPartResult()
> *** SIGSEGV (@0x0) received by PID 70308 (TID 0x7fffecb3d3c0) stack trace: ***
> @ 0x7fffe402abba _sigtramp
> @ 0x15 (unknown)
> @ 0x10f8e2525 testing::internal::AssertHelper::operator=()
> @ 0x10e4c5bf5
> mesos::internal::tests::AgentAPIStreamingTest_AttachContainerInput_Test::TestBody()
> @ 0x10f8eeafe
> testing::internal::HandleExceptionsInMethodIfSupported<>()
> @ 0x10f8ee9c1 testing::Test::Run()
> @ 0x10f8efb70 testing::TestInfo::Run()
> @ 0x10f8f04c7 testing::TestCase::Run()
> @ 0x10f8f8ab7 testing::internal::UnitTestImpl::RunAllTests()
> @ 0x10f8f852e
> testing::internal::HandleExceptionsInMethodIfSupported<>()
> @ 0x10f8f8497 testing::UnitTest::Run()
> @ 0x10eb93a81 main
> @ 0x7fffe3e1d255 start
> {code}
> {code}
> [==========] Running 1 test from 1 test case.
> [----------] Global test environment set-up.
> [----------] 1 test from ContentType/AgentAPIStreamingTest
> [ RUN ] ContentType/AgentAPIStreamingTest.AttachContainerInput/1
> I1212 17:14:53.102649 3971208128 cluster.cpp:160] Creating default 'local'
> authorizer
> I1212 17:14:53.125578 177336320 master.cpp:380] Master
> ae9af165-d9bd-4013-a212-acfb11f2ec58 (172.18.8.114) started on
> 172.18.8.114:51593
> I1212 17:14:53.125640 177336320 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="/private/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/BNggX9/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="/usr/local/share/mesos/webui"
> --work_dir="/private/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/BNggX9/master"
> --zk_session_timeout="10secs"
> I1212 17:14:53.126427 177336320 master.cpp:432] Master only allowing
> authenticated frameworks to register
> I1212 17:14:53.126441 177336320 master.cpp:446] Master only allowing
> authenticated agents to register
> I1212 17:14:53.126449 177336320 master.cpp:459] Master only allowing
> authenticated HTTP frameworks to register
> I1212 17:14:53.126457 177336320 credentials.hpp:37] Loading credentials for
> authentication from
> '/private/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/BNggX9/credentials'
> I1212 17:14:53.126719 177336320 master.cpp:504] Using default 'crammd5'
> authenticator
> I1212 17:14:53.126758 177336320 authenticator.cpp:519] Initializing server
> SASL
> I1212 17:14:53.143985 177336320 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-master-readonly'
> I1212 17:14:53.144109 177336320 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-master-readwrite'
> I1212 17:14:53.144203 177336320 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-master-scheduler'
> I1212 17:14:53.144305 177336320 master.cpp:584] Authorization enabled
> I1212 17:14:53.145725 180555776 master.cpp:2045] Elected as the leading
> master!
> I1212 17:14:53.145748 180555776 master.cpp:1568] Recovering from registrar
> I1212 17:14:53.149868 180019200 registrar.cpp:362] Successfully fetched the
> registry (0B) in 4.061952ms
> I1212 17:14:53.149933 180019200 registrar.cpp:461] Applied 1 operations in
> 14us; attempting to update the registry
> I1212 17:14:53.150269 178409472 registrar.cpp:506] Successfully updated the
> registry in 321024ns
> I1212 17:14:53.150305 178409472 registrar.cpp:392] Successfully recovered
> registrar
> I1212 17:14:53.150504 177336320 master.cpp:1684] Recovered 0 agents from the
> registry (136B); allowing 10mins for agents to re-register
> I1212 17:14:53.151446 3971208128 containerizer.cpp:220] Using isolation:
> posix/cpu,posix/mem,filesystem/posix
> I1212 17:14:53.152847 3971208128 cluster.cpp:446] Creating default 'local'
> authorizer
> I1212 17:14:53.153373 177872896 slave.cpp:209] Mesos agent started on
> (1)@172.18.8.114:51593
> I1212 17:14:53.153405 177872896 slave.cpp:210] 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_AttachContainerInput_1_2ZZqgF/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_AttachContainerInput_1_2ZZqgF/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_AttachContainerInput_1_2ZZqgF/http_credentials"
> --image_provisioner_backend="copy" --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_AttachContainerInput_1_2ZZqgF"
> --sandbox_directory="/mnt/mesos/sandbox" --strict="true"
> --switch_user="true" --version="false"
> --work_dir="/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_zjbSR5"
> I1212 17:14:53.153523 177872896 credentials.hpp:86] Loading credential for
> authentication from
> '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_2ZZqgF/credential'
> I1212 17:14:53.153580 177872896 slave.cpp:347] Agent using credential for:
> test-principal
> I1212 17:14:53.153592 177872896 credentials.hpp:37] Loading credentials for
> authentication from
> '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_2ZZqgF/http_credentials'
> I1212 17:14:53.153654 177872896 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-agent-readonly'
> I1212 17:14:53.153723 177872896 http.cpp:922] Using default 'basic' HTTP
> authenticator for realm 'mesos-agent-readwrite'
> I1212 17:14:53.154201 177872896 slave.cpp:534] Agent resources: cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000]
> I1212 17:14:53.154237 177872896 slave.cpp:542] Agent attributes: [ ]
> I1212 17:14:53.154245 177872896 slave.cpp:547] Agent hostname: 172.18.8.114
> I1212 17:14:53.154330 3971208128 sched.cpp:232] Version: 1.2.0
> I1212 17:14:53.154482 179482624 sched.cpp:336] New master detected at
> master@172.18.8.114:51593
> I1212 17:14:53.154505 179482624 sched.cpp:402] Authenticating with master
> master@172.18.8.114:51593
> I1212 17:14:53.154512 179482624 sched.cpp:409] Using default CRAM-MD5
> authenticatee
> I1212 17:14:53.154574 181092352 authenticatee.cpp:97] Initializing client SASL
> I1212 17:14:53.154692 179482624 state.cpp:57] Recovering state from
> '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_zjbSR5/meta'
> I1212 17:14:53.154790 177872896 status_update_manager.cpp:203] Recovering
> status update manager
> I1212 17:14:53.154877 179482624 containerizer.cpp:594] Recovering
> containerizer
> I1212 17:14:53.155285 178946048 provisioner.cpp:253] Provisioner recovery
> complete
> I1212 17:14:53.155418 179482624 slave.cpp:5415] Finished recovery
> I1212 17:14:53.155787 178946048 status_update_manager.cpp:177] Pausing
> sending status updates
> I1212 17:14:53.155791 179482624 slave.cpp:919] New master detected at
> master@172.18.8.114:51593
> I1212 17:14:53.155812 179482624 slave.cpp:978] Authenticating with master
> master@172.18.8.114:51593
> I1212 17:14:53.155822 179482624 slave.cpp:989] Using default CRAM-MD5
> authenticatee
> I1212 17:14:53.155858 179482624 slave.cpp:951] Detecting new master
> I1212 17:14:53.156754 181092352 authenticatee.cpp:121] Creating new client
> SASL connection
> I1212 17:14:53.156756 177336320 authenticatee.cpp:121] Creating new client
> SASL connection
> I1212 17:14:53.156823 180019200 master.cpp:6748] Authenticating
> scheduler-46594304-df71-4ca2-a2bb-864813134cbf@172.18.8.114:51593
> I1212 17:14:53.156888 180019200 master.cpp:6748] Authenticating
> slave(1)@172.18.8.114:51593
> I1212 17:14:53.156932 178946048 authenticator.cpp:98] Creating new server
> SASL connection
> I1212 17:14:53.156981 180019200 authenticator.cpp:98] Creating new server
> SASL connection
> I1212 17:14:53.157003 179482624 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> I1212 17:14:53.157032 179482624 authenticatee.cpp:239] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I1212 17:14:53.157035 181092352 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> I1212 17:14:53.157047 181092352 authenticatee.cpp:239] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I1212 17:14:53.157075 178409472 authenticator.cpp:204] Received SASL
> authentication start
> I1212 17:14:53.157085 177336320 authenticator.cpp:204] Received SASL
> authentication start
> I1212 17:14:53.157116 177336320 authenticator.cpp:326] Authentication
> requires more steps
> I1212 17:14:53.157120 178409472 authenticator.cpp:326] Authentication
> requires more steps
> I1212 17:14:53.157138 177336320 authenticatee.cpp:259] Received SASL
> authentication step
> I1212 17:14:53.157153 180019200 authenticatee.cpp:259] Received SASL
> authentication step
> I1212 17:14:53.157189 177872896 authenticator.cpp:232] Received SASL
> authentication step
> I1212 17:14:53.157203 180555776 authenticator.cpp:232] Received SASL
> authentication step
> I1212 17:14:53.157212 177872896 authenticator.cpp:318] Authentication success
> I1212 17:14:53.157217 180555776 authenticator.cpp:318] Authentication success
> I1212 17:14:53.157253 179482624 authenticatee.cpp:299] Authentication success
> I1212 17:14:53.157259 177336320 authenticatee.cpp:299] Authentication success
> I1212 17:14:53.157279 178409472 master.cpp:6778] Successfully authenticated
> principal 'test-principal' at slave(1)@172.18.8.114:51593
> I1212 17:14:53.157307 178409472 master.cpp:6778] Successfully authenticated
> principal 'test-principal' at
> scheduler-46594304-df71-4ca2-a2bb-864813134cbf@172.18.8.114:51593
> I1212 17:14:53.157385 178946048 sched.cpp:508] Successfully authenticated
> with master master@172.18.8.114:51593
> I1212 17:14:53.157387 180019200 slave.cpp:1073] Successfully authenticated
> with master master@172.18.8.114:51593
> I1212 17:14:53.157469 179482624 master.cpp:2633] Received SUBSCRIBE call for
> framework 'default' at
> scheduler-46594304-df71-4ca2-a2bb-864813134cbf@172.18.8.114:51593
> I1212 17:14:53.157480 179482624 master.cpp:2081] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1212 17:14:53.157562 179482624 master.cpp:5161] Registering agent at
> slave(1)@172.18.8.114:51593 (172.18.8.114) with id
> ae9af165-d9bd-4013-a212-acfb11f2ec58-S0
> I1212 17:14:53.157642 179482624 master.cpp:2709] Subscribing framework
> default with checkpointing disabled and capabilities [ ]
> I1212 17:14:53.157649 178946048 registrar.cpp:461] Applied 1 operations in
> 17us; attempting to update the registry
> I1212 17:14:53.157748 177336320 hierarchical.cpp:276] Added framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000
> I1212 17:14:53.157784 181092352 sched.cpp:749] Framework registered with
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000
> I1212 17:14:53.162051 178946048 registrar.cpp:506] Successfully updated the
> registry in 4.388096ms
> I1212 17:14:53.162256 180019200 master.cpp:5232] Registered agent
> ae9af165-d9bd-4013-a212-acfb11f2ec58-S0 at slave(1)@172.18.8.114:51593
> (172.18.8.114) with cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000]
> I1212 17:14:53.162276 177336320 slave.cpp:1119] Registered with master
> master@172.18.8.114:51593; given agent ID
> ae9af165-d9bd-4013-a212-acfb11f2ec58-S0
> I1212 17:14:53.162314 177872896 hierarchical.cpp:490] Added agent
> ae9af165-d9bd-4013-a212-acfb11f2ec58-S0 (172.18.8.114) with cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
> I1212 17:14:53.162364 181092352 status_update_manager.cpp:184] Resuming
> sending status updates
> I1212 17:14:53.162603 179482624 master.cpp:6577] Sending 1 offers to
> framework ae9af165-d9bd-4013-a212-acfb11f2ec58-0000 (default) at
> scheduler-46594304-df71-4ca2-a2bb-864813134cbf@172.18.8.114:51593
> I1212 17:14:53.162997 177336320 slave.cpp:1179] Forwarding total
> oversubscribed resources {}
> I1212 17:14:53.163071 179482624 master.cpp:5633] Received update of agent
> ae9af165-d9bd-4013-a212-acfb11f2ec58-S0 at slave(1)@172.18.8.114:51593
> (172.18.8.114) with total oversubscribed resources {}
> I1212 17:14:53.163161 177872896 hierarchical.cpp:560] Agent
> ae9af165-d9bd-4013-a212-acfb11f2ec58-S0 (172.18.8.114) updated with
> oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000])
> I1212 17:14:53.163255 179482624 master.cpp:3588] Processing ACCEPT call for
> offers: [ ae9af165-d9bd-4013-a212-acfb11f2ec58-O0 ] on agent
> ae9af165-d9bd-4013-a212-acfb11f2ec58-S0 at slave(1)@172.18.8.114:51593
> (172.18.8.114) for framework ae9af165-d9bd-4013-a212-acfb11f2ec58-0000
> (default) at scheduler-46594304-df71-4ca2-a2bb-864813134cbf@172.18.8.114:51593
> I1212 17:14:53.163280 179482624 master.cpp:3175] Authorizing framework
> principal 'test-principal' to launch task a7b0be01-0de3-4c2f-88e3-0d41135106ee
> I1212 17:14:53.163763 179482624 master.cpp:8501] Adding task
> a7b0be01-0de3-4c2f-88e3-0d41135106ee with resources cpus(*):2; mem(*):1024;
> disk(*):1024; ports(*):[31000-32000] on agent
> ae9af165-d9bd-4013-a212-acfb11f2ec58-S0 (172.18.8.114)
> I1212 17:14:53.163812 179482624 master.cpp:4240] Launching task
> a7b0be01-0de3-4c2f-88e3-0d41135106ee of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000 (default) at
> scheduler-46594304-df71-4ca2-a2bb-864813134cbf@172.18.8.114:51593 with
> resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on
> agent ae9af165-d9bd-4013-a212-acfb11f2ec58-S0 at slave(1)@172.18.8.114:51593
> (172.18.8.114)
> I1212 17:14:53.164006 178409472 slave.cpp:1551] Got assigned task
> 'a7b0be01-0de3-4c2f-88e3-0d41135106ee' for framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000
> I1212 17:14:53.164182 178409472 slave.cpp:1713] Launching task
> 'a7b0be01-0de3-4c2f-88e3-0d41135106ee' for framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000
> I1212 17:14:53.168439 178409472 paths.cpp:530] Trying to chown
> '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_zjbSR5/slaves/ae9af165-d9bd-4013-a212-acfb11f2ec58-S0/frameworks/ae9af165-d9bd-4013-a212-acfb11f2ec58-0000/executors/a7b0be01-0de3-4c2f-88e3-0d41135106ee/runs/9d539310-2fb6-4ecf-ac39-fb6c3279c551'
> to user 'bbannier'
> I1212 17:14:53.175879 178409472 slave.cpp:6342] Launching executor
> 'a7b0be01-0de3-4c2f-88e3-0d41135106ee' of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000 with resources cpus(*):0.1;
> mem(*):32 in work directory
> '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_zjbSR5/slaves/ae9af165-d9bd-4013-a212-acfb11f2ec58-S0/frameworks/ae9af165-d9bd-4013-a212-acfb11f2ec58-0000/executors/a7b0be01-0de3-4c2f-88e3-0d41135106ee/runs/9d539310-2fb6-4ecf-ac39-fb6c3279c551'
> I1212 17:14:53.176204 180019200 containerizer.cpp:986] Starting container
> 9d539310-2fb6-4ecf-ac39-fb6c3279c551 for executor
> 'a7b0be01-0de3-4c2f-88e3-0d41135106ee' of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000
> I1212 17:14:53.176241 178409472 slave.cpp:2035] Queued task
> 'a7b0be01-0de3-4c2f-88e3-0d41135106ee' for executor
> 'a7b0be01-0de3-4c2f-88e3-0d41135106ee' of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000
> I1212 17:14:53.178685 179482624 launcher.cpp:133] Forked child with pid
> '70900' for container '9d539310-2fb6-4ecf-ac39-fb6c3279c551'
> I1212 17:14:53.255278 177336320 slave.cpp:3309] Got registration for executor
> 'a7b0be01-0de3-4c2f-88e3-0d41135106ee' of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000 from executor(1)@172.18.8.114:51594
> I1212 17:14:53.255971 178946048 slave.cpp:2251] Sending queued task
> 'a7b0be01-0de3-4c2f-88e3-0d41135106ee' to executor
> 'a7b0be01-0de3-4c2f-88e3-0d41135106ee' of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000 at executor(1)@172.18.8.114:51594
> I1212 17:14:53.262200 179482624 slave.cpp:3744] Handling status update
> TASK_RUNNING (UUID: 153409af-d8ad-4356-bfb6-becaab327348) for task
> a7b0be01-0de3-4c2f-88e3-0d41135106ee of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000 from executor(1)@172.18.8.114:51594
> I1212 17:14:53.262794 181092352 status_update_manager.cpp:323] Received
> status update TASK_RUNNING (UUID: 153409af-d8ad-4356-bfb6-becaab327348) for
> task a7b0be01-0de3-4c2f-88e3-0d41135106ee of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000
> I1212 17:14:53.262962 178409472 slave.cpp:4185] Forwarding the update
> TASK_RUNNING (UUID: 153409af-d8ad-4356-bfb6-becaab327348) for task
> a7b0be01-0de3-4c2f-88e3-0d41135106ee of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000 to master@172.18.8.114:51593
> I1212 17:14:53.263043 178409472 slave.cpp:4095] Sending acknowledgement for
> status update TASK_RUNNING (UUID: 153409af-d8ad-4356-bfb6-becaab327348) for
> task a7b0be01-0de3-4c2f-88e3-0d41135106ee of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000 to executor(1)@172.18.8.114:51594
> I1212 17:14:53.263075 180019200 master.cpp:5769] Status update TASK_RUNNING
> (UUID: 153409af-d8ad-4356-bfb6-becaab327348) for task
> a7b0be01-0de3-4c2f-88e3-0d41135106ee of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000 from agent
> ae9af165-d9bd-4013-a212-acfb11f2ec58-S0 at slave(1)@172.18.8.114:51593
> (172.18.8.114)
> I1212 17:14:53.263098 180019200 master.cpp:5831] Forwarding status update
> TASK_RUNNING (UUID: 153409af-d8ad-4356-bfb6-becaab327348) for task
> a7b0be01-0de3-4c2f-88e3-0d41135106ee of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000
> I1212 17:14:53.263147 180019200 master.cpp:7867] Updating the state of task
> a7b0be01-0de3-4c2f-88e3-0d41135106ee of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000 (latest state: TASK_RUNNING, status
> update state: TASK_RUNNING)
> I1212 17:14:53.263332 180555776 master.cpp:4877] Processing ACKNOWLEDGE call
> 153409af-d8ad-4356-bfb6-becaab327348 for task
> a7b0be01-0de3-4c2f-88e3-0d41135106ee of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000 (default) at
> scheduler-46594304-df71-4ca2-a2bb-864813134cbf@172.18.8.114:51593 on agent
> ae9af165-d9bd-4013-a212-acfb11f2ec58-S0
> I1212 17:14:53.263535 177872896 status_update_manager.cpp:395] Received
> status update acknowledgement (UUID: 153409af-d8ad-4356-bfb6-becaab327348)
> for task a7b0be01-0de3-4c2f-88e3-0d41135106ee of framework
> ae9af165-d9bd-4013-a212-acfb11f2ec58-0000
> I1212 17:14:53.264525 178946048 http.cpp:301] HTTP POST for /slave(1)/api/v1
> from 172.18.8.114:51597
> I1212 17:14:53.264628 178946048 http.cpp:462] Processing call
> LAUNCH_NESTED_CONTAINER
> I1212 17:14:53.265455 177336320 containerizer.cpp:1789] Starting nested
> container
> 9d539310-2fb6-4ecf-ac39-fb6c3279c551.d379a870-f8f7-4966-925c-d0dbc4f45fed
> I1212 17:14:53.265627 177336320 containerizer.cpp:1813] Trying to chown
> '/var/folders/6t/yp_xgc8d6k32rpp0bsbfqm9m0000gp/T/ContentType_AgentAPIStreamingTest_AttachContainerInput_1_zjbSR5/slaves/ae9af165-d9bd-4013-a212-acfb11f2ec58-S0/frameworks/ae9af165-d9bd-4013-a212-acfb11f2ec58-0000/executors/a7b0be01-0de3-4c2f-88e3-0d41135106ee/runs/9d539310-2fb6-4ecf-ac39-fb6c3279c551/containers/d379a870-f8f7-4966-925c-d0dbc4f45fed'
> to user 'bbannier'
> I1212 17:14:53.273653 178946048 switchboard.cpp:427] Allocated pseudo
> terminal '/dev/ttys005' for container
> 9d539310-2fb6-4ecf-ac39-fb6c3279c551.d379a870-f8f7-4966-925c-d0dbc4f45fed
> I1212 17:14:53.274617 178946048 switchboard.cpp:593] Created I/O switchboard
> server (pid: 70939) listening on socket file
> '/tmp/mesos-io-switchboard-378f151f-d11b-4998-966d-89c14aa7bf7d' for
> container
> 9d539310-2fb6-4ecf-ac39-fb6c3279c551.d379a870-f8f7-4966-925c-d0dbc4f45fed
> I1212 17:14:53.276167 178409472 launcher.cpp:133] Forked child with pid
> '70942' for container
> '9d539310-2fb6-4ecf-ac39-fb6c3279c551.d379a870-f8f7-4966-925c-d0dbc4f45fed'
> I1212 17:14:53.280479 178409472 http.cpp:301] HTTP POST for /slave(1)/api/v1
> from 172.18.8.114:51598
> I1212 17:14:53.280597 178409472 http.cpp:462] Processing call
> ATTACH_CONTAINER_OUTPUT
> I1212 17:14:53.317458 181092352 http.cpp:301] HTTP POST for /slave(1)/api/v1
> from 172.18.8.114:51600
> I1212 17:14:53.317662 177872896 http.cpp:462] Processing call
> ATTACH_CONTAINER_INPUT
> ../../src/tests/api_tests.cpp:4563: Failure
> Value of: stdoutReceived
> Actual: "Lorem ipsum dolor sit amet, consectetur adipisicing elit, sed do
> eiusmod tempor incididunt ut labore et dolore magna aliqua. Ut enim ad minim
> veniam, quis nostrud exercitation ullamco laboris nisi ut aliquip ex ea
> commodo consequat. Duis aute irure dolor in reprehenderit in voluptate velit
> esse cillum dolore eu fugiat nulla pariatur. Excepteur sint occaecat
> cupidatat non proident, sunt in culpa qui officia deserunt mollit anim id est
> laborum.\r\nLorem ipsum dolor sit amet, consectetur adipisicing elit, sed do
> eiusmod tempor incididunt ut labore et dolore magna aliqua. Ut enim ad minim
> veniam, quis nostrud exercitation ullamco laboris nisi ut aliquip ex ea
> commodo consequat. Duis aute irure dolor in reprehenderit in voluptate velit
> esse cillum dolore eu fugiat nulla pariatur. Excepteur sint occaecat
> cupidatat non proident, sunt in culpa qui officia deserunt mollit anim id est
> laborum.\r\n^D\b\b"
> Expected: stdoutExpected + stdoutExpected
> Which is: "Lorem ipsum dolor sit amet, consectetur adipisicing elit, sed do
> eiusmod tempor incididunt ut labore et dolore magna aliqua. Ut enim ad minim
> veniam, quis nostrud exercitation ullamco laboris nisi ut aliquip ex ea
> commodo consequat. Duis aute irure dolor in reprehenderit in voluptate velit
> esse cillum dolore eu fugiat nulla pariatur. Excepteur sint occaecat
> cupidatat non proident, sunt in culpa qui officia deserunt mollit anim id est
> laborum.\r\nLorem ipsum dolor sit amet, consectetur adipisicing elit, sed do
> eiusmod tempor incididunt ut labore et dolore magna aliqua. Ut enim ad minim
> veniam, quis nostrud exercitation ullamco laboris nisi ut aliquip ex ea
> commodo consequat. Duis aute irure dolor in reprehenderit in voluptate velit
> esse cillum dolore eu fugiat nulla pariatur. Excepteur sint occaecat
> cupidatat non proident, sunt in culpa qui officia deserunt mollit anim id est
> laborum.\r\n"
> *** Aborted at 1481559293 (unix time) try "date -d @1481559293" if you are
> using GNU date ***
> PC: @ 0x104564928 testing::UnitTest::AddTestPartResult()
> *** SIGSEGV (@0x0) received by PID 70879 (TID 0x7fffecb3d3c0) stack trace: ***
> @ 0x7fffe402abba _sigtramp
> @ 0x15 (unknown)
> @ 0x104564525 testing::internal::AssertHelper::operator=()
> @ 0x103147bf5
> mesos::internal::tests::AgentAPIStreamingTest_AttachContainerInput_Test::TestBody()
> @ 0x104570afe
> testing::internal::HandleExceptionsInMethodIfSupported<>()
> @ 0x1045709c1 testing::Test::Run()
> @ 0x104571b70 testing::TestInfo::Run()
> @ 0x1045724c7 testing::TestCase::Run()
> @ 0x10457aab7 testing::internal::UnitTestImpl::RunAllTests()
> @ 0x10457a52e
> testing::internal::HandleExceptionsInMethodIfSupported<>()
> @ 0x10457a497 testing::UnitTest::Run()
> @ 0x103815a81 main
> @ 0x7fffe3e1d255 start
> {code}
--
This message was sent by Atlassian JIRA
(v7.6.3#76005)
