You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/07/04 07:28:15 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3862
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3862/display/redirect>
------------------------------------------
[...truncated 3.18 MB...]
I0704 07:27:56.157881 32094 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover
I0704 07:27:56.158442 32080 replica.cpp:537] Replica received write request for position 2 from __req_res__(174)@172.17.0.5:33842
I0704 07:27:56.158686 32080 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 207561ns
I0704 07:27:56.158709 32080 replica.cpp:708] Persisted action TRUNCATE at position 2
I0704 07:27:56.159276 32089 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0704 07:27:56.159528 32089 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 220082ns
I0704 07:27:56.159593 32089 leveldb.cpp:399] Deleting ~1 keys from leveldb took 38812ns
I0704 07:27:56.159617 32089 replica.cpp:708] Persisted action TRUNCATE at position 2
I0704 07:27:56.162654 32058 containerizer.cpp:200] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0704 07:27:56.163221 32058 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W0704 07:27:56.163352 32058 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 07:27:56.166780 32098 slave.cpp:208] Mesos agent started on 72.17.0.5:33842
I0704 07:27:56.166805 32098 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/HTTPCommandExecutorTest_TerminateWithACK_pravej/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/HTTPCommandExecutorTest_TerminateWithACK_pravej/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="true" --http_credentials="/tmp/HTTPCommandExecutorTest_TerminateWithACK_pravej/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.1.3/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/HTTPCommandExecutorTest_TerminateWithACK_pravej" --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/HTTPCommandExecutorTest_TerminateWithACK_PASuqq"
I0704 07:27:56.167300 32098 credentials.hpp:86] Loading credential for authentication from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_pravej/credential'
I0704 07:27:56.167471 32098 slave.cpp:346] Agent using credential for: test-principal
I0704 07:27:56.167508 32098 credentials.hpp:37] Loading credentials for authentication from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_pravej/http_credentials'
I0704 07:27:56.167716 32098 http.cpp:887] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 07:27:56.167816 32058 sched.cpp:226] Version: 1.1.3
I0704 07:27:56.167934 32098 http.cpp:887] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 07:27:56.168488 32080 sched.cpp:330] New master detected at master@172.17.0.5:33842
I0704 07:27:56.168566 32080 sched.cpp:396] Authenticating with master master@172.17.0.5:33842
I0704 07:27:56.168582 32080 sched.cpp:403] Using default CRAM-MD5 authenticatee
I0704 07:27:56.168781 32082 authenticatee.cpp:121] Creating new client SASL connection
I0704 07:27:56.169031 32077 master.cpp:6680] Authenticating scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:27:56.169068 32098 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 07:27:56.169138 32093 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(32)@172.17.0.5:33842
I0704 07:27:56.169145 32098 slave.cpp:541] Agent attributes: [ ]
I0704 07:27:56.169179 32098 slave.cpp:546] Agent hostname: 9b18639978b2
I0704 07:27:56.169348 32085 authenticator.cpp:98] Creating new server SASL connection
I0704 07:27:56.169566 32083 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0704 07:27:56.169598 32083 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0704 07:27:56.169708 32095 authenticator.cpp:204] Received SASL authentication start
I0704 07:27:56.169773 32095 authenticator.cpp:326] Authentication requires more steps
I0704 07:27:56.169878 32089 authenticatee.cpp:259] Received SASL authentication step
I0704 07:27:56.170075 32088 authenticator.cpp:232] Received SASL authentication step
I0704 07:27:56.170114 32088 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9b18639978b2' server FQDN: '9b18639978b2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0704 07:27:56.170135 32088 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0704 07:27:56.170183 32088 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0704 07:27:56.170214 32088 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9b18639978b2' server FQDN: '9b18639978b2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0704 07:27:56.170233 32088 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0704 07:27:56.170246 32088 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0704 07:27:56.170269 32088 authenticator.cpp:318] Authentication success
I0704 07:27:56.170366 32083 authenticatee.cpp:299] Authentication success
I0704 07:27:56.170420 32089 master.cpp:6710] Successfully authenticated principal 'test-principal' at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:27:56.170586 32080 state.cpp:57] Recovering state from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_PASuqq/meta'
I0704 07:27:56.170635 32079 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(32)@172.17.0.5:33842
I0704 07:27:56.170758 32086 sched.cpp:502] Successfully authenticated with master master@172.17.0.5:33842
I0704 07:27:56.170778 32086 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.5:33842
I0704 07:27:56.171041 32088 status_update_manager.cpp:203] Recovering status update manager
I0704 07:27:56.171049 32086 sched.cpp:853] Will retry registration in 236.794605ms if necessary
I0704 07:27:56.171213 32078 master.cpp:2596] Received SUBSCRIBE call for framework 'default' at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:27:56.171245 32091 containerizer.cpp:555] Recovering containerizer
I0704 07:27:56.171272 32078 master.cpp:2053] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0704 07:27:56.171758 32078 master.cpp:2672] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0704 07:27:56.172371 32082 sched.cpp:743] Framework registered with 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:27:56.172417 32082 sched.cpp:757] Scheduler::registered took 24099ns
I0704 07:27:56.172457 32092 hierarchical.cpp:275] Added framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:27:56.172536 32092 hierarchical.cpp:1694] No allocations performed
I0704 07:27:56.172555 32083 provisioner.cpp:253] Provisioner recovery complete
I0704 07:27:56.172567 32092 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:27:56.172627 32092 hierarchical.cpp:1286] Performed allocation for 0 agents in 144398ns
I0704 07:27:56.172824 32093 slave.cpp:5388] Finished recovery
I0704 07:27:56.173316 32093 slave.cpp:5562] Querying resource estimator for oversubscribable resources
I0704 07:27:56.173523 32093 slave.cpp:915] New master detected at master@172.17.0.5:33842
I0704 07:27:56.173543 32093 slave.cpp:974] Authenticating with master master@172.17.0.5:33842
I0704 07:27:56.173552 32096 status_update_manager.cpp:177] Pausing sending status updates
I0704 07:27:56.173599 32093 slave.cpp:985] Using default CRAM-MD5 authenticatee
I0704 07:27:56.173724 32093 slave.cpp:947] Detecting new master
I0704 07:27:56.173779 32091 authenticatee.cpp:121] Creating new client SASL connection
I0704 07:27:56.174015 32081 master.cpp:6680] Authenticating (1)@172.17.0.5:33842
I0704 07:27:56.174108 32085 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(33)@172.17.0.5:33842
I0704 07:27:56.174299 32086 authenticator.cpp:98] Creating new server SASL connection
I0704 07:27:56.174464 32093 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0704 07:27:56.174485 32093 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0704 07:27:56.174567 32096 authenticator.cpp:204] Received SASL authentication start
I0704 07:27:56.174618 32096 authenticator.cpp:326] Authentication requires more steps
I0704 07:27:56.174700 32084 authenticatee.cpp:259] Received SASL authentication step
I0704 07:27:56.174813 32093 authenticator.cpp:232] Received SASL authentication step
I0704 07:27:56.174846 32093 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9b18639978b2' server FQDN: '9b18639978b2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0704 07:27:56.174865 32093 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0704 07:27:56.174902 32093 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0704 07:27:56.174931 32093 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9b18639978b2' server FQDN: '9b18639978b2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0704 07:27:56.174947 32093 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0704 07:27:56.174960 32093 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0704 07:27:56.174983 32093 authenticator.cpp:318] Authentication success
I0704 07:27:56.175055 32084 authenticatee.cpp:299] Authentication success
I0704 07:27:56.175159 32096 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(33)@172.17.0.5:33842
I0704 07:27:56.175197 32088 master.cpp:6710] Successfully authenticated principal 'test-principal' at (1)@172.17.0.5:33842
I0704 07:27:56.175422 32082 slave.cpp:1069] Successfully authenticated with master master@172.17.0.5:33842
I0704 07:27:56.175590 32082 slave.cpp:1475] Will retry registration in 1.256262ms if necessary
I0704 07:27:56.175736 32089 master.cpp:5103] Registering agent at (1)@172.17.0.5:33842 (9b18639978b2) with id 6d791d4e-39d7-4052-a072-7c54264508ce-S0
I0704 07:27:56.176156 32087 registrar.cpp:461] Applied 1 operations in 53525ns; attempting to update the registry
I0704 07:27:56.176928 32090 log.cpp:577] Attempting to append 337 bytes to the log
I0704 07:27:56.177042 32088 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 3
I0704 07:27:56.177968 32098 slave.cpp:1475] Will retry registration in 10.122728ms if necessary
I0704 07:27:56.177980 32078 replica.cpp:537] Replica received write request for position 3 from __req_res__(175)@172.17.0.5:33842
I0704 07:27:56.178062 32088 master.cpp:5091] Ignoring register agent message from (1)@172.17.0.5:33842 (9b18639978b2) as admission is already in progress
I0704 07:27:56.182516 32078 leveldb.cpp:341] Persisting action (356 bytes) to leveldb took 4.495053ms
I0704 07:27:56.182559 32078 replica.cpp:708] Persisted action APPEND at position 3
I0704 07:27:56.183100 32079 replica.cpp:691] Replica received learned notice for position 3 from @0.0.0.0:0
I0704 07:27:56.183413 32079 leveldb.cpp:341] Persisting action (358 bytes) to leveldb took 285383ns
I0704 07:27:56.183434 32079 replica.cpp:708] Persisted action APPEND at position 3
I0704 07:27:56.184710 32091 registrar.cpp:506] Successfully updated the registry in 8.498944ms
I0704 07:27:56.184957 32098 log.cpp:596] Attempting to truncate the log to 3
I0704 07:27:56.185055 32084 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4
I0704 07:27:56.185446 32084 slave.cpp:4176] Received ping from slave-observer(8)@172.17.0.5:33842
I0704 07:27:56.185508 32080 master.cpp:5174] Registered agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 at (1)@172.17.0.5:33842 (9b18639978b2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 07:27:56.185650 32084 slave.cpp:1115] Registered with master master@172.17.0.5:33842; given agent ID 6d791d4e-39d7-4052-a072-7c54264508ce-S0
I0704 07:27:56.185684 32084 fetcher.cpp:90] Clearing fetcher cache
I0704 07:27:56.185745 32083 replica.cpp:537] Replica received write request for position 4 from __req_res__(176)@172.17.0.5:33842
I0704 07:27:56.185858 32100 hierarchical.cpp:485] Added agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 (9b18639978b2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0704 07:27:56.185894 32080 status_update_manager.cpp:184] Resuming sending status updates
I0704 07:27:56.186008 32083 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 229547ns
I0704 07:27:56.186025 32083 replica.cpp:708] Persisted action TRUNCATE at position 4
I0704 07:27:56.186049 32084 slave.cpp:1138] Checkpointing SlaveInfo to '/tmp/HTTPCommandExecutorTest_TerminateWithACK_PASuqq/meta/slaves/6d791d4e-39d7-4052-a072-7c54264508ce-S0/slave.info'
I0704 07:27:56.186542 32079 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0
I0704 07:27:56.187110 32100 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:27:56.187182 32100 hierarchical.cpp:1309] Performed allocation for agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 in 1.282152ms
I0704 07:27:56.187630 32093 master.cpp:6509] Sending 1 offers to framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:27:56.188221 32080 sched.cpp:917] Scheduler::resourceOffers took 97242ns
I0704 07:27:56.190575 32078 master.cpp:3554] Processing ACCEPT call for offers: [ 6d791d4e-39d7-4052-a072-7c54264508ce-O0 ] on agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 at (1)@172.17.0.5:33842 (9b18639978b2) for framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:27:56.190675 32078 master.cpp:3159] Authorizing framework principal 'test-principal' to launch task 2490f8c2-e633-498b-9a57-78693d0c0786
I0704 07:27:56.190814 32079 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 4.241141ms
I0704 07:27:56.190897 32079 leveldb.cpp:399] Deleting ~2 keys from leveldb took 55186ns
I0704 07:27:56.190922 32079 replica.cpp:708] Persisted action TRUNCATE at position 4
I0704 07:27:56.193218 32078 master.cpp:8262] Adding task 2490f8c2-e633-498b-9a57-78693d0c0786 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 (9b18639978b2)
I0704 07:27:56.193470 32078 master.cpp:4192] Launching task 2490f8c2-e633-498b-9a57-78693d0c0786 of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 at (1)@172.17.0.5:33842 (9b18639978b2)
I0704 07:27:56.194241 32093 slave.cpp:1539] Got assigned task '2490f8c2-e633-498b-9a57-78693d0c0786' for framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:27:56.195170 32093 slave.cpp:1701] Launching task '2490f8c2-e633-498b-9a57-78693d0c0786' for framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:27:56.195817 32093 paths.cpp:536] Trying to chown '/tmp/HTTPCommandExecutorTest_TerminateWithACK_PASuqq/slaves/6d791d4e-39d7-4052-a072-7c54264508ce-S0/frameworks/6d791d4e-39d7-4052-a072-7c54264508ce-0000/executors/2490f8c2-e633-498b-9a57-78693d0c0786/runs/765e883a-cb63-4045-8704-8e17c781b5f8' to user 'mesos'
I0704 07:27:56.201314 32093 slave.cpp:6286] Launching executor '2490f8c2-e633-498b-9a57-78693d0c0786' of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/HTTPCommandExecutorTest_TerminateWithACK_PASuqq/slaves/6d791d4e-39d7-4052-a072-7c54264508ce-S0/frameworks/6d791d4e-39d7-4052-a072-7c54264508ce-0000/executors/2490f8c2-e633-498b-9a57-78693d0c0786/runs/765e883a-cb63-4045-8704-8e17c781b5f8'
I0704 07:27:56.202368 32084 containerizer.cpp:938] Starting container 765e883a-cb63-4045-8704-8e17c781b5f8 for executor '2490f8c2-e633-498b-9a57-78693d0c0786' of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:27:56.202841 32093 slave.cpp:1987] Queued task '2490f8c2-e633-498b-9a57-78693d0c0786' for executor '2490f8c2-e633-498b-9a57-78693d0c0786' of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:27:56.202970 32093 slave.cpp:868] Successfully attached file '/tmp/HTTPCommandExecutorTest_TerminateWithACK_PASuqq/slaves/6d791d4e-39d7-4052-a072-7c54264508ce-S0/frameworks/6d791d4e-39d7-4052-a072-7c54264508ce-0000/executors/2490f8c2-e633-498b-9a57-78693d0c0786/runs/765e883a-cb63-4045-8704-8e17c781b5f8'
I0704 07:27:56.208575 32092 containerizer.cpp:1463] Launching 'mesos-containerizer' with flags '--command="{"arguments":["mesos-executor","--launcher_dir=\/mesos\/mesos-1.1.3\/_build\/src"],"shell":false,"value":"\/mesos\/mesos-1.1.3\/_build\/src\/mesos-executor"}" --help="false" --pipe_read="9" --pipe_write="12" --pre_exec_commands="[]" --runtime_directory="/tmp/HTTPCommandExecutorTest_TerminateWithACK_pravej/containers/765e883a-cb63-4045-8704-8e17c781b5f8" --unshare_namespace_mnt="false" --user="mesos" --working_directory="/tmp/HTTPCommandExecutorTest_TerminateWithACK_PASuqq/slaves/6d791d4e-39d7-4052-a072-7c54264508ce-S0/frameworks/6d791d4e-39d7-4052-a072-7c54264508ce-0000/executors/2490f8c2-e633-498b-9a57-78693d0c0786/runs/765e883a-cb63-4045-8704-8e17c781b5f8"'
I0704 07:27:56.210381 32092 launcher.cpp:127] Forked child with pid '32115' for container '765e883a-cb63-4045-8704-8e17c781b5f8'
I0704 07:27:57.139818 32093 hierarchical.cpp:1694] No allocations performed
I0704 07:27:57.139900 32093 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:27:57.139986 32093 hierarchical.cpp:1286] Performed allocation for 1 agents in 435266ns
I0704 07:27:58.140571 32083 hierarchical.cpp:1694] No allocations performed
I0704 07:27:58.140648 32083 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:27:58.140739 32083 hierarchical.cpp:1286] Performed allocation for 1 agents in 419966ns
I0704 07:27:59.141898 32089 hierarchical.cpp:1694] No allocations performed
I0704 07:27:59.141978 32089 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:27:59.142068 32089 hierarchical.cpp:1286] Performed allocation for 1 agents in 401222ns
I0704 07:28:00.143640 32098 hierarchical.cpp:1694] No allocations performed
I0704 07:28:00.143754 32098 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:00.143851 32098 hierarchical.cpp:1286] Performed allocation for 1 agents in 517320ns
I0704 07:28:01.145193 32099 hierarchical.cpp:1694] No allocations performed
I0704 07:28:01.145306 32099 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:01.145396 32099 hierarchical.cpp:1286] Performed allocation for 1 agents in 486141ns
I0704 07:28:02.146589 32084 hierarchical.cpp:1694] No allocations performed
I0704 07:28:02.146692 32084 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:02.146796 32084 hierarchical.cpp:1286] Performed allocation for 1 agents in 532411ns
I0704 07:28:03.148078 32083 hierarchical.cpp:1694] No allocations performed
I0704 07:28:03.148185 32083 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:03.148296 32083 hierarchical.cpp:1286] Performed allocation for 1 agents in 471537ns
I0704 07:28:04.148949 32086 hierarchical.cpp:1694] No allocations performed
I0704 07:28:04.149031 32086 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:04.149117 32086 hierarchical.cpp:1286] Performed allocation for 1 agents in 386492ns
I0704 07:28:05.150135 32100 hierarchical.cpp:1694] No allocations performed
I0704 07:28:05.150220 32100 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:05.150305 32100 hierarchical.cpp:1286] Performed allocation for 1 agents in 392771ns
I0704 07:28:06.151582 32077 hierarchical.cpp:1694] No allocations performed
I0704 07:28:06.151682 32077 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:06.151760 32077 hierarchical.cpp:1286] Performed allocation for 1 agents in 471986ns
I0704 07:28:07.152446 32097 hierarchical.cpp:1694] No allocations performed
I0704 07:28:07.152531 32097 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:07.152622 32097 hierarchical.cpp:1286] Performed allocation for 1 agents in 373205ns
I0704 07:28:08.154006 32080 hierarchical.cpp:1694] No allocations performed
I0704 07:28:08.154098 32080 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:08.154191 32080 hierarchical.cpp:1286] Performed allocation for 1 agents in 446651ns
I0704 07:28:09.155745 32096 hierarchical.cpp:1694] No allocations performed
I0704 07:28:09.155843 32096 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:09.155939 32096 hierarchical.cpp:1286] Performed allocation for 1 agents in 469684ns
I0704 07:28:10.157173 32094 hierarchical.cpp:1694] No allocations performed
I0704 07:28:10.157256 32094 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:10.157346 32094 hierarchical.cpp:1286] Performed allocation for 1 agents in 397009ns
I0704 07:28:11.158957 32088 hierarchical.cpp:1694] No allocations performed
I0704 07:28:11.159062 32088 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:11.159179 32088 hierarchical.cpp:1286] Performed allocation for 1 agents in 529641ns
I0704 07:28:11.186323 32079 slave.cpp:4176] Received ping from slave-observer(8)@172.17.0.5:33842
../../src/tests/command_executor_tests.cpp:280: Failure
Failed to wait 15secs for statusRunning
I0704 07:28:11.190170 32077 master.cpp:1297] Framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842 disconnected
I0704 07:28:11.190217 32077 master.cpp:2904] Disconnecting framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:28:11.190254 32077 master.cpp:2928] Deactivating framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:28:11.190367 32077 master.cpp:1310] Giving framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842 0ns to failover
I0704 07:28:11.190466 32095 hierarchical.cpp:386] Deactivated framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
../../src/tests/command_executor_tests.cpp:269: Failure
Actual function call count doesn't match EXPECT_CALL(sched, statusUpdate(_, _))...
Expected: to be called twice
Actual: never called - unsatisfied and active
I0704 07:28:11.190675 32086 master.cpp:6361] Framework failover timeout, removing framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:28:11.190713 32086 master.cpp:7095] Removing framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:28:11.191083 32092 slave.cpp:2511] Asked to shut down framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 by master@172.17.0.5:33842
I0704 07:28:11.191143 32092 slave.cpp:2536] Shutting down framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:28:11.191262 32086 master.cpp:7640] Updating the state of task 2490f8c2-e633-498b-9a57-78693d0c0786 of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0704 07:28:11.191349 32092 slave.cpp:4903] Shutting down executor '2490f8c2-e633-498b-9a57-78693d0c0786' of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
W0704 07:28:11.191376 32092 slave.hpp:870] Unable to send event to executor '2490f8c2-e633-498b-9a57-78693d0c0786' of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000: unknown connection type
../../src/tests/command_executor_tests.cpp:274: Failure
Actual function call count doesn't match EXPECT_CALL(slave, executorTerminated(_, _, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
I0704 07:28:11.192728 32087 hierarchical.cpp:1018] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 from framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:28:11.192760 32086 master.cpp:7736] Removing task 2490f8c2-e633-498b-9a57-78693d0c0786 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 on agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 at (1)@172.17.0.5:33842 (9b18639978b2)
*** Aborted at 1499153291 (unix time) try "date -d @1499153291" if you are using GNU date ***
I0704 07:28:11.193471 32092 hierarchical.cpp:337] Removed framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
PC: @ 0x0 (unknown)
*** SIGSEGV (@0x0) received by PID 32058 (TID 0x7fa9735ef700) from PID 0; stack trace: ***
@ 0x7fa97f3e7370 (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.1.3/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.1.3/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.1.3/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.1.3/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1499151727-4918
Untagged: mesos-1499151727-4918:latest
Deleted: sha256:95ae829b1a358fe190e9d1b17fb7e950e6e6d090c41500a080f6be8641ac5c85
Deleted: sha256:721dc47f34c2077525c2f5983c2b52fcba99870780415b2e7bef66437eabd3b8
Deleted: sha256:f0e0a1c2aecc68372b01ecf66fb36aad2efeaaf74fcc7e57f3e3c189f5c7b743
Deleted: sha256:3402ae1e02eb76dcea81f4bc0c33ecee555ec673aa844290854240e17f277d7c
Deleted: sha256:4d1c2c56f8587ea44266fa6c08a777658aef164a9871342d8eea88ef96f2c0b4
Deleted: sha256:93d3fc5b13ce18bac9687da87713500bad46de60efbbe34ea5a18796dab6a11f
Deleted: sha256:7c806565801854dd3c0fe473031e35ad0895605d7be02eb6a129b76667b7527b
Deleted: sha256:ffa31924615f3350907caaac461c40ea4acfa6628c28940c2c9d05cf796453c1
Deleted: sha256:2cc36820e92225e957c816a6ad13dbc6dab2106518f8ab2bdc4420b43a197494
Deleted: sha256:6ab060a20a9b8262978611a77923104b399932e193c26baeb5c4987b0c06b77c
Deleted: sha256:b605c420cd834faa2804daa87733385df9d9612857a2d0926d901f433055370a
Deleted: sha256:294dd0ee86576519cbff98a509f0bb52d592a909fb65d04514b9d3a20447d8d7
Deleted: sha256:38a24dce19fd25d15fd18b883e1809631ff3bd213eb646f90f89272487871366
Deleted: sha256:40d12d0f71885f2442d9951f35fd355d498074af58cd12f946e03916faa523bf
Deleted: sha256:f37c9406df3ed6d6bc8b19935fb885bd1739c556c5e88b5f224191d18126595e
Deleted: sha256:0415c6535bc5de93127b176fe1ff118e8c894e9cb0502dc9d11d44484ec2f752
Deleted: sha256:d6d73c6eac613b3d918deab31aa4cc8b0af4f4dc40366eafe5ac8edf96966f16
Deleted: sha256:1843c16b950a416162f83e0206c0036827fae1885195cbb6f09d9feb7bf5dc57
Deleted: sha256:6cc5dc91c33e9ed8cc183eeca7d0507c2c793bdf9d290ec8b178a8e885c5cf37
Deleted: sha256:4f51205e46c902c967247dcafdcbbacc4741ebd7a8374aa0be7fcdea758db9d6
Deleted: sha256:3d841c2785306dc94e070604836479cce458f79232c55428dbd06995cd7b8690
Deleted: sha256:721b2d53a1294be51c9290548dfbd5746c7280b4e22dc2f624eb1313dd9b81fe
Deleted: sha256:8bc3f12ebe1e12e104b79cbc81777053dbca934a7b0dea2d91b17a8ab73ef58c
Deleted: sha256:fb52122868a8d9234be927616b3f14318e84e97cee1708cae2fb4836fad768f2
Deleted: sha256:bba8d4ae1145015dc838d2c1e44275b50da5277b40a15af15cfe510603977925
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user toenshoff@me.com
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3865
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3865/display/redirect?page=changes>
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3864
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3864/display/redirect?page=changes>
Changes:
[mpark] Updated `accept` to perform operation adjustment in one place.
[mpark] Updated `validateAndNormalizeResources` to operate on `Operation`s.
[mpark] Performed validation/normalization of `Resource`s before authorization.
[mpark] Validated and normalized resources on the V1 operator API path.
------------------------------------------
[...truncated 2.69 MB...]
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:21.021195 2972 libevent_ssl_socket.cpp:1170] Socket error: error:140760FC:SSL routines:SSL23_GET_CLIENT_HELLO:unknown protocol
I0705 11:20:21.108685 2947 ssl_tests.cpp:638] Testing server protocol 'LIBPROCESS_SSL_ENABLE_TLS_V1_1'
I0705 11:20:21.109408 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:21.109428 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:21.109436 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:21.109443 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:21.129014 2972 libevent_ssl_socket.cpp:1170] Socket error: error:140760FC:SSL routines:SSL23_GET_CLIENT_HELLO:unknown protocol
I0705 11:20:21.209233 2947 ssl_tests.cpp:638] Testing server protocol 'LIBPROCESS_SSL_ENABLE_TLS_V1_2'
I0705 11:20:21.209983 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:21.210026 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:21.210034 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:21.210041 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:21.223760 2972 libevent_ssl_socket.cpp:1170] Socket error: error:140760FC:SSL routines:SSL23_GET_CLIENT_HELLO:unknown protocol
[ OK ] SSLTest.NoValidDowngradeEachProtocol (604 ms)
[ RUN ] SSLTest.PeerAddress
I0705 11:20:21.806216 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:21.806246 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:21.806254 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:21.806262 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
[ OK ] SSLTest.PeerAddress (503 ms)
[ RUN ] SSLTest.HTTPSGet
I0705 11:20:22.182507 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:22.182548 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:22.182557 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:22.182565 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
[ OK ] SSLTest.HTTPSGet (376 ms)
[ RUN ] SSLTest.HTTPSPost
I0705 11:20:22.291604 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:22.291671 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:22.291681 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:22.291687 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
[ OK ] SSLTest.HTTPSPost (112 ms)
[ RUN ] SSLTest.SilentSocket
I0705 11:20:22.418620 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:22.418645 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:22.418651 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:22.418656 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:22.424218 2972 libevent_ssl_socket.cpp:1170] Socket error: error:00000000:lib(0):func(0):reason(0)
[ OK ] SSLTest.SilentSocket (123 ms)
[----------] 15 tests from SSLTest (12081 ms total)
[----------] 42 tests from Scheme/HTTPTest
[ RUN ] Scheme/HTTPTest.Endpoints/0
I0705 11:20:22.656047 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:22.656075 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:22.656080 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:22.656085 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:22.656484 2972 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:22.660854 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:35869 with 24 worker threads
I0705 11:20:22.667027 4022 process.cpp:3779] Handling HTTP event for process '(74)' with path: '/(74)/body'
I0705 11:20:22.671514 4023 process.cpp:3779] Handling HTTP event for process '(74)' with path: '/(74)/pipe'
I0705 11:20:22.676558 4038 process.cpp:3779] Handling HTTP event for process '(74)' with path: '/(74)/body'
I0705 11:20:22.677114 4039 process.cpp:3836] Failed to process request for '/(74)/body': failure
I0705 11:20:22.677145 4031 process.cpp:1495] Returning '500 Internal Server Error' for '/(74)/body' (failure)
[ OK ] Scheme/HTTPTest.Endpoints/0 (254 ms)
[ RUN ] Scheme/HTTPTest.Endpoints/1
I0705 11:20:22.681447 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:45441 with 24 worker threads
I0705 11:20:22.682745 4051 process.cpp:3779] Handling HTTP event for process '(75)' with path: '/(75)/body'
I0705 11:20:22.684763 4054 process.cpp:3779] Handling HTTP event for process '(75)' with path: '/(75)/pipe'
I0705 11:20:22.687577 4058 process.cpp:3779] Handling HTTP event for process '(75)' with path: '/(75)/body'
I0705 11:20:22.688119 4055 process.cpp:3836] Failed to process request for '/(75)/body': failure
I0705 11:20:22.688148 4045 process.cpp:1495] Returning '500 Internal Server Error' for '/(75)/body' (failure)
I0705 11:20:22.688954 4066 process.cpp:855] Decode failure: Connection reset by peer
E0705 11:20:22.688997 4066 process.cpp:2469] Failed to shutdown socket with fd 9, address 172.17.0.12:45441: Transport endpoint is not connected
[ OK ] Scheme/HTTPTest.Endpoints/1 (11 ms)
[ RUN ] Scheme/HTTPTest.EndpointsHelp/0
I0705 11:20:23.138721 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:23.138746 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:23.138752 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:23.138756 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:23.139199 4066 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:23.142405 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:35821 with 24 worker threads
I0705 11:20:23.150888 4080 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help'
I0705 11:20:23.158167 4079 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help'
I0705 11:20:23.164543 4077 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(76)/body'
I0705 11:20:23.169515 4070 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(76)/a/b/c'
[ OK ] Scheme/HTTPTest.EndpointsHelp/0 (482 ms)
[ RUN ] Scheme/HTTPTest.EndpointsHelp/1
I0705 11:20:23.174764 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:34664 with 24 worker threads
I0705 11:20:23.176110 4097 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help'
I0705 11:20:23.178395 4112 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help'
I0705 11:20:23.181529 4112 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(77)/body'
I0705 11:20:23.183929 4100 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(77)/a/b/c'
[ OK ] Scheme/HTTPTest.EndpointsHelp/1 (14 ms)
[ RUN ] Scheme/HTTPTest.EndpointsHelpRemoval/0
I0705 11:20:23.600616 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:23.600644 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:23.600652 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:23.600657 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:23.601191 4116 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:23.604173 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:34590 with 24 worker threads
I0705 11:20:23.609839 4128 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(78)/body'
I0705 11:20:23.616029 4135 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(78)/bogus'
[ OK ] Scheme/HTTPTest.EndpointsHelpRemoval/0 (432 ms)
[ RUN ] Scheme/HTTPTest.EndpointsHelpRemoval/1
I0705 11:20:23.621642 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:34168 with 24 worker threads
I0705 11:20:23.623296 4142 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(79)/body'
I0705 11:20:23.625905 4161 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(79)/bogus'
[ OK ] Scheme/HTTPTest.EndpointsHelpRemoval/1 (10 ms)
[ RUN ] Scheme/HTTPTest.PipeEOF/0
I0705 11:20:23.901912 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:23.901938 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:23.901944 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:23.901948 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:23.902508 4166 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:23.904866 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:42068 with 24 worker threads
[ OK ] Scheme/HTTPTest.PipeEOF/0 (278 ms)
[ RUN ] Scheme/HTTPTest.PipeEOF/1
I0705 11:20:23.908774 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:45260 with 24 worker threads
[ OK ] Scheme/HTTPTest.PipeEOF/1 (4 ms)
[ RUN ] Scheme/HTTPTest.PipeFailure/0
I0705 11:20:24.117470 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:24.117496 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:24.117501 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:24.117506 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:24.117944 4216 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:24.120388 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:43007 with 24 worker threads
[ OK ] Scheme/HTTPTest.PipeFailure/0 (211 ms)
[ RUN ] Scheme/HTTPTest.PipeFailure/1
I0705 11:20:24.123726 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:36654 with 24 worker threads
[ OK ] Scheme/HTTPTest.PipeFailure/1 (4 ms)
[ RUN ] Scheme/HTTPTest.PipeReaderCloses/0
I0705 11:20:24.236085 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:24.236109 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:24.236115 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:24.236120 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:24.236598 4266 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:24.239130 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:44209 with 24 worker threads
[ OK ] Scheme/HTTPTest.PipeReaderCloses/0 (115 ms)
[ RUN ] Scheme/HTTPTest.PipeReaderCloses/1
I0705 11:20:24.242797 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:32798 with 24 worker threads
[ OK ] Scheme/HTTPTest.PipeReaderCloses/1 (4 ms)
[ RUN ] Scheme/HTTPTest.Encode/0
I0705 11:20:24.500529 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:24.500555 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:24.500560 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:24.500564 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:24.501149 4316 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:24.503741 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:35044 with 24 worker threads
[ OK ] Scheme/HTTPTest.Encode/0 (261 ms)
[ RUN ] Scheme/HTTPTest.Encode/1
I0705 11:20:24.507438 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:33497 with 24 worker threads
[ OK ] Scheme/HTTPTest.Encode/1 (3 ms)
[ RUN ] Scheme/HTTPTest.PathParse/0
I0705 11:20:24.738029 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:24.738071 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:24.738078 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:24.738083 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:24.738646 4366 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:24.741395 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:34915 with 24 worker threads
[ OK ] Scheme/HTTPTest.PathParse/0 (234 ms)
[ RUN ] Scheme/HTTPTest.PathParse/1
*** Aborted at 1499253624 (unix time) try "date -d @1499253624" if you are using GNU date ***
PC: @ 0x7f87bf798912 (unknown)
*** SIGSEGV (@0x7f8771cf6eb8) received by PID 2947 (TID 0x7f87bb836700) from PID 1909419704; stack trace: ***
I0705 11:20:24.744930 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:40474 with 24 worker threads
[ OK ] Scheme/HTTPTest.PathParse/1 (4 ms)
[ RUN ] Scheme/HTTPTest.Get/0
@ 0x7f87c0014370 (unknown)
@ 0x7f87bf798912 (unknown)
@ 0x7f87bf798f8c (unknown)
@ 0x42b1a3 process::UPID::UPID()
@ 0x8fcdec process::DispatchEvent::DispatchEvent()
@ 0x8f5275 process::internal::dispatch()
@ 0x910002 process::dispatch<>()
@ 0x8f4184 process::ProcessBase::route()
@ 0x9e88b9 process::ProcessBase::route<>()
@ 0x9e4bb2 process::Help::initialize()
@ 0x8ed69a process::ProcessManager::resume()
@ 0x8e9a98 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
@ 0x8fc38c _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x8fc2d0 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
@ 0x8fc25a _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x7f87bf78f230 (unknown)
@ 0x7f87c000cdc5 start_thread
@ 0x7f87beef876d __clone
make[7]: *** [check-local] Segmentation fault (core dumped)
make[7]: Leaving directory `/mesos/mesos-1.4.0/_build/3rdparty/libprocess'
make[6]: *** [check-am] Error 2
make[6]: Leaving directory `/mesos/mesos-1.4.0/_build/3rdparty/libprocess'
make[5]: *** [check-recursive] Error 1
make[5]: Leaving directory `/mesos/mesos-1.4.0/_build/3rdparty/libprocess'
make[4]: *** [check] Error 2
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/3rdparty/libprocess'
make[3]: *** [check-recursive] Error 1
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/3rdparty'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/3rdparty'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1499252354-5989
Untagged: mesos-1499252354-5989:latest
Deleted: sha256:d6af4faef13a50b0db6b883453b99c3c90cabf0940c4d40780e35ff37ae67fd3
Deleted: sha256:9616e3f7c405d8eace271afbac88a09431685544ca022400521d28da7edb082e
Deleted: sha256:7bc93d49eddd1eb3bb1d6528fa9665dca1bf339166e5a57498d8f1afda6b71c3
Deleted: sha256:d6eaf8667cac7bc90e5dc00e4638db4a6cf354b84a65ff340215d084f3ddd41a
Deleted: sha256:ee8dde873bdc356dfc66affe95035e3db12aa1e94b885c1e4b26fc101628b004
Deleted: sha256:fb94bf38730267f850197f843ebaeabf88d63ddbb06b445a1b2835654227b013
Deleted: sha256:bd9cdec3a6f34ad1e640346b721a2933ddc184537b134a1bcec2118f17db21b1
Deleted: sha256:b4e74941b34456699fa007d48a5082ef1d8c59cc6ca35578db87aa5a3eabe790
Deleted: sha256:eabaef1315cca628630c4553096e0eb7f2179c8cc56ba4875eadd0692d3e2804
Deleted: sha256:58a218eaf89d899278f998533af46ee3636ef0857920fbabe652d3c9b7b0486d
Deleted: sha256:6e8aa0fcf8e4fdb7b93fe523f466378cb1e45f3f214b012f08927f103490e7a0
Deleted: sha256:97047c3ae048380dffd8ddf00c0ba394b8f4b902d8bde93e9a70235af89e111a
Deleted: sha256:31f3dabf8323b66be0d84c64f93c9fa1b938cbf0216a161b715dfdb2775d8c94
Deleted: sha256:828d96ea899f6d8b397cdda7030b0aeb56e1bd87af2c827aefb07092a4d81653
Deleted: sha256:a01c24500e5d484a4fcd15634516cb8a7270b0e87d49c5f53ae06690f071efa2
Deleted: sha256:d5bf5c08b61dc85b8d30030f035428ecbcadcbccc8b4122229786cc57550e3c2
Deleted: sha256:8f6cdc4a6c3818e009eaad28ce699b7af9372f3d0dd8e1d49cc88d781cc01d28
Deleted: sha256:98bbc09032b57319839c2ba5ebca772a129acbe6e2dee865de37c33f7f4dcc3f
Deleted: sha256:84166cf99e7890fe507df0d9e0304e5719150598a6406dbaac6f51d5a01116c5
Deleted: sha256:bbf10190b4f2155d8d8db1da2a15bb402bc3ced5a58fdb8d2b4b3481a33516d1
Deleted: sha256:31fcdcfede9ee2fcce146ae44ff65a897589f1907a54484f9cea27e7bc96a32a
Deleted: sha256:bbb16be2d407b992c4dedad1cb287219a863607281823f5ee90670b2368e6612
Deleted: sha256:9ecb85f47774e77f03e2aee5333e258a8ee513c88b3ab725b778474dca23144d
Deleted: sha256:50a5b1622f846d757a17cd0605304bf6f90d7d214576636c597f0b53b1c90fde
Deleted: sha256:47e15f92156201b72d4fafb70a5c791ddc1edb949eab3440e4147bfbedfb38a7
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user toenshoff@me.com
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3863
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3863/display/redirect?page=changes>
Changes:
[alexr] Cleaned up logging around subscribers in master.
------------------------------------------
[...truncated 30.68 MB...]
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0
I0704 13:57:25.798975 9187 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0704 13:57:25.799741 9187 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 13:57:25.799917 9187 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 13:57:25.799995 9187 provisioner.cpp:255] Using default backend 'copy'
I0704 13:57:25.806398 29880 slave.cpp:250] Mesos agent started on (776)@172.17.0.6:37756
I0704 13:57:25.806421 29880 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_jOI50W"
I0704 13:57:25.806814 29880 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC/credential'
I0704 13:57:25.807037 29880 slave.cpp:283] Agent using credential for: test-principal
I0704 13:57:25.807061 29880 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC/http_credentials'
I0704 13:57:25.807391 29880 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.807502 29880 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.807729 29880 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.807823 29880 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.808138 29880 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.808297 29880 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.810031 29880 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0704 13:57:25.810389 29880 slave.cpp:573] Agent attributes: [ ]
I0704 13:57:25.810405 29880 slave.cpp:578] Agent hostname: e3db83f9470c
I0704 13:57:25.810575 29889 status_update_manager.cpp:177] Pausing sending status updates
I0704 13:57:25.812430 29877 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_jOI50W/meta'
I0704 13:57:25.812937 29885 status_update_manager.cpp:203] Recovering status update manager
I0704 13:57:25.813163 29892 containerizer.cpp:582] Recovering containerizer
I0704 13:57:25.814903 29885 provisioner.cpp:416] Provisioner recovery complete
I0704 13:57:25.815456 29876 slave.cpp:6189] Finished recovery
I0704 13:57:25.816053 29876 slave.cpp:6371] Querying resource estimator for oversubscribable resources
I0704 13:57:25.816346 29879 slave.cpp:6385] Received oversubscribable resources {} from the resource estimator
I0704 13:57:25.818859 29891 process.cpp:3779] Handling HTTP event for process 'slave(776)' with path: '/slave(776)/monitor/statistics'
I0704 13:57:25.820797 29872 http.cpp:1116] HTTP GET for /slave(776)/monitor/statistics from 172.17.0.6:57620
I0704 13:57:25.820893 29872 http.cpp:937] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0704 13:57:25.823536 29893 slave.cpp:839] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0 (39 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1
I0704 13:57:25.835858 9187 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0704 13:57:25.836654 9187 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 13:57:25.836819 9187 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 13:57:25.836858 9187 provisioner.cpp:255] Using default backend 'copy'
I0704 13:57:25.840900 29890 slave.cpp:250] Mesos agent started on (777)@172.17.0.6:37756
I0704 13:57:25.840926 29890 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_Vzx55D"
I0704 13:57:25.841343 29890 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj/credential'
I0704 13:57:25.841511 29890 slave.cpp:283] Agent using credential for: test-principal
I0704 13:57:25.841536 29890 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj/http_credentials'
I0704 13:57:25.841825 29890 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.841940 29890 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.842170 29890 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.842295 29890 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.842660 29890 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.842782 29890 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.844442 29890 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0704 13:57:25.844719 29890 slave.cpp:573] Agent attributes: [ ]
I0704 13:57:25.844735 29890 slave.cpp:578] Agent hostname: e3db83f9470c
I0704 13:57:25.844892 29879 status_update_manager.cpp:177] Pausing sending status updates
I0704 13:57:25.846472 29882 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_Vzx55D/meta'
I0704 13:57:25.846993 29891 status_update_manager.cpp:203] Recovering status update manager
I0704 13:57:25.847379 29880 containerizer.cpp:582] Recovering containerizer
I0704 13:57:25.848951 29879 provisioner.cpp:416] Provisioner recovery complete
I0704 13:57:25.849292 29874 slave.cpp:6189] Finished recovery
I0704 13:57:25.849738 29874 slave.cpp:6371] Querying resource estimator for oversubscribable resources
I0704 13:57:25.850064 29875 slave.cpp:6385] Received oversubscribable resources {} from the resource estimator
I0704 13:57:25.852149 29874 process.cpp:3779] Handling HTTP event for process 'slave(777)' with path: '/slave(777)/monitor/statistics.json'
I0704 13:57:25.853781 29893 http.cpp:1116] HTTP GET for /slave(777)/monitor/statistics.json from 172.17.0.6:57622
I0704 13:57:25.853873 29893 http.cpp:937] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0704 13:57:25.857033 29892 slave.cpp:839] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1 (33 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2
I0704 13:57:25.868460 9187 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0704 13:57:25.868938 9187 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 13:57:25.869036 9187 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 13:57:25.869068 9187 provisioner.cpp:255] Using default backend 'copy'
I0704 13:57:25.872390 29894 slave.cpp:250] Mesos agent started on (778)@172.17.0.6:37756
I0704 13:57:25.872414 29894 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_hb6Gvl"
I0704 13:57:25.872814 29894 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1/credential'
I0704 13:57:25.872957 29894 slave.cpp:283] Agent using credential for: test-principal
I0704 13:57:25.872982 29894 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1/http_credentials'
I0704 13:57:25.873255 29894 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.873360 29894 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.873669 29894 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.873772 29894 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.873976 29894 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.874078 29894 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.875802 29894 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0704 13:57:25.876044 29894 slave.cpp:573] Agent attributes: [ ]
I0704 13:57:25.876067 29894 slave.cpp:578] Agent hostname: e3db83f9470c
I0704 13:57:25.876207 29878 status_update_manager.cpp:177] Pausing sending status updates
I0704 13:57:25.877689 29880 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_hb6Gvl/meta'
I0704 13:57:25.877959 29879 status_update_manager.cpp:203] Recovering status update manager
I0704 13:57:25.878212 29880 containerizer.cpp:582] Recovering containerizer
I0704 13:57:25.879850 29871 provisioner.cpp:416] Provisioner recovery complete
I0704 13:57:25.880288 29877 slave.cpp:6189] Finished recovery
I0704 13:57:25.880769 29877 slave.cpp:6371] Querying resource estimator for oversubscribable resources
I0704 13:57:25.881140 29885 slave.cpp:6385] Received oversubscribable resources {} from the resource estimator
I0704 13:57:25.883831 29882 process.cpp:3779] Handling HTTP event for process 'slave(778)' with path: '/slave(778)/containers'
I0704 13:57:25.885679 29882 http.cpp:1116] HTTP GET for /slave(778)/containers from 172.17.0.6:57624
I0704 13:57:25.885759 29882 http.cpp:937] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0704 13:57:25.888310 29882 slave.cpp:839] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2 (31 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/0
I0704 13:57:25.901640 9187 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0704 13:57:25.902132 9187 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 13:57:25.902248 9187 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 13:57:25.902284 9187 provisioner.cpp:255] Using default backend 'copy'
I0704 13:57:25.904197 9187 cluster.cpp:448] Creating default 'local' authorizer
I0704 13:57:25.905921 29881 slave.cpp:250] Mesos agent started on (779)@172.17.0.6:37756
I0704 13:57:25.905946 29881 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ/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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ/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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ/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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_bKfte3"
I0704 13:57:25.906358 29881 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ/credential'
I0704 13:57:25.906539 29881 slave.cpp:283] Agent using credential for: test-principal
I0704 13:57:25.906563 29881 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ/http_credentials'
I0704 13:57:25.906842 29881 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.906949 29881 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.907124 29881 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.907382 29881 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.907693 29881 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.907840 29881 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.909729 29881 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0704 13:57:25.910007 29881 slave.cpp:573] Agent attributes: [ ]
I0704 13:57:25.910023 29881 slave.cpp:578] Agent hostname: e3db83f9470c
I0704 13:57:25.910199 29892 status_update_manager.cpp:177] Pausing sending status updates
I0704 13:57:25.911873 29882 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_bKfte3/meta'
I0704 13:57:25.912395 29887 status_update_manager.cpp:203] Recovering status update manager
I0704 13:57:25.912616 29892 containerizer.cpp:582] Recovering containerizer
I0704 13:57:25.914418 29878 provisioner.cpp:416] Provisioner recovery complete
I0704 13:57:25.915001 29884 slave.cpp:6189] Finished recovery
I0704 13:57:25.915423 29884 slave.cpp:6371] Querying resource estimator for oversubscribable resources
I0704 13:57:25.915824 29874 slave.cpp:6385] Received oversubscribable resources {} from the resource estimator
I0704 13:57:25.918082 29882 process.cpp:3779] Handling HTTP event for process 'slave(779)' with path: '/slave(779)/monitor/statistics'
I0704 13:57:25.920137 29877 http.cpp:1116] HTTP GET for /slave(779)/monitor/statistics from 172.17.0.6:57626
I0704 13:57:25.920207 29877 http.cpp:937] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0704 13:57:25.924012 29871 slave.cpp:839] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/0 (36 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
I0704 13:57:25.936712 9187 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0704 13:57:25.937239 9187 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 13:57:25.937335 9187 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 13:57:25.937367 9187 provisioner.cpp:255] Using default backend 'copy'
I0704 13:57:25.939024 9187 cluster.cpp:448] Creating default 'local' authorizer
I0704 13:57:25.940636 29887 slave.cpp:250] Mesos agent started on (780)@172.17.0.6:37756
I0704 13:57:25.940659 29887 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr/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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr/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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr/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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_1gVRhL"
I0704 13:57:25.941045 29887 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr/credential'
I0704 13:57:25.941187 29887 slave.cpp:283] Agent using credential for: test-principal
I0704 13:57:25.941210 29887 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr/http_credentials'
I0704 13:57:25.941512 29887 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.941622 29887 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.941908 29887 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.942044 29887 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.942260 29887 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.942435 29887 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.943981 29887 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0704 13:57:25.944221 29887 slave.cpp:573] Agent attributes: [ ]
I0704 13:57:25.944247 29887 slave.cpp:578] Agent hostname: e3db83f9470c
I0704 13:57:25.944403 29878 status_update_manager.cpp:177] Pausing sending status updates
I0704 13:57:25.946106 29881 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_1gVRhL/meta'
I0704 13:57:25.946815 29877 status_update_manager.cpp:203] Recovering status update manager
I0704 13:57:25.947152 29882 containerizer.cpp:582] Recovering containerizer
I0704 13:57:25.948770 29876 provisioner.cpp:416] Provisioner recovery complete
I0704 13:57:25.949232 29893 slave.cpp:6189] Finished recovery
I0704 13:57:25.949915 29893 slave.cpp:6371] Querying resource estimator for oversubscribable resources
I0704 13:57:25.950258 29893 slave.cpp:6385] Received oversubscribable resources {} from the resource estimator
I0704 13:57:25.952733 29892 process.cpp:3779] Handling HTTP event for process 'slave(780)' with path: '/slave(780)/monitor/statistics.json'
I0704 13:57:25.954684 29874 http.cpp:1116] HTTP GET for /slave(780)/monitor/statistics.json from 172.17.0.6:57628
I0704 13:57:25.954763 29874 http.cpp:937] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0704 13:57:25.957998 29888 slave.cpp:839] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (33 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/2
I0704 13:57:25.969863 9187 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0704 13:57:25.970360 9187 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 13:57:25.970458 9187 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 13:57:25.970492 9187 provisioner.cpp:255] Using default backend 'copy'
I0704 13:57:25.972728 9187 cluster.cpp:448] Creating default 'local' authorizer
I0704 13:57:25.974550 29877 slave.cpp:250] Mesos agent started on (781)@172.17.0.6:37756
I0704 13:57:25.974577 29877 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9/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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9/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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9/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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_dZfuFt"
I0704 13:57:25.975020 29877 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9/credential'
I0704 13:57:25.975167 29877 slave.cpp:283] Agent using credential for: test-principal
I0704 13:57:25.975194 29877 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9/http_credentials'
I0704 13:57:25.975487 29877 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.975592 29877 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.975775 29877 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.975927 29877 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.976214 29877 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.976331 29877 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.977962 29877 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0704 13:57:25.978210 29877 slave.cpp:573] Agent attributes: [ ]
I0704 13:57:25.978227 29877 slave.cpp:578] Agent hostname: e3db83f9470c
I0704 13:57:25.978405 29887 status_update_manager.cpp:177] Pausing sending status updates
I0704 13:57:25.979746 29876 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_dZfuFt/meta'
I0704 13:57:25.980154 29891 status_update_manager.cpp:203] Recovering status update manager
I0704 13:57:25.980394 29871 containerizer.cpp:582] Recovering containerizer
I0704 13:57:25.982137 29889 provisioner.cpp:416] Provisioner recovery complete
I0704 13:57:25.982614 29879 slave.cpp:6189] Finished recovery
I0704 13:57:25.983062 29879 slave.cpp:6371] Querying resource estimator for oversubscribable resources
I0704 13:57:25.983409 29873 slave.cpp:6385] Received oversubscribable resources {} from the resource estimator
I0704 13:57:25.985735 29880 process.cpp:3779] Handling HTTP event for process 'slave(781)' with path: '/slave(781)/containers'
I0704 13:57:25.987577 29883 http.cpp:1116] HTTP GET for /slave(781)/containers from 172.17.0.6:57630
I0704 13:57:25.987671 29883 http.cpp:937] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0704 13:57:25.991379 29887 slave.cpp:839] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/2 (33 ms)
[----------] 9 tests from Endpoint/SlaveEndpointTest (315 ms total)
[----------] Global test environment tear-down
[==========] 1625 tests from 181 test cases ran. (635205 ms total)
[ PASSED ] 1620 tests.
[ FAILED ] 5 tests, listed below:
[ FAILED ] CommandExecutorCheckTest.CommandCheckDeliveredAndReconciled
[ FAILED ] DefaultExecutorCheckTest.CommandCheckDeliveredAndReconciled
[ FAILED ] DefaultExecutorCheckTest.CommandCheckStatusChange
[ FAILED ] DefaultExecutorCheckTest.CommandCheckSeesParentsEnv
[ FAILED ] DefaultExecutorCheckTest.CommandCheckSharesWorkDirWithTask
5 FAILED TESTS
YOU HAVE 20 DISABLED TESTS
I0704 13:57:26.060756 29895 process.cpp:968] Failed to accept socket: future discarded
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1499173934-27274
Untagged: mesos-1499173934-27274:latest
Deleted: sha256:308c434e069815e3a69f1530e8e77e314819a91de192008fee80917d5cf60822
Deleted: sha256:68a92609bd9ed7844bad5d5d46709d8adf26c469d4b6fb5ce49a272c0e019968
Deleted: sha256:99b684cbc325d6d7c6b802fd685ba34e5762598aaa4825263421211afa667b69
Deleted: sha256:7879f7ca03d1fe140b91cd05f7d67b3a53af6c2a8d34ca2f0a647204473f434c
Deleted: sha256:cd64fc85520f2cf9563b54fe7073d7e57201f46bd7fc34786d753353d35f77a4
Deleted: sha256:82bae648c7cb0f05cbe87239f4ed068b2ea9174675a69260511745f6c4153f46
Deleted: sha256:776d21953060132beb41deb5959b541ce0a04b648526020276dc250617939bb6
Deleted: sha256:b28c2004ec117801cbb9054bc46f542e5e345a96ed3d0e0aae52145b326eb564
Deleted: sha256:a57e07b2295e82af265c5fca4938eaf71a833873d41c230afcb5d34bba18ca52
Deleted: sha256:1dfba2c1f06ecc5dd584454a142f3aa06acad7da8c6ba9fc2f2e91285773abb9
Deleted: sha256:ff333c7476762438ee4614ee1d00f75330412b6f54f67ec77c03a132033d51b2
Deleted: sha256:61d642c72bb43a3f7b954b74065d3afdc188b2fa5e7375f5a96dcdef0f59be29
Deleted: sha256:d2adab793e50e15ce01a1ced4d03a7b319302509f867881fc142f8103451991f
Deleted: sha256:ea62cc969708d7f5cf9b0c35c18077c1b69d3bd61cb6419644de99284e31d573
Deleted: sha256:602d13f602f3ade733c35fba54d8ac0f76188145b6dae3c3148519100395308f
Deleted: sha256:f6aa535783d4053c8f58214077b6ccb19d9241da0a1ddf84ca5aa92652cf8d72
Deleted: sha256:a5dceb6319ed8f67d0eeb96cddaf6d03a653b31decb9c2efb508fb1e691f8976
Deleted: sha256:f96d6b88429e76bd068ad85586c0d4e3aa24af65e4feebc96002a47adbe56812
Deleted: sha256:c146b2065e703ae749daf648b503bd28a61742d9f44521fb5827c5866196a2a4
Deleted: sha256:3e204942f626caf5553bcfdb9a0f9ec66a66d4fd9e2f192078c4c6d4519e5217
Deleted: sha256:4ff355dfdf8f5266b83a46c74cc65cbea770bdaa2efa1f2741d86dfaba3bd933
Deleted: sha256:8ecd2e5abbf886ea9e7ac01bfca6c19fc5a65c0e0a17192c29f515928660b3ec
Deleted: sha256:8a880565a082f7e47bee4b810f03b74e2ea124e6c8ee4f5633e80624b227acea
Deleted: sha256:0073af38d71a86e6881c64b539cd53974df440c54b086257932ac2c6297cbc87
Deleted: sha256:b8c0cdbca8d7fe2fbd692f115204e171e57e542438e50fd7af3f392ed9a2ec20
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user toenshoff@me.com