See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4334/display/redirect>
------------------------------------------ [...truncated 7.39 MB...] 3: I1017 23:03:37.948704 16684 registrar.cpp:495] Applied 1 operations in 35222ns; attempting to update the registry 3: I1017 23:03:37.949465 16684 registrar.cpp:552] Successfully updated the registry in 694016ns 3: I1017 23:03:37.949744 16684 registrar.cpp:424] Successfully recovered registrar 3: I1017 23:03:37.950314 16682 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1017 23:03:37.950574 16683 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover 3: W1017 23:03:37.955801 16678 process.cpp:3194] Attempted to spawn already running process [email protected]:39397 3: I1017 23:03:37.958730 16686 slave.cpp:254] Mesos agent started on (148)@172.17.0.2:39397 3: I1017 23:03:37.958753 16686 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_hBt5dH/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_hBt5dH/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_hBt5dH/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_secret_key="/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_hBt5dH/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_hBt5dH/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_hBt5dH/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus(superhero):2;cpus(muggle):3;mem(superhero):512;mem(muggle):1024;" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_hBt5dH" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_K4EUkG" --zk_session_timeout="10secs" 3: I1017 23:03:37.959239 16686 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_hBt5dH/credential' 3: I1017 23:03:37.959548 16686 slave.cpp:287] Agent using credential for: test-principal 3: I1017 23:03:37.959573 16686 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_hBt5dH/http_credentials' 3: W1017 23:03:37.959758 16678 process.cpp:3194] Attempted to spawn already running process [email protected]:39397 3: I1017 23:03:37.960142 16686 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' 3: I1017 23:03:37.960692 16686 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' 3: I1017 23:03:37.961079 16686 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1017 23:03:37.961318 16686 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1017 23:03:37.961647 16686 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1017 23:03:37.961881 16686 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1017 23:03:37.962361 16678 sched.cpp:232] Version: 1.5.0 3: I1017 23:03:37.962954 16682 sched.cpp:336] New master detected at [email protected]:39397 3: I1017 23:03:37.963094 16682 sched.cpp:396] Authenticating with master [email protected]:39397 3: I1017 23:03:37.963119 16682 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1017 23:03:37.963479 16683 authenticatee.cpp:121] Creating new client SASL connection 3: I1017 23:03:37.963924 16681 master.cpp:7936] Authenticating [email protected]:39397 3: I1017 23:03:37.964067 16680 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(349)@172.17.0.2:39397 3: I1017 23:03:37.964524 16680 authenticator.cpp:98] Creating new server SASL connection 3: I1017 23:03:37.964838 16684 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1017 23:03:37.964865 16684 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1017 23:03:37.965119 16684 authenticator.cpp:204] Received SASL authentication start 3: I1017 23:03:37.965306 16684 authenticator.cpp:326] Authentication requires more steps 3: I1017 23:03:37.965546 16685 authenticatee.cpp:259] Received SASL authentication step 3: I1017 23:03:37.964879 16686 slave.cpp:585] Agent resources: [{"name":"cpus","reservations":[{"role":"superhero","type":"STATIC"}],"scalar":{"value":2.0},"type":"SCALAR"},{"name":"cpus","reservations":[{"role":"muggle","type":"STATIC"}],"scalar":{"value":3.0},"type":"SCALAR"},{"name":"mem","reservations":[{"role":"superhero","type":"STATIC"}],"scalar":{"value":512.0},"type":"SCALAR"},{"name":"mem","reservations":[{"role":"muggle","type":"STATIC"}],"scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1026873.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1017 23:03:37.965750 16686 slave.cpp:593] Agent attributes: [ ] 3: I1017 23:03:37.965770 16686 slave.cpp:602] Agent hostname: 4ec4734f0528 3: I1017 23:03:37.965986 16684 authenticator.cpp:232] Received SASL authentication step 3: I1017 23:03:37.966205 16683 status_update_manager.cpp:177] Pausing sending status updates 3: I1017 23:03:37.966276 16684 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4ec4734f0528' server FQDN: '4ec4734f0528' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1017 23:03:37.966394 16684 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1017 23:03:37.966588 16684 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1017 23:03:37.966784 16684 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4ec4734f0528' server FQDN: '4ec4734f0528' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1017 23:03:37.966845 16684 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1017 23:03:37.966892 16684 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1017 23:03:37.967051 16684 authenticator.cpp:318] Authentication success 3: I1017 23:03:37.967280 16681 authenticatee.cpp:299] Authentication success 3: I1017 23:03:37.967597 16679 sched.cpp:502] Successfully authenticated with master [email protected]:39397 3: I1017 23:03:37.967653 16681 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(349)@172.17.0.2:39397 3: I1017 23:03:37.967664 16679 sched.cpp:820] Sending SUBSCRIBE call to [email protected]:39397 3: I1017 23:03:37.967591 16682 master.cpp:7966] Successfully authenticated principal 'test-principal' at [email protected]:39397 3: I1017 23:03:37.967871 16679 sched.cpp:853] Will retry registration in 787.264582ms if necessary 3: I1017 23:03:37.968401 16679 master.cpp:2929] Received SUBSCRIBE call for framework 'framework-superhero' at [email protected]:39397 3: I1017 23:03:37.968549 16679 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ superhero }' 3: *** Aborted at 1508281417 (unix time) try "date -d @1508281417" if you are using GNU date *** 3: I1017 23:03:37.969352 16681 master.cpp:3009] Subscribing framework framework-superhero with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ] 3: I1017 23:03:37.970077 16679 sched.cpp:747] Framework registered with 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: I1017 23:03:37.970212 16679 sched.cpp:761] Scheduler::registered took 81932ns 3: I1017 23:03:37.970640 16685 hierarchical.cpp:303] Added framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: I1017 23:03:37.970927 16685 hierarchical.cpp:1945] No allocations performed 3: I1017 23:03:37.971015 16685 hierarchical.cpp:2035] No inverse offers to send out! 3: I1017 23:03:37.971146 16685 hierarchical.cpp:1488] Performed allocation for 0 agents in 272059ns 3: I1017 23:03:37.971544 16681 state.cpp:64] Recovering state from '/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_K4EUkG/meta' 3: I1017 23:03:37.971873 16681 status_update_manager.cpp:203] Recovering status update manager 3: I1017 23:03:37.972900 16686 slave.cpp:6310] Finished recovery 3: I1017 23:03:37.973558 16686 slave.cpp:6492] Querying resource estimator for oversubscribable resources 3: I1017 23:03:37.973898 16679 status_update_manager.cpp:177] Pausing sending status updates 3: I1017 23:03:37.973914 16686 slave.cpp:993] New master detected at [email protected]:39397 3: I1017 23:03:37.974364 16686 slave.cpp:1028] Detecting new master 3: I1017 23:03:37.974676 16686 slave.cpp:6506] Received oversubscribable resources {} from the resource estimator 3: I1017 23:03:37.976595 16683 slave.cpp:1055] Authenticating with master [email protected]:39397 3: I1017 23:03:37.976667 16683 slave.cpp:1064] Using default CRAM-MD5 authenticatee 3: I1017 23:03:37.977174 16682 authenticatee.cpp:121] Creating new client SASL connection 3: I1017 23:03:37.977485 16685 master.cpp:7936] Authenticating slave(148)@172.17.0.2:39397 3: I1017 23:03:37.977664 16680 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(350)@172.17.0.2:39397 3: I1017 23:03:37.978137 16681 authenticator.cpp:98] Creating new server SASL connection 3: I1017 23:03:37.978456 16681 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1017 23:03:37.978483 16681 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1017 23:03:37.978579 16681 authenticator.cpp:204] Received SASL authentication start 3: I1017 23:03:37.978627 16681 authenticator.cpp:326] Authentication requires more steps 3: I1017 23:03:37.978778 16681 authenticatee.cpp:259] Received SASL authentication step 3: I1017 23:03:37.978955 16681 authenticator.cpp:232] Received SASL authentication step 3: I1017 23:03:37.978982 16681 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4ec4734f0528' server FQDN: '4ec4734f0528' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1017 23:03:37.978991 16681 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1017 23:03:37.979017 16681 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1017 23:03:37.979055 16681 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4ec4734f0528' server FQDN: '4ec4734f0528' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1017 23:03:37.979071 16681 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1017 23:03:37.979076 16681 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1017 23:03:37.979097 16681 authenticator.cpp:318] Authentication success 3: I1017 23:03:37.979199 16679 authenticatee.cpp:299] Authentication success 3: I1017 23:03:37.979347 16683 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(148)@172.17.0.2:39397 3: I1017 23:03:37.979403 16681 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(350)@172.17.0.2:39397 3: I1017 23:03:37.979914 16682 slave.cpp:1147] Successfully authenticated with master [email protected]:39397 3: I1017 23:03:37.980438 16683 master.cpp:5801] Received register agent message from slave(148)@172.17.0.2:39397 (4ec4734f0528) 3: I1017 23:03:37.980192 16682 slave.cpp:1626] Will retry registration in 6.57189ms if necessary 3: I1017 23:03:37.997352 16683 master.cpp:3838] Authorizing agent with principal 'test-principal' 3: I1017 23:03:38.000757 16683 master.cpp:5861] Authorized registration of agent at slave(148)@172.17.0.2:39397 (4ec4734f0528) 3: I1017 23:03:38.000959 16683 master.cpp:5954] Registering agent at slave(148)@172.17.0.2:39397 (4ec4734f0528) with id 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 3: PC: @ 0x7ff29a0c34f2 process::EventQueue::Consumer::empty() 3: I1017 23:03:38.002032 16679 registrar.cpp:495] Applied 1 operations in 147664ns; attempting to update the registry 3: I1017 23:03:38.003134 16680 registrar.cpp:552] Successfully updated the registry in 973056ns 3: I1017 23:03:38.003535 16683 master.cpp:6001] Admitted agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 at slave(148)@172.17.0.2:39397 (4ec4734f0528) 3: I1017 23:03:38.004750 16679 slave.cpp:1626] Will retry registration in 35.939104ms if necessary 3: I1017 23:03:38.005064 16679 slave.cpp:4966] Received ping from slave-observer(150)@172.17.0.2:39397 3: I1017 23:03:38.005401 16679 slave.cpp:1193] Registered with master [email protected]:39397; given agent ID 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 3: *** SIGSEGV (@0x8) received by PID 16678 (TID 0x7ff28c444700) from PID 8; stack trace: *** 3: I1017 23:03:38.005918 16681 hierarchical.cpp:593] Added agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 (4ec4734f0528) with cpus(reservations: [(STATIC,superhero)]):2; cpus(reservations: [(STATIC,muggle)]):3; mem(reservations: [(STATIC,superhero)]):512; mem(reservations: [(STATIC,muggle)]):1024; disk:1026873; ports:[31000-32000] (allocated: {}) 3: I1017 23:03:38.006186 16679 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_K4EUkG/meta/slaves/55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0/slave.info' 3: I1017 23:03:38.006765 16679 slave.cpp:1262] Forwarding total oversubscribed resources {} 3: I1017 23:03:38.004933 16683 master.cpp:6032] Registered agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 at slave(148)@172.17.0.2:39397 (4ec4734f0528) with [{"name":"cpus","reservations":[{"role":"superhero","type":"STATIC"}],"scalar":{"value":2.0},"type":"SCALAR"},{"name":"cpus","reservations":[{"role":"muggle","type":"STATIC"}],"scalar":{"value":3.0},"type":"SCALAR"},{"name":"mem","reservations":[{"role":"superhero","type":"STATIC"}],"scalar":{"value":512.0},"type":"SCALAR"},{"name":"mem","reservations":[{"role":"muggle","type":"STATIC"}],"scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1026873.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1017 23:03:38.007095 16679 status_update_manager.cpp:184] Resuming sending status updates 3: I1017 23:03:38.007524 16683 master.cpp:5801] Received register agent message from slave(148)@172.17.0.2:39397 (4ec4734f0528) 3: I1017 23:03:38.007679 16683 master.cpp:3838] Authorizing agent with principal 'test-principal' 3: I1017 23:03:38.007876 16681 hierarchical.cpp:2035] No inverse offers to send out! 3: I1017 23:03:38.007932 16681 hierarchical.cpp:1488] Performed allocation for 1 agents in 1.745129ms 3: @ 0x7ff298cfc5e0 (unknown) 3: @ 0x7ff29a0c34f2 process::EventQueue::Consumer::empty() 3: I1017 23:03:38.010251 16683 master.cpp:6817] Received update of agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 at slave(148)@172.17.0.2:39397 (4ec4734f0528) with total oversubscribed resources {} 3: I1017 23:03:38.010471 16683 master.cpp:6828] Ignoring update on agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 at slave(148)@172.17.0.2:39397 (4ec4734f0528) as it reports no changes 3: I1017 23:03:38.011404 16683 master.cpp:7766] Sending 1 offers to framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 (framework-superhero) at [email protected]:39397 3: I1017 23:03:38.011600 16683 master.cpp:5861] Authorized registration of agent at slave(148)@172.17.0.2:39397 (4ec4734f0528) 3: I1017 23:03:38.011734 16683 master.cpp:5932] Agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 at slave(148)@172.17.0.2:39397 (4ec4734f0528) already registered, resending acknowledgement 3: W1017 23:03:38.012008 16679 slave.cpp:1248] Already registered with master [email protected]:39397 3: I1017 23:03:38.012063 16679 slave.cpp:1262] Forwarding total oversubscribed resources {} 3: I1017 23:03:38.012421 16679 master.cpp:6817] Received update of agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 at slave(148)@172.17.0.2:39397 (4ec4734f0528) with total oversubscribed resources {} 3: I1017 23:03:38.012781 16679 master.cpp:6828] Ignoring update on agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 at slave(148)@172.17.0.2:39397 (4ec4734f0528) as it reports no changes 3: I1017 23:03:38.015194 16685 sched.cpp:917] Scheduler::resourceOffers took 2.040146ms 3: I1017 23:03:38.017920 16686 master.cpp:9389] Removing offer 55462fea-3e21-4ac5-ba72-6a02f5d3787f-O0 3: I1017 23:03:38.018123 16686 master.cpp:4196] Processing ACCEPT call for offers: [ 55462fea-3e21-4ac5-ba72-6a02f5d3787f-O0 ] on agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 at slave(148)@172.17.0.2:39397 (4ec4734f0528) for framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 (framework-superhero) at [email protected]:39397 3: I1017 23:03:38.018348 16686 master.cpp:3565] Authorizing framework principal 'test-principal' to launch task 1 3: W1017 23:03:38.021113 16686 validation.cpp:1338] Executor 'test-executor-superhero' for task '1' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. 3: W1017 23:03:38.021163 16686 validation.cpp:1350] Executor 'test-executor-superhero' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. 3: I1017 23:03:38.022068 16686 master.cpp:10135] Adding task 1 with resources [{"allocation_info":{"role":"superhero"},"name":"cpus","reservations":[{"role":"superhero","type":"STATIC"}],"scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"superhero"},"name":"mem","reservations":[{"role":"superhero","type":"STATIC"}],"scalar":{"value":512.0},"type":"SCALAR"},{"allocation_info":{"role":"superhero"},"name":"disk","scalar":{"value":1026873.0},"type":"SCALAR"},{"allocation_info":{"role":"superhero"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 at slave(148)@172.17.0.2:39397 (4ec4734f0528) 3: I1017 23:03:38.022909 16686 master.cpp:4879] Launching task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 (framework-superhero) at [email protected]:39397 with resources [{"allocation_info":{"role":"superhero"},"name":"cpus","reservations":[{"role":"superhero","type":"STATIC"}],"scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"superhero"},"name":"mem","reservations":[{"role":"superhero","type":"STATIC"}],"scalar":{"value":512.0},"type":"SCALAR"},{"allocation_info":{"role":"superhero"},"name":"disk","scalar":{"value":1026873.0},"type":"SCALAR"},{"allocation_info":{"role":"superhero"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 at slave(148)@172.17.0.2:39397 (4ec4734f0528) 3: I1017 23:03:38.023720 16680 slave.cpp:1747] Got assigned task '1' for framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: I1017 23:03:38.024930 16680 slave.cpp:2015] Authorizing task '1' for framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: I1017 23:03:38.025104 16680 slave.cpp:6809] Authorizing framework principal 'test-principal' to launch task 1 3: I1017 23:03:38.026142 16685 slave.cpp:2183] Launching task '1' for framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: I1017 23:03:38.026813 16683 hierarchical.cpp:887] Updated allocation of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 on agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 from cpus(allocated: superhero)(reservations: [(STATIC,superhero)]):2; mem(allocated: superhero)(reservations: [(STATIC,superhero)]):512; disk(allocated: superhero):1026873; ports(allocated: superhero):[31000-32000] to cpus(allocated: superhero)(reservations: [(STATIC,superhero)]):2; mem(allocated: superhero)(reservations: [(STATIC,superhero)]):512; disk(allocated: superhero):1026873; ports(allocated: superhero):[31000-32000] 3: I1017 23:03:38.027263 16685 paths.cpp:605] Trying to chown '/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_K4EUkG/slaves/55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0/frameworks/55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000/executors/test-executor-superhero/runs/87e5b2a0-53d6-47b5-add6-16d425d7ed20' to user 'bar' 3: W1017 23:03:38.027528 16685 paths.cpp:613] Failed to chown executor directory '/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_K4EUkG/slaves/55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0/frameworks/55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000/executors/test-executor-superhero/runs/87e5b2a0-53d6-47b5-add6-16d425d7ed20'. This may be due to attempting to run the executor as a nonexistent user on the agent; see the description for the `--switch_user` flag for more information: Failed to get user information for 'bar': Success 3: I1017 23:03:38.027825 16685 slave.cpp:7283] Launching executor 'test-executor-superhero' of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 with resources [] in work directory '/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_K4EUkG/slaves/55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0/frameworks/55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000/executors/test-executor-superhero/runs/87e5b2a0-53d6-47b5-add6-16d425d7ed20' 3: I1017 23:03:38.029039 16685 slave.cpp:2411] Queued task '1' for executor 'test-executor-superhero' of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: I1017 23:03:38.029173 16685 slave.cpp:944] Successfully attached '/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_K4EUkG/slaves/55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0/frameworks/55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000/executors/test-executor-superhero/runs/87e5b2a0-53d6-47b5-add6-16d425d7ed20' to virtual path '/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_K4EUkG/slaves/55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0/frameworks/55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000/executors/test-executor-superhero/runs/latest' 3: I1017 23:03:38.029474 16685 slave.cpp:944] Successfully attached '/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_K4EUkG/slaves/55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0/frameworks/55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000/executors/test-executor-superhero/runs/87e5b2a0-53d6-47b5-add6-16d425d7ed20' to virtual path '/frameworks/55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000/executors/test-executor-superhero/runs/latest' 3: I1017 23:03:38.029579 16685 slave.cpp:944] Successfully attached '/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_K4EUkG/slaves/55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0/frameworks/55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000/executors/test-executor-superhero/runs/87e5b2a0-53d6-47b5-add6-16d425d7ed20' to virtual path '/tmp/SlaveAuthorizerTest_1_FilterStateEndpoint_K4EUkG/slaves/55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0/frameworks/55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000/executors/test-executor-superhero/runs/87e5b2a0-53d6-47b5-add6-16d425d7ed20' 3: I1017 23:03:38.030273 16685 slave.cpp:2874] Launching container 87e5b2a0-53d6-47b5-add6-16d425d7ed20 for executor 'test-executor-superhero' of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: @ 0x7ff29a0a71c4 process::ProcessManager::resume() 3: W1017 23:03:38.033097 16679 process.cpp:3194] Attempted to spawn already running process [email protected]:39397 3: I1017 23:03:38.033473 16679 exec.cpp:162] Version: 1.5.0 3: I1017 23:03:38.033922 16682 exec.cpp:212] Executor started at: executor(43)@172.17.0.2:39397 with pid 16678 3: I1017 23:03:38.034204 16682 slave.cpp:3941] Got registration for executor 'test-executor-superhero' of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 from executor(43)@172.17.0.2:39397 3: I1017 23:03:38.035056 16680 exec.cpp:237] Executor registered on agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 3: I1017 23:03:38.035190 16680 exec.cpp:249] Executor::registered took 76926ns 3: I1017 23:03:38.035620 16685 slave.cpp:2613] Sending queued task '1' to executor 'test-executor-superhero' of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 at executor(43)@172.17.0.2:39397 3: I1017 23:03:38.036420 16681 exec.cpp:331] Executor asked to run task '1' 3: @ 0x7ff29a0a384c _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv 3: I1017 23:03:38.036511 16681 exec.cpp:340] Executor::launchTask took 64956ns 3: I1017 23:03:38.036604 16681 exec.cpp:571] Executor sending status update TASK_RUNNING (UUID: f9ef35f8-860a-40f0-b3c9-d18171ea7f4e) for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: I1017 23:03:38.037114 16679 slave.cpp:4395] Handling status update TASK_RUNNING (UUID: f9ef35f8-860a-40f0-b3c9-d18171ea7f4e) for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 from executor(43)@172.17.0.2:39397 3: I1017 23:03:38.037986 16682 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: f9ef35f8-860a-40f0-b3c9-d18171ea7f4e) for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: I1017 23:03:38.038035 16682 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: I1017 23:03:38.038681 16682 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: f9ef35f8-860a-40f0-b3c9-d18171ea7f4e) for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 to the agent 3: I1017 23:03:38.039132 16682 slave.cpp:4876] Forwarding the update TASK_RUNNING (UUID: f9ef35f8-860a-40f0-b3c9-d18171ea7f4e) for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 to [email protected]:39397 3: I1017 23:03:38.039336 16682 slave.cpp:4770] Status update manager successfully handled status update TASK_RUNNING (UUID: f9ef35f8-860a-40f0-b3c9-d18171ea7f4e) for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: I1017 23:03:38.039412 16682 slave.cpp:4786] Sending acknowledgement for status update TASK_RUNNING (UUID: f9ef35f8-860a-40f0-b3c9-d18171ea7f4e) for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 to executor(43)@172.17.0.2:39397 3: I1017 23:03:38.039695 16681 exec.cpp:388] Executor received status update acknowledgement f9ef35f8-860a-40f0-b3c9-d18171ea7f4e for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: I1017 23:03:38.040135 16685 master.cpp:6993] Status update TASK_RUNNING (UUID: f9ef35f8-860a-40f0-b3c9-d18171ea7f4e) for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 from agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 at slave(148)@172.17.0.2:39397 (4ec4734f0528) 3: I1017 23:03:38.040220 16685 master.cpp:7055] Forwarding status update TASK_RUNNING (UUID: f9ef35f8-860a-40f0-b3c9-d18171ea7f4e) for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: I1017 23:03:38.040735 16685 master.cpp:9157] Updating the state of task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) 3: @ 0x7ff29a0b68f2 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE 3: @ 0x7ff29a0b6836 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv 3: @ 0x7ff29a0b67c0 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv 3: I1017 23:03:38.044956 16679 sched.cpp:1025] Scheduler::statusUpdate took 3.91463ms 3: @ 0x7ff29521a2b0 (unknown) 3: I1017 23:03:38.045667 16686 master.cpp:5566] Processing ACKNOWLEDGE call f9ef35f8-860a-40f0-b3c9-d18171ea7f4e for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 (framework-superhero) at [email protected]:39397 on agent 55462fea-3e21-4ac5-ba72-6a02f5d3787f-S0 3: I1017 23:03:38.046066 16681 status_update_manager.cpp:395] Received status update acknowledgement (UUID: f9ef35f8-860a-40f0-b3c9-d18171ea7f4e) for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: W1017 23:03:38.046317 16678 process.cpp:3194] Attempted to spawn already running process [email protected]:39397 3: I1017 23:03:38.046452 16686 slave.cpp:3679] Status update manager successfully handled status update acknowledgement (UUID: f9ef35f8-860a-40f0-b3c9-d18171ea7f4e) for task 1 of framework 55462fea-3e21-4ac5-ba72-6a02f5d3787f-0000 3: @ 0x7ff298cf4e25 start_thread 3: I1017 23:03:38.047890 16678 sched.cpp:232] Version: 1.5.0 3: I1017 23:03:38.048975 16683 sched.cpp:336] New master detected at [email protected]:39397 3: I1017 23:03:38.049082 16683 sched.cpp:396] Authenticating with master [email protected]:39397 3: I1017 23:03:38.049096 16683 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1017 23:03:38.049603 16680 authenticatee.cpp:121] Creating new client SASL connection 3: I1017 23:03:38.050307 16681 master.cpp:7936] Authenticating [email protected]:39397 3: I1017 23:03:38.050554 16685 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(351)@172.17.0.2:39397 3: I1017 23:03:38.051002 16682 authenticator.cpp:98] Creating new server SASL connection 3: I1017 23:03:38.051344 16682 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1017 23:03:38.051373 16682 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1017 23:03:38.051473 16682 authenticator.cpp:204] Received SASL authentication start 3: I1017 23:03:38.051606 16682 authenticator.cpp:326] Authentication requires more steps 3: I1017 23:03:38.051730 16682 authenticatee.cpp:259] Received SASL authentication step 3: I1017 23:03:38.052011 16682 authenticator.cpp:232] Received SASL authentication step 3: I1017 23:03:38.052121 16682 auxprop.cpp:109] Request to lookup properties for user: 'test-principal-2' realm: '4ec4734f0528' server FQDN: '4ec4734f0528' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1017 23:03:38.052142 16682 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1017 23:03:38.052191 16682 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1017 23:03:38.052208 16682 auxprop.cpp:109] Request to lookup properties for user: 'test-principal-2' realm: '4ec4734f0528' server FQDN: '4ec4734f0528' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1017 23:03:38.052217 16682 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1017 23:03:38.052220 16682 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1017 23:03:38.052230 16682 authenticator.cpp:318] Authentication success 3: I1017 23:03:38.052314 16681 authenticatee.cpp:299] Authentication success 3: @ 0x7ff29498234d __clone 3/3 Test #3: MesosTests .......................***Exception: SegFault 88.48 sec 67% tests passed, 1 tests failed out of 3 Total Test time (real) = 122.97 sec The following tests FAILED: 3 - MesosTests (SEGFAULT) Errors while running CTest make[3]: *** [CMakeFiles/check] Error 8 make[3]: Leaving directory `/mesos/build' make[2]: *** [CMakeFiles/check.dir/all] Error 2 make[2]: Leaving directory `/mesos/build' make[1]: *** [CMakeFiles/check.dir/rule] Error 2 make[1]: Leaving directory `/mesos/build' make: *** [check] Error 2 + docker rmi mesos-1508278487-17468 Untagged: mesos-1508278487-17468:latest Deleted: sha256:698b1ea23f6e313eaae0feb4713d66d577aeb1234ff48c3ebf1b759fb611cd09 Deleted: sha256:64f9f0405a1daed8105b7a8197b95eb6e42a7897d516563cd69ed2982a64f138 Deleted: sha256:fa5ab3cd9606b696763a71a933ec5a2f100a3f66c52f5ca772f729aac0aa04f7 Deleted: sha256:2cc151ab2bf811ff876da14cfe6ba075bae68a65085b1ec8fdd250832640cbc5 Deleted: sha256:11943ec3ef175aa905707b86f76e578c03bf1baf693aa0c61d3db33c23e33120 Deleted: sha256:85502a76dcd5637c6cb0c8dd350ede012416f43c8ceda3ca39b7c745ca857fc7 Deleted: sha256:4786e4789403f371535ef8246fff76e404bab2632bd83c3d4db7d2da9875c49d Deleted: sha256:2c45f66ff378215dbac3a0333b058a272fb5ac5a959dd41b9b09d190c280e559 Deleted: sha256:6c5f479cf58c7841c55df29ac788cb81dcfd642c4c6239771eb7b80db7cb3fc7 Deleted: sha256:24cc253dd83d029790a72b8a4458952fa41309a18b26f37c56a156af459260ad Deleted: sha256:c42ce8c5d066f9929b8644f317a85a3c7d63b9b122536254ce6019c59c7637c4 Deleted: sha256:63687213fc44da153eb4f7972be8f91ae81b7e1c609f35d8f4cdc23b4de83517 Deleted: sha256:8d678ebfc5cf88806f879ff6d422f6fb91aab850fcc8e70f6a0d4ccd292f2d97 Deleted: sha256:d3f072da182030bb977fe5b0342b9f0dbf58920eae7af80fa8cfdd095ef9217a Deleted: sha256:21d99d4a55af292751b38a7c745c65cb17cc1459439ff7775e7f0f58d474197d Deleted: sha256:e70ce3a5b66f13b01753363bf31a8b7d4c42720d2273f1f4da87498b6b7b0eba Deleted: sha256:272a9df8d1393d448ad48db77d3cc18e3519d548f9e4763d35cbe34342ad804a Deleted: sha256:3b44c1f99f2fff1a70efaacc9a657706a321a87b4e371bb025e1b84e9a92a21d Deleted: sha256:d48197ce2b2b05fa082c0960074a9e8cd730eb52f559539363c470d26cb8b42a Deleted: sha256:b07e4d3d12fa9b2c8fc1626f1869a8c6382544111aefdb5dafcf445f36d5bf6d Deleted: sha256:415ffd1b6275bc2b89e65201d762702033b36c339704113bd785712813ef8b7e Deleted: sha256:f90a1eb7e88da27f7f7e44447abc2f34c739962fcf538d6aea8171ee4f54e407 Deleted: sha256:2ccf68baffb44a4c38a7d5a3cb333c460f5e7743fe4a1a3bf4839faecef4db1d Deleted: sha256:b1ebe4f40a89bb9c080e096f38aa406b42f878adf12e63e3c4705dd43217e51d Deleted: sha256:49e9c7646a942904923109a1c036e03537236b47f396e52f0bae247ed5586b1b Deleted: sha256:6a1423df59893e6b55663d76bad773f31f3e9434d440a89e27e0b7a9274674ef Deleted: sha256:5b92b214008ff73ac7464841cba9d85512b40c457e85a3efcc0d8ccad75aa1f7 Deleted: sha256:86b59c25628ef265630428adceb2be8d95bb5dbdb845b77804288434a41454dd Deleted: sha256:54d822376c877ca434ae5f06283c53c38a812f50c3bc285f6ae964f96369516c Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected]
