[MESOS-6780] ContentType/AgentAPIStreamingTest.AttachContainerInput test fails reliably - ASF JIRA

XML

Word

Printable

JSON

Details

Type: Bug
Status: Accepted
Priority: Major
Resolution: Unresolved
Affects Version/s: None
Fix Version/s: None
Component/s: None
Labels:
Environment:

Hide

Mac OS 10.12, clang version 4.0.0 (http://llvm.org/git/clang 88800602c0baafb8739cb838c2fa3f5fb6cc6968) (http://llvm.org/git/llvm 25801f0f22e178343ee1eadfb4c6cc058628280e), libc++-513447dbb91dd555ea08297dbee6a1ceb6abdc46

Show
Mac OS 10.12, clang version 4.0.0 ( http://llvm.org/git/clang 88800602c0baafb8739cb838c2fa3f5fb6cc6968) ( http://llvm.org/git/llvm 25801f0f22e178343ee1eadfb4c6cc058628280e), libc++-513447dbb91dd555ea08297dbee6a1ceb6abdc46

Epic Link:
Container Attach/Exec Improvements
Sprint:
Mesosphere Sprint 48

Description

The test ContentType/AgentAPIStreamingTest.AttachContainerInput (both /0 and /1) fail consistently for me in an SSL-enabled, optimized build.

[==========] 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

[==========] 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

Attachments

- Sort By Name
- Sort By Date
- Ascending
- Descending

attach_container_input_no_ssl.log
15/Dec/16 21:11
29 kB
Neil Conway

Activity

People

Assignee:: Unassigned

Reporter:: Benjamin Bannier

Votes:: 1 Vote for this issue

Watchers:: 7 Start watching this issue

Dates

Created:: 12/Dec/16 16:15

Updated:: 08/Jan/19 19:01