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/08/25 23:15:32 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4159
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4159/display/redirect?page=changes>
Changes:
[mpark] Added port mappings in `NetworkInfo` as part of `state` endpoints.
------------------------------------------
[...truncated 18.74 MB...]
I0825 23:15:22.274030 5714 master.cpp:7869] Successfully authenticated principal 'test-principal' at slave(412)@172.17.0.3:45254
I0825 23:15:22.274092 5722 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(862)@172.17.0.3:45254
I0825 23:15:22.274222 5713 slave.cpp:1144] Successfully authenticated with master master@172.17.0.3:45254
I0825 23:15:22.274454 5713 slave.cpp:1599] Will retry registration in 10.687802ms if necessary
I0825 23:15:22.274633 5708 master.cpp:5714] Received register agent message from slave(412)@172.17.0.3:45254 (29d8e135262c)
I0825 23:15:22.274763 5708 master.cpp:3803] Authorizing agent with principal 'test-principal'
I0825 23:15:22.275111 5721 master.cpp:5774] Authorized registration of agent at slave(412)@172.17.0.3:45254 (29d8e135262c)
I0825 23:15:22.275256 5721 master.cpp:5867] Registering agent at slave(412)@172.17.0.3:45254 (29d8e135262c) with id f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0
I0825 23:15:22.275708 5723 registrar.cpp:495] Applied 1 operations in 60384ns; attempting to update the registry
I0825 23:15:22.276276 5723 registrar.cpp:552] Successfully updated the registry in 510720ns
I0825 23:15:22.276466 5725 master.cpp:5914] Admitted agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 at slave(412)@172.17.0.3:45254 (29d8e135262c)
I0825 23:15:22.277185 5717 slave.cpp:4939] Received ping from slave-observer(416)@172.17.0.3:45254
I0825 23:15:22.277333 5717 slave.cpp:1190] Registered with master master@172.17.0.3:45254; given agent ID f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0
I0825 23:15:22.277130 5725 master.cpp:5945] Registered agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 at slave(412)@172.17.0.3:45254 (29d8e135262c) with [{"name":"cpus","scalar":{"value":8.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":367489.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0825 23:15:22.277415 5729 status_update_manager.cpp:184] Resuming sending status updates
I0825 23:15:22.277451 5707 hierarchical.cpp:593] Added agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 (29d8e135262c) with cpus:8; mem:2048; disk:367489; ports:[31000-32000] (allocated: {})
I0825 23:15:22.277623 5717 slave.cpp:1210] Checkpointing SlaveInfo to '/tmp/ReservationTest_MasterFailover_FRFcj7/meta/slaves/f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0/slave.info'
I0825 23:15:22.277971 5717 slave.cpp:1259] Forwarding total oversubscribed resources {}
I0825 23:15:22.278118 5716 master.cpp:6690] Received update of agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 at slave(412)@172.17.0.3:45254 (29d8e135262c) with total oversubscribed resources {}
I0825 23:15:22.278587 5707 hierarchical.cpp:2033] No inverse offers to send out!
I0825 23:15:22.278637 5707 hierarchical.cpp:1486] Performed allocation for 1 agents in 984619ns
I0825 23:15:22.278919 5707 hierarchical.cpp:660] Agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 (29d8e135262c) updated with total resources cpus:8; mem:2048; disk:367489; ports:[31000-32000]
I0825 23:15:22.279045 5726 master.cpp:7669] Sending 1 offers to framework f8af7e5a-24cf-4af1-a0d5-bd489d597484-0000 (default) at scheduler-83e054a5-7fa7-420a-a5b3-f33bf61bd4c5@172.17.0.3:45254
I0825 23:15:22.279534 5714 sched.cpp:933] Scheduler::resourceOffers took 101465ns
I0825 23:15:22.280980 5711 master.cpp:9166] Removing offer f8af7e5a-24cf-4af1-a0d5-bd489d597484-O0
I0825 23:15:22.281100 5711 master.cpp:4153] Processing ACCEPT call for offers: [ f8af7e5a-24cf-4af1-a0d5-bd489d597484-O0 ] on agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 at slave(412)@172.17.0.3:45254 (29d8e135262c) for framework f8af7e5a-24cf-4af1-a0d5-bd489d597484-0000 (default) at scheduler-83e054a5-7fa7-420a-a5b3-f33bf61bd4c5@172.17.0.3:45254
I0825 23:15:22.281244 5711 master.cpp:3588] Authorizing principal 'test-principal' to reserve resources '[{"allocation_info":{"role":"role"},"name":"cpus","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":8.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"mem","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
I0825 23:15:22.282637 5711 master.cpp:4456] Applying RESERVE operation for resources [{"allocation_info":{"role":"role"},"name":"cpus","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":8.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"mem","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}] from framework f8af7e5a-24cf-4af1-a0d5-bd489d597484-0000 (default) at scheduler-83e054a5-7fa7-420a-a5b3-f33bf61bd4c5@172.17.0.3:45254 to agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 at slave(412)@172.17.0.3:45254 (29d8e135262c)
I0825 23:15:22.283190 5711 master.cpp:9112] Sending updated checkpointed resources cpus(reservations: [(DYNAMIC,role,test-principal)]):8; mem(reservations: [(DYNAMIC,role,test-principal)]):2048 to agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 at slave(412)@172.17.0.3:45254 (29d8e135262c)
I0825 23:15:22.284149 5729 slave.cpp:3492] Updated checkpointed resources from {} to cpus(reservations: [(DYNAMIC,role,test-principal)]):8; mem(reservations: [(DYNAMIC,role,test-principal)]):2048
I0825 23:15:22.286293 5717 hierarchical.cpp:887] Updated allocation of framework f8af7e5a-24cf-4af1-a0d5-bd489d597484-0000 on agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 from cpus(allocated: role):8; mem(allocated: role):2048; disk(allocated: role):367489; ports(allocated: role):[31000-32000] to ports(allocated: role):[31000-32000]; cpus(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):8; disk(allocated: role):367489; mem(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):2048
I0825 23:15:22.287510 5717 hierarchical.cpp:1161] Recovered ports(allocated: role):[31000-32000]; cpus(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):8; disk(allocated: role):367489; mem(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):2048 (total: ports:[31000-32000]; cpus(reservations: [(DYNAMIC,role,test-principal)]):8; disk:367489; mem(reservations: [(DYNAMIC,role,test-principal)]):2048, allocated: {}) on agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 from framework f8af7e5a-24cf-4af1-a0d5-bd489d597484-0000
I0825 23:15:22.288981 5717 hierarchical.cpp:2033] No inverse offers to send out!
I0825 23:15:22.289057 5717 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.32339ms
I0825 23:15:22.289546 5715 master.cpp:7669] Sending 1 offers to framework f8af7e5a-24cf-4af1-a0d5-bd489d597484-0000 (default) at scheduler-83e054a5-7fa7-420a-a5b3-f33bf61bd4c5@172.17.0.3:45254
I0825 23:15:22.289954 5722 sched.cpp:933] Scheduler::resourceOffers took 15442ns
I0825 23:15:22.290199 5687 master.cpp:1160] Master terminating
I0825 23:15:22.290756 5687 master.cpp:9166] Removing offer f8af7e5a-24cf-4af1-a0d5-bd489d597484-O1
I0825 23:15:22.290856 5727 hierarchical.cpp:626] Removed agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0
I0825 23:15:22.291421 5711 slave.cpp:4981] Got exited event for master@172.17.0.3:45254
W0825 23:15:22.291447 5711 slave.cpp:4986] Master disconnected! Waiting for a new master to be elected
I0825 23:15:22.291816 5720 hierarchical.cpp:355] Removed framework f8af7e5a-24cf-4af1-a0d5-bd489d597484-0000
I0825 23:15:22.291997 5720 hierarchical.cpp:1943] No allocations performed
I0825 23:15:22.292042 5720 hierarchical.cpp:1486] Performed allocation for 0 agents in 66491ns
I0825 23:15:22.294889 5687 cluster.cpp:162] Creating default 'local' authorizer
I0825 23:15:22.297719 5714 master.cpp:442] Master b8637ff9-620a-4526-abe3-604e7fcdce0e (29d8e135262c) started on 172.17.0.3:45254
I0825 23:15:22.297744 5714 master.cpp:444] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="5ms" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/ip6NhZ/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --roles="role" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.5.0/_inst/share/mesos/webui" --work_dir="/tmp/ip6NhZ/master" --zk_session_timeout="10secs"
I0825 23:15:22.298046 5714 master.cpp:494] Master only allowing authenticated frameworks to register
I0825 23:15:22.298059 5714 master.cpp:508] Master only allowing authenticated agents to register
I0825 23:15:22.298069 5714 master.cpp:521] Master only allowing authenticated HTTP frameworks to register
I0825 23:15:22.298079 5714 credentials.hpp:37] Loading credentials for authentication from '/tmp/ip6NhZ/credentials'
I0825 23:15:22.298365 5714 master.cpp:566] Using default 'crammd5' authenticator
I0825 23:15:22.298528 5714 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0825 23:15:22.298693 5714 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0825 23:15:22.298835 5714 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0825 23:15:22.298976 5714 master.cpp:646] Authorization enabled
W0825 23:15:22.299002 5714 master.cpp:709] The '--roles' flag is deprecated. This flag will be removed in the future. See the Mesos 0.27 upgrade notes for more information
I0825 23:15:22.299185 5706 hierarchical.cpp:171] Initialized hierarchical allocator process
I0825 23:15:22.299221 5724 whitelist_watcher.cpp:77] No whitelist given
I0825 23:15:22.301921 5709 master.cpp:2163] Elected as the leading master!
I0825 23:15:22.301951 5709 master.cpp:1702] Recovering from registrar
I0825 23:15:22.302067 5727 registrar.cpp:347] Recovering registrar
I0825 23:15:22.302577 5727 registrar.cpp:391] Successfully fetched the registry (0B) in 473088ns
I0825 23:15:22.302666 5727 registrar.cpp:495] Applied 1 operations in 22307ns; attempting to update the registry
I0825 23:15:22.303144 5727 registrar.cpp:552] Successfully updated the registry in 432896ns
I0825 23:15:22.303251 5727 registrar.cpp:424] Successfully recovered registrar
I0825 23:15:22.303624 5712 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0825 23:15:22.303632 5708 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
I0825 23:15:22.304364 5707 status_update_manager.cpp:177] Pausing sending status updates
I0825 23:15:22.304364 5710 slave.cpp:987] New master detected at master@172.17.0.3:45254
I0825 23:15:22.304416 5725 sched.cpp:330] Scheduler::disconnected took 22891ns
I0825 23:15:22.304443 5725 sched.cpp:336] New master detected at master@172.17.0.3:45254
I0825 23:15:22.304445 5710 slave.cpp:1022] Detecting new master
I0825 23:15:22.304567 5725 sched.cpp:407] Authenticating with master master@172.17.0.3:45254
I0825 23:15:22.304596 5725 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0825 23:15:22.304842 5709 authenticatee.cpp:121] Creating new client SASL connection
I0825 23:15:22.305094 5720 master.cpp:7839] Authenticating scheduler-83e054a5-7fa7-420a-a5b3-f33bf61bd4c5@172.17.0.3:45254
I0825 23:15:22.305202 5716 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(863)@172.17.0.3:45254
I0825 23:15:22.305486 5729 authenticator.cpp:98] Creating new server SASL connection
I0825 23:15:22.305516 5717 slave.cpp:1049] Authenticating with master master@172.17.0.3:45254
I0825 23:15:22.305565 5722 hierarchical.cpp:1943] No allocations performed
I0825 23:15:22.305634 5717 slave.cpp:1060] Using default CRAM-MD5 authenticatee
I0825 23:15:22.305641 5722 hierarchical.cpp:1486] Performed allocation for 0 agents in 101568ns
I0825 23:15:22.305734 5721 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0825 23:15:22.305761 5721 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0825 23:15:22.305903 5719 authenticator.cpp:204] Received SASL authentication start
I0825 23:15:22.305936 5715 authenticatee.cpp:121] Creating new client SASL connection
I0825 23:15:22.305979 5719 authenticator.cpp:326] Authentication requires more steps
I0825 23:15:22.306085 5727 authenticatee.cpp:259] Received SASL authentication step
I0825 23:15:22.306213 5706 authenticator.cpp:232] Received SASL authentication step
I0825 23:15:22.306210 5728 master.cpp:7839] Authenticating slave(412)@172.17.0.3:45254
I0825 23:15:22.306246 5706 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '29d8e135262c' server FQDN: '29d8e135262c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0825 23:15:22.306262 5706 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0825 23:15:22.306293 5706 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0825 23:15:22.306324 5706 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '29d8e135262c' server FQDN: '29d8e135262c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0825 23:15:22.306335 5711 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(864)@172.17.0.3:45254
I0825 23:15:22.306342 5706 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0825 23:15:22.306380 5706 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0825 23:15:22.306406 5706 authenticator.cpp:318] Authentication success
I0825 23:15:22.306531 5714 authenticatee.cpp:299] Authentication success
I0825 23:15:22.306591 5708 master.cpp:7869] Successfully authenticated principal 'test-principal' at scheduler-83e054a5-7fa7-420a-a5b3-f33bf61bd4c5@172.17.0.3:45254
I0825 23:15:22.306648 5712 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(863)@172.17.0.3:45254
I0825 23:15:22.306689 5724 authenticator.cpp:98] Creating new server SASL connection
I0825 23:15:22.306836 5723 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:45254
I0825 23:15:22.306862 5723 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:45254
I0825 23:15:22.306978 5726 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0825 23:15:22.307008 5726 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0825 23:15:22.306999 5723 sched.cpp:869] Will retry registration in 1.332610525secs if necessary
*** Aborted at 1503702922 (unix time) try "date -d @1503702922" if you are using GNU date ***
I0825 23:15:22.307129 5720 authenticator.cpp:204] Received SASL authentication start
I0825 23:15:22.307163 5709 master.cpp:2894] Received SUBSCRIBE call for framework 'default' at scheduler-83e054a5-7fa7-420a-a5b3-f33bf61bd4c5@172.17.0.3:45254
I0825 23:15:22.307180 5720 authenticator.cpp:326] Authentication requires more steps
I0825 23:15:22.307248 5709 master.cpp:2228] Authorizing framework principal 'test-principal' to receive offers for roles '{ role }'
I0825 23:15:22.307288 5713 authenticatee.cpp:259] Received SASL authentication step
I0825 23:15:22.307426 5729 authenticator.cpp:232] Received SASL authentication step
I0825 23:15:22.307456 5729 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '29d8e135262c' server FQDN: '29d8e135262c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0825 23:15:22.307469 5729 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0825 23:15:22.307505 5729 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0825 23:15:22.307539 5729 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '29d8e135262c' server FQDN: '29d8e135262c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0825 23:15:22.307557 5729 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0825 23:15:22.307574 5729 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0825 23:15:22.307597 5729 authenticator.cpp:318] Authentication success
I0825 23:15:22.307672 5721 master.cpp:2974] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0825 23:15:22.307689 5717 authenticatee.cpp:299] Authentication success
I0825 23:15:22.307785 5722 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(864)@172.17.0.3:45254
I0825 23:15:22.308030 5719 slave.cpp:1144] Successfully authenticated with master master@172.17.0.3:45254
I0825 23:15:22.308095 5721 master.cpp:6625] Updating info for framework f8af7e5a-24cf-4af1-a0d5-bd489d597484-0000
I0825 23:15:22.308295 5719 slave.cpp:1599] Will retry registration in 12.033854ms if necessary
I0825 23:15:22.308351 5711 hierarchical.cpp:303] Added framework f8af7e5a-24cf-4af1-a0d5-bd489d597484-0000
I0825 23:15:22.308404 5711 hierarchical.cpp:412] Deactivated framework f8af7e5a-24cf-4af1-a0d5-bd489d597484-0000
I0825 23:15:22.308403 5721 master.cpp:7869] Successfully authenticated principal 'test-principal' at slave(412)@172.17.0.3:45254
I0825 23:15:22.308449 5707 sched.cpp:759] Framework registered with f8af7e5a-24cf-4af1-a0d5-bd489d597484-0000
I0825 23:15:22.308502 5707 sched.cpp:773] Scheduler::registered took 29467ns
I0825 23:15:22.308569 5711 hierarchical.cpp:380] Activated framework f8af7e5a-24cf-4af1-a0d5-bd489d597484-0000
I0825 23:15:22.308709 5711 hierarchical.cpp:1943] No allocations performed
I0825 23:15:22.308748 5711 hierarchical.cpp:2033] No inverse offers to send out!
I0825 23:15:22.308789 5711 hierarchical.cpp:1486] Performed allocation for 0 agents in 104858ns
I0825 23:15:22.308825 5721 master.cpp:6014] Received re-register agent message from agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 at slave(412)@172.17.0.3:45254 (29d8e135262c)
I0825 23:15:22.309022 5721 master.cpp:3803] Authorizing agent with principal 'test-principal'
I0825 23:15:22.309430 5718 master.cpp:6083] Authorized re-registration of agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 at slave(412)@172.17.0.3:45254 (29d8e135262c)
I0825 23:15:22.309516 5718 master.cpp:6219] Re-registering agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 at slave(412)@172.17.0.3:45254 (29d8e135262c)
W0825 23:15:22.309931 5725 master.hpp:2182] Allowing UNKNOWN agent to reregister: hostname: "29d8e135262c"
resources {
name: "cpus"
type: SCALAR
scalar {
value: 8
}
role: "*"
}
resources {
name: "mem"
type: SCALAR
scalar {
value: 2048
}
role: "*"
}
resources {
name: "disk"
type: SCALAR
scalar {
value: 367489
}
role: "*"
}
resources {
name: "ports"
type: RANGES
ranges {
range {
begin: 31000
end: 32000
}
}
role: "*"
}
id {
value: "f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0"
}
checkpoint: true
port: 45254
I0825 23:15:22.310251 5725 registrar.cpp:495] Applied 1 operations in 337418ns; attempting to update the registry
I0825 23:15:22.310786 5725 registrar.cpp:552] Successfully updated the registry in 0ns
I0825 23:15:22.311020 5709 master.cpp:6288] Re-admitted agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 at slave(412)@172.17.0.3:45254 (29d8e135262c)
I0825 23:15:22.311796 5717 slave.cpp:4939] Received ping from slave-observer(417)@172.17.0.3:45254
I0825 23:15:22.312054 5717 slave.cpp:1302] Re-registered with master master@172.17.0.3:45254
I0825 23:15:22.311858 5709 master.cpp:6473] Re-registered agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 at slave(412)@172.17.0.3:45254 (29d8e135262c) with [{"name":"cpus","scalar":{"value":8.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":367489.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0825 23:15:22.312142 5717 slave.cpp:1339] Forwarding total oversubscribed resources {}
I0825 23:15:22.312119 5709 master.cpp:6588] Sending updated checkpointed resources cpus(reservations: [(DYNAMIC,role,test-principal)]):8; mem(reservations: [(DYNAMIC,role,test-principal)]):2048 to agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 at slave(412)@172.17.0.3:45254 (29d8e135262c)
I0825 23:15:22.312178 5722 status_update_manager.cpp:184] Resuming sending status updates
I0825 23:15:22.312314 5709 master.cpp:6690] Received update of agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 at slave(412)@172.17.0.3:45254 (29d8e135262c) with total oversubscribed resources {}
I0825 23:15:22.312361 5717 slave.cpp:3419] Ignoring new checkpointed resources identical to the current version: cpus(reservations: [(DYNAMIC,role,test-principal)]):8; mem(reservations: [(DYNAMIC,role,test-principal)]):2048
I0825 23:15:22.312389 5729 hierarchical.cpp:593] Added agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 (29d8e135262c) with ports:[31000-32000]; cpus(reservations: [(DYNAMIC,role,test-principal)]):8; disk:367489; mem(reservations: [(DYNAMIC,role,test-principal)]):2048 (allocated: {})
I0825 23:15:22.312688 5729 hierarchical.cpp:660] Agent f8af7e5a-24cf-4af1-a0d5-bd489d597484-S0 (29d8e135262c) updated with total resources ports:[31000-32000]; cpus(reservations: [(DYNAMIC,role,test-principal)]):8; disk:367489; mem(reservations: [(DYNAMIC,role,test-principal)]):2048
I0825 23:15:22.313992 5729 hierarchical.cpp:2033] No inverse offers to send out!
I0825 23:15:22.314031 5729 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.237873ms
I0825 23:15:22.314616 5728 master.cpp:7669] Sending 1 offers to framework f8af7e5a-24cf-4af1-a0d5-bd489d597484-0000 (default) at scheduler-83e054a5-7fa7-420a-a5b3-f33bf61bd4c5@172.17.0.3:45254
I0825 23:15:22.315083 5715 sched.cpp:933] Scheduler::resourceOffers took 82488ns
PC: @ 0x2b61c588596e process::EventQueue::Consumer::empty()
*** SIGSEGV (@0x8) received by PID 5687 (TID 0x2b61d14d2700) from PID 8; stack trace: ***
@ 0x2b6239d88af7 (unknown)
@ 0x2b6239d8d449 (unknown)
@ 0x2b61c7f60330 (unknown)
@ 0x2b61c588596e process::EventQueue::Consumer::empty()
@ 0x2b61c587261c process::ProcessManager::resume()
@ 0x2b61c586ec18 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2b61c5880386 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2b61c58802dd _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2b61c5880276 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x2b61c77e1a60 (unknown)
@ 0x2b61c7f58184 start_thread
@ 0x2b61c826bffd (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.5.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1503700136-12306
Untagged: mesos-1503700136-12306:latest
Deleted: sha256:eba17dce682beab6f7602fc932c3bf06f05fff54f81056f2365b35f10c330a4f
Deleted: sha256:bf4b783898d01b2f5a0dfb8bb7364331b114c8dc4e10f2d99dec0f89a84593c5
Deleted: sha256:aeadd6350c2afea44a8dbdf9f4371eda56544807b1c9c9e817de7d5cd898952b
Deleted: sha256:de0d618925ae375b336715143652e764d8ad87a601ef509b2354fdcf5b9c7a73
Deleted: sha256:071c179e520aafdc10922ae0a0d4b5b49cd56b37991c096d573ced7c4c3759cd
Deleted: sha256:9d3fd06dd9135b3336fee29bcd9e7dee3ca80bf13b60b9712973bf3f5694fa74
Deleted: sha256:633b1ddf4e73c46e660ca743de65ce8a22236a95eb02ad0ee50d2cec1b78cc3d
Deleted: sha256:bc793bfd0609bc1a50e6a8c4544a78e79f7e0ed4a2726f859e542d7af5fb8678
Deleted: sha256:4dc33a08b9fc8403d370441ac6174cc31bceb22b3967a50921cd6a1f03973272
Deleted: sha256:f77c46eb5d693592a53f542840308464f0a93270e5969b2ee9806f6890d5274a
Deleted: sha256:67f9df80b68c09759fe38cf1d901b18e70f20bc4738c75c32f7a2eb8e48033fb
Deleted: sha256:7855637589cb3b20616fa0bdbbc6aee405c81301b6c4be70ed7159a113fb14d8
Deleted: sha256:59666f308f53966e672b0c67f66059962de365aceab1797a6b75e889f7b528e6
Deleted: sha256:fb720e21464ae4e3397e23008b460d444567ff77577f239bc6b28e224a059535
Deleted: sha256:bfca649dc5bdc03d071a821d3f51303cb5cda79ef4307ec7924d689c9ea9ce66
Deleted: sha256:1c6cfe3d7d5b94440e54226bb0ec9114e103e65cbab68ba191cbfd75ae827d6c
Deleted: sha256:a35d5ec92a0fa705b8833625d68a6082db79e65c712d58192471023180ad4589
Deleted: sha256:4cb18a6d0bd412947950b05ae39f65960f09e85687f6f22a3e1dffb78d272f66
Deleted: sha256:fc17ef4c2bdbf14f4099e5b815f9c6ead6a344910021da40a119375b209b13ef
Deleted: sha256:0bd74c7365cdebc4f04077dc4bd3734ba43a4bfdf2889466c276269a64387bad
Deleted: sha256:72701d1ffafa831608234f4a8411e778dba23998e2734587c516907c6a4b9f36
Deleted: sha256:56985975fdab780988d3d03f0e5d4449cb1d2e45a357600fdc4b449ca3a2212d
Deleted: sha256:1a79964ddadda51dc99e8fb47264cebc1aac90f4aef7eba637eabe263faa8d26
Deleted: sha256:e8008905586e2666c127e62e47da0ba72a671b471731cfafa77d7b3b125d70ad
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4161
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4161/display/redirect?page=changes>
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4160
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4160/display/redirect>
------------------------------------------
[...truncated 30.96 MB...]
I0826 00:22:37.937801 5726 slave.cpp:252] Mesos agent started on (803)@172.17.0.3:33493
I0826 00:22:37.937829 5726 slave.cpp:253] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_XyRh9O/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/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_XyRh9O/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_XyRh9O/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/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_XyRh9O/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_AuthorizedRequest_2_XyRh9O/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.5.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_AuthorizedRequest_2_XyRh9O" --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_AuthorizedRequest_2_NtNkUn"
I0826 00:22:37.938324 5726 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_XyRh9O/credential'
I0826 00:22:37.938565 5726 slave.cpp:285] Agent using credential for: test-principal
I0826 00:22:37.938590 5726 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_XyRh9O/http_credentials'
I0826 00:22:37.938884 5726 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0826 00:22:37.939071 5726 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0826 00:22:37.940640 5726 slave.cpp:581] 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"}]
I0826 00:22:37.940892 5726 slave.cpp:589] Agent attributes: [ ]
I0826 00:22:37.940906 5726 slave.cpp:598] Agent hostname: ffe17fb77ca2
I0826 00:22:37.941052 5728 status_update_manager.cpp:177] Pausing sending status updates
I0826 00:22:37.942607 5719 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_NtNkUn/meta'
I0826 00:22:37.943197 5714 status_update_manager.cpp:203] Recovering status update manager
I0826 00:22:37.943428 5725 containerizer.cpp:643] Recovering containerizer
I0826 00:22:37.945137 5713 provisioner.cpp:416] Provisioner recovery complete
I0826 00:22:37.945494 5734 slave.cpp:6264] Finished recovery
I0826 00:22:37.945971 5734 slave.cpp:6446] Querying resource estimator for oversubscribable resources
I0826 00:22:37.946193 5735 slave.cpp:6460] Received oversubscribable resources {} from the resource estimator
I0826 00:22:37.948781 5717 process.cpp:3933] Handling HTTP event for process 'slave(803)' with path: '/slave(803)/containers'
I0826 00:22:37.950263 5724 http.cpp:1185] HTTP GET for /slave(803)/containers from 172.17.0.3:43018
I0826 00:22:37.950340 5724 http.cpp:976] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0826 00:22:37.954399 5694 slave.cpp:859] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2 (34 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0
I0826 00:22:37.970518 5694 containerizer.cpp:288] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0826 00:22:37.971249 5694 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0826 00:22:37.971395 5694 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0826 00:22:37.971427 5694 provisioner.cpp:255] Using default backend 'copy'
I0826 00:22:37.975971 5721 slave.cpp:252] Mesos agent started on (804)@172.17.0.3:33493
I0826 00:22:37.975998 5721 slave.cpp:253] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_69X5oA/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_69X5oA/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_69X5oA/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_69X5oA/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_69X5oA/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.5.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_69X5oA" --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_0x5K48"
I0826 00:22:37.976474 5721 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_69X5oA/credential'
I0826 00:22:37.976783 5721 slave.cpp:285] Agent using credential for: test-principal
I0826 00:22:37.976814 5721 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_69X5oA/http_credentials'
I0826 00:22:37.977176 5721 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0826 00:22:37.977406 5721 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0826 00:22:37.979770 5721 slave.cpp:581] 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"}]
I0826 00:22:37.980209 5721 slave.cpp:589] Agent attributes: [ ]
I0826 00:22:37.980247 5721 slave.cpp:598] Agent hostname: ffe17fb77ca2
I0826 00:22:37.980484 5725 status_update_manager.cpp:177] Pausing sending status updates
I0826 00:22:37.982789 5728 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_0x5K48/meta'
I0826 00:22:37.983117 5733 status_update_manager.cpp:203] Recovering status update manager
I0826 00:22:37.983343 5727 containerizer.cpp:643] Recovering containerizer
I0826 00:22:37.985136 5725 provisioner.cpp:416] Provisioner recovery complete
I0826 00:22:37.985743 5725 slave.cpp:6264] Finished recovery
I0826 00:22:37.986448 5725 slave.cpp:6446] Querying resource estimator for oversubscribable resources
I0826 00:22:37.986829 5718 slave.cpp:6460] Received oversubscribable resources {} from the resource estimator
I0826 00:22:37.989549 5716 process.cpp:3933] Handling HTTP event for process 'slave(804)' with path: '/slave(804)/monitor/statistics'
I0826 00:22:37.991278 5728 http.cpp:1185] HTTP GET for /slave(804)/monitor/statistics from 172.17.0.3:43020
I0826 00:22:37.991391 5728 http.cpp:976] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0826 00:22:37.993932 5694 slave.cpp:859] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0 (40 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1
I0826 00:22:38.010191 5694 containerizer.cpp:288] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0826 00:22:38.011085 5694 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0826 00:22:38.011265 5694 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0826 00:22:38.011323 5694 provisioner.cpp:255] Using default backend 'copy'
I0826 00:22:38.016671 5724 slave.cpp:252] Mesos agent started on (805)@172.17.0.3:33493
I0826 00:22:38.016700 5724 slave.cpp:253] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_JyECWk/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_JyECWk/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_JyECWk/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_JyECWk/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_JyECWk/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.5.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_JyECWk" --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_f9xnwT"
I0826 00:22:38.017037 5724 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_JyECWk/credential'
I0826 00:22:38.017201 5724 slave.cpp:285] Agent using credential for: test-principal
I0826 00:22:38.017223 5724 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_JyECWk/http_credentials'
I0826 00:22:38.017460 5724 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0826 00:22:38.017613 5724 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0826 00:22:38.018935 5724 slave.cpp:581] 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"}]
I0826 00:22:38.019165 5724 slave.cpp:589] Agent attributes: [ ]
I0826 00:22:38.019177 5724 slave.cpp:598] Agent hostname: ffe17fb77ca2
I0826 00:22:38.019340 5721 status_update_manager.cpp:177] Pausing sending status updates
I0826 00:22:38.020587 5726 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_f9xnwT/meta'
I0826 00:22:38.020929 5726 status_update_manager.cpp:203] Recovering status update manager
I0826 00:22:38.021138 5723 containerizer.cpp:643] Recovering containerizer
I0826 00:22:38.022725 5732 provisioner.cpp:416] Provisioner recovery complete
I0826 00:22:38.023233 5733 slave.cpp:6264] Finished recovery
I0826 00:22:38.023778 5733 slave.cpp:6446] Querying resource estimator for oversubscribable resources
I0826 00:22:38.024045 5729 slave.cpp:6460] Received oversubscribable resources {} from the resource estimator
I0826 00:22:38.027050 5721 process.cpp:3933] Handling HTTP event for process 'slave(805)' with path: '/slave(805)/monitor/statistics.json'
I0826 00:22:38.028767 5725 http.cpp:1185] HTTP GET for /slave(805)/monitor/statistics.json from 172.17.0.3:43022
I0826 00:22:38.028847 5725 http.cpp:976] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0826 00:22:38.031025 5736 slave.cpp:859] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1 (34 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2
I0826 00:22:38.042681 5694 containerizer.cpp:288] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0826 00:22:38.043217 5694 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0826 00:22:38.043331 5694 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0826 00:22:38.043366 5694 provisioner.cpp:255] Using default backend 'copy'
I0826 00:22:38.046787 5717 slave.cpp:252] Mesos agent started on (806)@172.17.0.3:33493
I0826 00:22:38.046808 5717 slave.cpp:253] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_7WukV6/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_7WukV6/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_7WukV6/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_7WukV6/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_7WukV6/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.5.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_7WukV6" --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_prFXpF"
I0826 00:22:38.047179 5717 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_7WukV6/credential'
I0826 00:22:38.047340 5717 slave.cpp:285] Agent using credential for: test-principal
I0826 00:22:38.047363 5717 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_7WukV6/http_credentials'
I0826 00:22:38.047605 5717 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0826 00:22:38.047754 5717 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0826 00:22:38.049113 5717 slave.cpp:581] 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"}]
I0826 00:22:38.049357 5717 slave.cpp:589] Agent attributes: [ ]
I0826 00:22:38.049372 5717 slave.cpp:598] Agent hostname: ffe17fb77ca2
I0826 00:22:38.049507 5726 status_update_manager.cpp:177] Pausing sending status updates
I0826 00:22:38.050982 5723 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_prFXpF/meta'
I0826 00:22:38.051270 5730 status_update_manager.cpp:203] Recovering status update manager
I0826 00:22:38.051496 5735 containerizer.cpp:643] Recovering containerizer
I0826 00:22:38.052958 5736 provisioner.cpp:416] Provisioner recovery complete
I0826 00:22:38.053300 5733 slave.cpp:6264] Finished recovery
I0826 00:22:38.053753 5733 slave.cpp:6446] Querying resource estimator for oversubscribable resources
I0826 00:22:38.054014 5715 slave.cpp:6460] Received oversubscribable resources {} from the resource estimator
I0826 00:22:38.056226 5713 process.cpp:3933] Handling HTTP event for process 'slave(806)' with path: '/slave(806)/containers'
I0826 00:22:38.057611 5719 http.cpp:1185] HTTP GET for /slave(806)/containers from 172.17.0.3:43024
I0826 00:22:38.057677 5719 http.cpp:976] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0826 00:22:38.059803 5719 slave.cpp:859] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2 (29 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/0
I0826 00:22:38.072969 5694 containerizer.cpp:288] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0826 00:22:38.073468 5694 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0826 00:22:38.073573 5694 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0826 00:22:38.073603 5694 provisioner.cpp:255] Using default backend 'copy'
I0826 00:22:38.075219 5694 cluster.cpp:448] Creating default 'local' authorizer
I0826 00:22:38.077200 5713 slave.cpp:252] Mesos agent started on (807)@172.17.0.3:33493
I0826 00:22:38.077230 5713 slave.cpp:253] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_RHPUbT/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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_RHPUbT/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_RHPUbT/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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_RHPUbT/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_RHPUbT/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.5.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_RHPUbT" --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_r614Br"
I0826 00:22:38.077909 5713 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_RHPUbT/credential'
I0826 00:22:38.078186 5713 slave.cpp:285] Agent using credential for: test-principal
I0826 00:22:38.078215 5713 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_RHPUbT/http_credentials'
I0826 00:22:38.078722 5713 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0826 00:22:38.078946 5713 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0826 00:22:38.080934 5713 slave.cpp:581] 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"}]
I0826 00:22:38.081167 5713 slave.cpp:589] Agent attributes: [ ]
I0826 00:22:38.081182 5713 slave.cpp:598] Agent hostname: ffe17fb77ca2
I0826 00:22:38.081436 5721 status_update_manager.cpp:177] Pausing sending status updates
I0826 00:22:38.083015 5727 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_r614Br/meta'
I0826 00:22:38.083482 5729 status_update_manager.cpp:203] Recovering status update manager
I0826 00:22:38.083781 5735 containerizer.cpp:643] Recovering containerizer
I0826 00:22:38.085578 5721 provisioner.cpp:416] Provisioner recovery complete
I0826 00:22:38.085975 5730 slave.cpp:6264] Finished recovery
I0826 00:22:38.086381 5730 slave.cpp:6446] Querying resource estimator for oversubscribable resources
I0826 00:22:38.086663 5736 slave.cpp:6460] Received oversubscribable resources {} from the resource estimator
I0826 00:22:38.089704 5723 process.cpp:3933] Handling HTTP event for process 'slave(807)' with path: '/slave(807)/monitor/statistics'
I0826 00:22:38.091179 5713 http.cpp:1185] HTTP GET for /slave(807)/monitor/statistics from 172.17.0.3:43030
I0826 00:22:38.091248 5713 http.cpp:976] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0826 00:22:38.096220 5694 slave.cpp:859] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/0 (37 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
I0826 00:22:38.111265 5694 containerizer.cpp:288] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0826 00:22:38.111842 5694 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0826 00:22:38.111981 5694 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0826 00:22:38.112011 5694 provisioner.cpp:255] Using default backend 'copy'
I0826 00:22:38.113749 5694 cluster.cpp:448] Creating default 'local' authorizer
I0826 00:22:38.115820 5723 slave.cpp:252] Mesos agent started on (808)@172.17.0.3:33493
I0826 00:22:38.115845 5723 slave.cpp:253] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_LnviOF/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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_LnviOF/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_LnviOF/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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_LnviOF/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_LnviOF/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.5.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_LnviOF" --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_l5nO8d"
I0826 00:22:38.116195 5723 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_LnviOF/credential'
I0826 00:22:38.116334 5723 slave.cpp:285] Agent using credential for: test-principal
I0826 00:22:38.116355 5723 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_LnviOF/http_credentials'
I0826 00:22:38.116576 5723 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0826 00:22:38.116798 5723 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0826 00:22:38.118211 5723 slave.cpp:581] 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"}]
I0826 00:22:38.118448 5723 slave.cpp:589] Agent attributes: [ ]
I0826 00:22:38.118463 5723 slave.cpp:598] Agent hostname: ffe17fb77ca2
I0826 00:22:38.118609 5720 status_update_manager.cpp:177] Pausing sending status updates
I0826 00:22:38.120163 5733 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_l5nO8d/meta'
I0826 00:22:38.120617 5724 status_update_manager.cpp:203] Recovering status update manager
I0826 00:22:38.120859 5721 containerizer.cpp:643] Recovering containerizer
I0826 00:22:38.122640 5720 provisioner.cpp:416] Provisioner recovery complete
I0826 00:22:38.123015 5732 slave.cpp:6264] Finished recovery
I0826 00:22:38.123567 5732 slave.cpp:6446] Querying resource estimator for oversubscribable resources
I0826 00:22:38.123826 5729 slave.cpp:6460] Received oversubscribable resources {} from the resource estimator
I0826 00:22:38.126446 5722 process.cpp:3933] Handling HTTP event for process 'slave(808)' with path: '/slave(808)/monitor/statistics.json'
I0826 00:22:38.128074 5714 http.cpp:1185] HTTP GET for /slave(808)/monitor/statistics.json from 172.17.0.3:43032
I0826 00:22:38.128144 5714 http.cpp:976] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0826 00:22:38.132056 5736 slave.cpp:859] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (36 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/2
I0826 00:22:38.148948 5694 containerizer.cpp:288] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0826 00:22:38.149667 5694 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0826 00:22:38.149837 5694 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0826 00:22:38.149879 5694 provisioner.cpp:255] Using default backend 'copy'
I0826 00:22:38.152129 5694 cluster.cpp:448] Creating default 'local' authorizer
I0826 00:22:38.154629 5719 slave.cpp:252] Mesos agent started on (809)@172.17.0.3:33493
I0826 00:22:38.154670 5719 slave.cpp:253] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_k3owMs/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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_k3owMs/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_k3owMs/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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_k3owMs/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_k3owMs/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.5.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_k3owMs" --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_NTAy10"
I0826 00:22:38.155267 5719 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_k3owMs/credential'
I0826 00:22:38.155419 5719 slave.cpp:285] Agent using credential for: test-principal
I0826 00:22:38.155439 5719 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_k3owMs/http_credentials'
I0826 00:22:38.155684 5719 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0826 00:22:38.155849 5719 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0826 00:22:38.157232 5719 slave.cpp:581] 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"}]
I0826 00:22:38.157467 5719 slave.cpp:589] Agent attributes: [ ]
I0826 00:22:38.157490 5719 slave.cpp:598] Agent hostname: ffe17fb77ca2
I0826 00:22:38.157634 5732 status_update_manager.cpp:177] Pausing sending status updates
I0826 00:22:38.158936 5728 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_NTAy10/meta'
I0826 00:22:38.159342 5728 status_update_manager.cpp:203] Recovering status update manager
I0826 00:22:38.159518 5728 containerizer.cpp:643] Recovering containerizer
I0826 00:22:38.161216 5732 provisioner.cpp:416] Provisioner recovery complete
I0826 00:22:38.161837 5713 slave.cpp:6264] Finished recovery
I0826 00:22:38.162497 5713 slave.cpp:6446] Querying resource estimator for oversubscribable resources
I0826 00:22:38.162834 5722 slave.cpp:6460] Received oversubscribable resources {} from the resource estimator
I0826 00:22:38.165504 5727 process.cpp:3933] Handling HTTP event for process 'slave(809)' with path: '/slave(809)/containers'
I0826 00:22:38.166827 5724 http.cpp:1185] HTTP GET for /slave(809)/containers from 172.17.0.3:43034
I0826 00:22:38.166908 5724 http.cpp:976] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0826 00:22:38.170645 5694 slave.cpp:859] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/2 (35 ms)
[----------] 9 tests from Endpoint/SlaveEndpointTest (327 ms total)
[----------] 2 tests from ContainerizerType/DefaultContainerDNSFlagTest
[ RUN ] ContainerizerType/DefaultContainerDNSFlagTest.ValidateFlag/0
[ OK ] ContainerizerType/DefaultContainerDNSFlagTest.ValidateFlag/0 (10 ms)
[ RUN ] ContainerizerType/DefaultContainerDNSFlagTest.ValidateFlag/1
[ OK ] ContainerizerType/DefaultContainerDNSFlagTest.ValidateFlag/1 (10 ms)
[----------] 2 tests from ContainerizerType/DefaultContainerDNSFlagTest (20 ms total)
[----------] Global test environment tear-down
[==========] 1661 tests from 182 test cases ran. (508487 ms total)
[ PASSED ] 1660 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ExamplesTest.TestFramework
1 FAILED TEST
YOU HAVE 20 DISABLED TESTS
I0826 00:22:38.257985 5737 process.cpp:1072] Failed to accept socket: future discarded
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.5.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1503703915-25430
Untagged: mesos-1503703915-25430:latest
Deleted: sha256:7113d4d5f1677fb0dde3fb82b00327ad77b60a2e0ab12b4fc6068132f36cdd1e
Deleted: sha256:0b7f8c7c4f59c14b7858488e2c5e49629a1d708192e92a228e2ed620bd562142
Deleted: sha256:14d51ecb2bd4bdc9e6005bb86bfa8c89343595868f7a8eacc9d99b2a6d8b8a15
Deleted: sha256:6d2d43b81da960122e4edd70fd551b89fe060789f6e661866f630120cda598ae
Deleted: sha256:3bd03209d582fca40ccc31173182588382d0c25af6111fccae48ca4f4aeea776
Deleted: sha256:5c365dee47c77e38ff4965342e9aeda7df5e892160982c73b7045f49fb3c9869
Deleted: sha256:0b2286fdbf757b075aa1151f8491c3ce71b14ec5e090f5434120c1761bc8f873
Deleted: sha256:1a849c7fac46d14958c85704835a867d0870110aa76be45c2aff45ddf66614d7
Deleted: sha256:583575b3a75f65eddb15e141f68bd8b1cb695d3685bd8e09c0cc410ea54913ff
Deleted: sha256:3e79acca17080d2de5a588ecf49363100cecb4f1ed0bae43e9a8d392619943b2
Deleted: sha256:b2c2d77975948429b206e56aaf58dc1c5b301860c71e70fcfad4e433811fc03a
Deleted: sha256:2ef68294040cdc3db5c1384676c7d28345c914d4d40bb84978c5a901c1fc60ec
Deleted: sha256:59242e56ecd0470bd1065ba3123ad636e217e55078d9a3f5aed892925dddf131
Deleted: sha256:88b8ed4248b3fea822918c90e2088d25ab0634fe50ad1a10257972bc9a158f62
Deleted: sha256:2791b6de1bdf858957a5e3b780202665b2a99f57d4bb8f1fb32b860395d47d6d
Deleted: sha256:75388b3716b5750453772b62805a13e3a80e3e55853b8af0f57e44373524d009
Deleted: sha256:43b2c6ed0eb6281308fe40f8d9b485f8728e683722378edaf9e14cdca1f23660
Deleted: sha256:321767008a716795145a56f1cecfb40140e3d31f73010cae548bdc04900bdaf7
Deleted: sha256:f44fcef454380049545a571ce4d65d0cec7553ec901cd9d016813c61fa36becc
Deleted: sha256:e1e7bd3fd2ca2d8b8d7fba828cf6b7f916161b9236cf1b0ee3b148d884815494
Deleted: sha256:6e49cbee68c08f53295025fcac0c79cf54f3ca1d7453aa71aaee524a69af7205
Deleted: sha256:9504da3e294a36bf5d0b37eb0c4644f646a8e77dbe058a403687961af11d2f58
Deleted: sha256:d3ef3b178f5be2c2863809efa47d5413021b31ad732c6a518ddf89aebd29360f
Deleted: sha256:3976ab8b04c7abdf3d4ab90ebf57091dbe25273045d897322a7a92efcc2ccfab
Build step 'Execute shell' marked build as failure