mesos-builds mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4489
Date Wed, 22 Nov 2017 05:26:29 GMT
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4489/display/redirect?page=changes>

Changes:

[yujie.jay] Added an event to publish resources.

[yujie.jay] Started `LocalResourceProviderDaemon` after obtaining the slave ID.

[yujie.jay] Added an optional `additional_chars` parameter to `http::encode`.

------------------------------------------
[...truncated 20.78 MB...]
3: I1122 05:26:14.910640 18316 master.cpp:8306] Authenticating scheduler-0cc18b0c-81c2-40e8-b795-8e0aa8e58f73@172.17.0.4:39249
3: I1122 05:26:14.910748 18304 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(1056)@172.17.0.4:39249
3: I1122 05:26:14.910987 18296 authenticator.cpp:98] Creating new server SASL connection
3: I1122 05:26:14.911216 18310 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1122 05:26:14.911248 18310 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1122 05:26:14.911396 18315 authenticator.cpp:204] Received SASL authentication start
3: I1122 05:26:14.911460 18315 authenticator.cpp:326] Authentication requires more steps
3: I1122 05:26:14.911572 18305 authenticatee.cpp:259] Received SASL authentication step
3: I1122 05:26:14.911706 18312 authenticator.cpp:232] Received SASL authentication step
3: I1122 05:26:14.911734 18298 state.cpp:64] Recovering state from '/tmp/ReservationTest_ReserveShareWithinRole_AVnQlM/meta'
3: I1122 05:26:14.911736 18312 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '441d7a7e00ba' server FQDN: '441d7a7e00ba' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1122 05:26:14.911761 18312 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1122 05:26:14.911798 18312 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1122 05:26:14.911818 18312 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '441d7a7e00ba' server FQDN: '441d7a7e00ba' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1122 05:26:14.911829 18312 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1122 05:26:14.911835 18312 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1122 05:26:14.911847 18312 authenticator.cpp:318] Authentication success
3: I1122 05:26:14.911942 18297 authenticatee.cpp:299] Authentication success
3: I1122 05:26:14.912032 18298 task_status_update_manager.cpp:207] Recovering task status
update manager
3: I1122 05:26:14.912050 18313 master.cpp:8336] Successfully authenticated principal 'test-principal'
at scheduler-0cc18b0c-81c2-40e8-b795-8e0aa8e58f73@172.17.0.4:39249
3: I1122 05:26:14.912089 18306 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1056)@172.17.0.4:39249
3: I1122 05:26:14.912176 18299 sched.cpp:502] Successfully authenticated with master master@172.17.0.4:39249
3: I1122 05:26:14.912194 18299 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.4:39249
3: I1122 05:26:14.912228 18316 containerizer.cpp:668] Recovering containerizer
3: I1122 05:26:14.912292 18299 sched.cpp:857] Will retry registration in 1.927559358secs if
necessary
3: I1122 05:26:14.912457 18304 master.cpp:2963] Received SUBSCRIBE call for framework 'framework2'
at scheduler-0cc18b0c-81c2-40e8-b795-8e0aa8e58f73@172.17.0.4:39249
3: I1122 05:26:14.912539 18304 master.cpp:2280] Authorizing framework principal 'test-principal'
to receive offers for roles '{ role }'
3: I1122 05:26:14.913008 18300 master.cpp:3043] Subscribing framework framework2 with checkpointing
disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1122 05:26:14.913543 18298 sched.cpp:751] Framework registered with bae28168-4f3c-460c-8d11-184dfcaf5550-0000
3: I1122 05:26:14.913591 18298 sched.cpp:765] Scheduler::registered took 25329ns
3: I1122 05:26:14.913599 18313 provisioner.cpp:455] Provisioner recovery complete
3: I1122 05:26:14.913714 18318 hierarchical.cpp:306] Added framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
3: I1122 05:26:14.913915 18317 slave.cpp:6449] Finished recovery
3: I1122 05:26:14.913918 18318 hierarchical.cpp:1457] Performed allocation for 0 agents in
59629ns
3: I1122 05:26:14.914505 18299 task_status_update_manager.cpp:181] Pausing sending task status
updates
3: I1122 05:26:14.914525 18317 slave.cpp:1007] New master detected at master@172.17.0.4:39249
3: I1122 05:26:14.914575 18317 slave.cpp:1042] Detecting new master
3: I1122 05:26:14.915815 18310 hierarchical.cpp:1457] Performed allocation for 0 agents in
51185ns
3: I1122 05:26:14.921975 18305 slave.cpp:1069] Authenticating with master master@172.17.0.4:39249
3: I1122 05:26:14.922039 18305 slave.cpp:1078] Using default CRAM-MD5 authenticatee
3: I1122 05:26:14.922134 18302 hierarchical.cpp:1457] Performed allocation for 0 agents in
50280ns
3: I1122 05:26:14.922212 18301 authenticatee.cpp:121] Creating new client SASL connection
3: I1122 05:26:14.922437 18316 master.cpp:8306] Authenticating slave(522)@172.17.0.4:39249
3: I1122 05:26:14.922585 18303 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(1057)@172.17.0.4:39249
3: I1122 05:26:14.922963 18297 authenticator.cpp:98] Creating new server SASL connection
3: I1122 05:26:14.923374 18312 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1122 05:26:14.923413 18312 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1122 05:26:14.923528 18312 authenticator.cpp:204] Received SASL authentication start
3: I1122 05:26:14.923599 18312 authenticator.cpp:326] Authentication requires more steps
3: I1122 05:26:14.923755 18300 authenticatee.cpp:259] Received SASL authentication step
3: I1122 05:26:14.923861 18300 authenticator.cpp:232] Received SASL authentication step
3: I1122 05:26:14.923892 18300 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '441d7a7e00ba' server FQDN: '441d7a7e00ba' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1122 05:26:14.923903 18300 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1122 05:26:14.923928 18300 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1122 05:26:14.923943 18300 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '441d7a7e00ba' server FQDN: '441d7a7e00ba' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1122 05:26:14.923950 18300 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1122 05:26:14.923955 18300 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1122 05:26:14.923966 18300 authenticator.cpp:318] Authentication success
3: I1122 05:26:14.924041 18315 authenticatee.cpp:299] Authentication success
3: I1122 05:26:14.924085 18308 master.cpp:8336] Successfully authenticated principal 'test-principal'
at slave(522)@172.17.0.4:39249
3: I1122 05:26:14.924142 18311 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1057)@172.17.0.4:39249
3: I1122 05:26:14.924320 18298 slave.cpp:1161] Successfully authenticated with master master@172.17.0.4:39249
3: I1122 05:26:14.924538 18298 slave.cpp:1685] Will retry registration in 9.891181ms if necessary
3: I1122 05:26:14.924906 18318 master.cpp:6036] Received register agent message from slave(522)@172.17.0.4:39249
(441d7a7e00ba)
3: I1122 05:26:14.925077 18318 master.cpp:3872] Authorizing agent with principal 'test-principal'
3: I1122 05:26:14.925498 18299 master.cpp:6098] Authorized registration of agent at slave(522)@172.17.0.4:39249
(441d7a7e00ba)
3: I1122 05:26:14.925593 18299 master.cpp:6191] Registering agent at slave(522)@172.17.0.4:39249
(441d7a7e00ba) with id bae28168-4f3c-460c-8d11-184dfcaf5550-S0
3: I1122 05:26:14.925992 18295 registrar.cpp:495] Applied 1 operations in 59818ns; attempting
to update the registry
3: I1122 05:26:14.926524 18295 registrar.cpp:552] Successfully updated the registry in 484096ns
3: I1122 05:26:14.926735 18302 master.cpp:6240] Admitted agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0
at slave(522)@172.17.0.4:39249 (441d7a7e00ba)
3: I1122 05:26:14.927422 18316 slave.cpp:1207] Registered with master master@172.17.0.4:39249;
given agent ID bae28168-4f3c-460c-8d11-184dfcaf5550-S0
3: I1122 05:26:14.927527 18304 task_status_update_manager.cpp:188] Resuming sending task status
updates
3: I1122 05:26:14.927341 18302 master.cpp:6273] Registered agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0
at slave(522)@172.17.0.4:39249 (441d7a7e00ba) with [{"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":512.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":367489.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1122 05:26:14.927687 18303 hierarchical.cpp:600] Added agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0
(441d7a7e00ba) with cpus:1; mem:512; disk:367489; ports:[31000-32000] (allocated: {})
3: I1122 05:26:14.927754 18316 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/ReservationTest_ReserveShareWithinRole_AVnQlM/meta/slaves/bae28168-4f3c-460c-8d11-184dfcaf5550-S0/slave.info'
3: I1122 05:26:14.928184 18316 slave.cpp:1298] Forwarding total oversubscribed resources {}
3: I1122 05:26:14.928381 18316 master.cpp:7085] Received update of agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0
at slave(522)@172.17.0.4:39249 (441d7a7e00ba) with total oversubscribed resources {}
3: I1122 05:26:14.928539 18316 master.cpp:7103] Ignoring update on agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0
at slave(522)@172.17.0.4:39249 (441d7a7e00ba) as it reports no changes
3: I1122 05:26:14.928872 18303 hierarchical.cpp:1457] Performed allocation for 1 agents in
969891ns
3: I1122 05:26:14.929280 18300 master.cpp:8136] Sending 1 offers to framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
(framework2) at scheduler-0cc18b0c-81c2-40e8-b795-8e0aa8e58f73@172.17.0.4:39249
3: I1122 05:26:14.929739 18315 sched.cpp:921] Scheduler::resourceOffers took 95508ns
3: I1122 05:26:14.931145 18307 master.cpp:10045] Removing offer bae28168-4f3c-460c-8d11-184dfcaf5550-O0
3: I1122 05:26:14.931282 18307 master.cpp:4230] Processing ACCEPT call for offers: [ bae28168-4f3c-460c-8d11-184dfcaf5550-O0
] on agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0 at slave(522)@172.17.0.4:39249 (441d7a7e00ba)
for framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000 (framework2) at scheduler-0cc18b0c-81c2-40e8-b795-8e0aa8e58f73@172.17.0.4:39249
3: I1122 05:26:14.931412 18307 master.cpp:3657] Authorizing principal 'test-principal' to
reserve resources '[{"allocation_info":{"role":"role"},"name":"cpus","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"mem","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":512.0},"type":"SCALAR"}]'
3: I1122 05:26:14.932807 18305 master.cpp:4563] Applying RESERVE operation for resources [{"allocation_info":{"role":"role"},"name":"cpus","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"mem","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":512.0},"type":"SCALAR"}]
from framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000 (framework2) at scheduler-0cc18b0c-81c2-40e8-b795-8e0aa8e58f73@172.17.0.4:39249
to agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0 at slave(522)@172.17.0.4:39249 (441d7a7e00ba)
3: I1122 05:26:14.933449 18305 master.cpp:9990] Sending updated checkpointed resources cpus(reservations:
[(DYNAMIC,role,test-principal)]):1; mem(reservations: [(DYNAMIC,role,test-principal)]):512
to agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0 at slave(522)@172.17.0.4:39249 (441d7a7e00ba)
3: I1122 05:26:14.934234 18301 slave.cpp:3590] Updated checkpointed resources from {} to cpus(reservations:
[(DYNAMIC,role,test-principal)]):1; mem(reservations: [(DYNAMIC,role,test-principal)]):512
3: I1122 05:26:14.936030 18297 hierarchical.cpp:856] Updated allocation of framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
on agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0 from cpus(allocated: role):1; mem(allocated:
role):512; disk(allocated: role):367489; ports(allocated: role):[31000-32000] to ports(allocated:
role):[31000-32000]; cpus(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):1;
disk(allocated: role):367489; mem(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):512
3: I1122 05:26:14.937106 18297 hierarchical.cpp:1132] Recovered ports(allocated: role):[31000-32000];
cpus(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):1; disk(allocated: role):367489;
mem(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):512 (total: ports:[31000-32000];
cpus(reservations: [(DYNAMIC,role,test-principal)]):1; disk:367489; mem(reservations: [(DYNAMIC,role,test-principal)]):512,
allocated: {}) on agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0 from framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
3: I1122 05:26:14.938459 18297 hierarchical.cpp:1457] Performed allocation for 1 agents in
1.134287ms
3: I1122 05:26:14.939018 18304 master.cpp:8136] Sending 1 offers to framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
(framework2) at scheduler-0cc18b0c-81c2-40e8-b795-8e0aa8e58f73@172.17.0.4:39249
3: I1122 05:26:14.939680 18302 sched.cpp:921] Scheduler::resourceOffers took 173376ns
3: I1122 05:26:14.940343 18300 master.cpp:5434] Processing DECLINE call for offers: [ bae28168-4f3c-460c-8d11-184dfcaf5550-O1
] for framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000 (framework2) at scheduler-0cc18b0c-81c2-40e8-b795-8e0aa8e58f73@172.17.0.4:39249
3: I1122 05:26:14.940665 18294 sched.cpp:232] Version: 1.5.0
3: I1122 05:26:14.940939 18300 master.cpp:10045] Removing offer bae28168-4f3c-460c-8d11-184dfcaf5550-O1
3: I1122 05:26:14.941179 18311 sched.cpp:336] New master detected at master@172.17.0.4:39249
3: I1122 05:26:14.941270 18311 sched.cpp:396] Authenticating with master master@172.17.0.4:39249
3: I1122 05:26:14.941290 18311 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1122 05:26:14.941488 18299 authenticatee.cpp:121] Creating new client SASL connection
3: I1122 05:26:14.941570 18313 hierarchical.cpp:1132] Recovered ports(allocated: role):[31000-32000];
cpus(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):1; disk(allocated: role):367489;
mem(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):512 (total: ports:[31000-32000];
cpus(reservations: [(DYNAMIC,role,test-principal)]):1; disk:367489; mem(reservations: [(DYNAMIC,role,test-principal)]):512,
allocated: {}) on agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0 from framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
3: W1122 05:26:14.941637 18313 hierarchical.cpp:1153] Using 365 days to create the refused
resources offer filter because the input value is too big
3: I1122 05:26:14.941671 18313 hierarchical.cpp:1178] Framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
filtered agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0 for 365days
3: I1122 05:26:14.941720 18296 master.cpp:8306] Authenticating scheduler-59461bb5-388b-42dc-ab94-dafb770945e5@172.17.0.4:39249
3: I1122 05:26:14.941807 18307 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(1058)@172.17.0.4:39249
3: I1122 05:26:14.941998 18317 authenticator.cpp:98] Creating new server SASL connection
3: I1122 05:26:14.942237 18295 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1122 05:26:14.942261 18295 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1122 05:26:14.942363 18305 authenticator.cpp:204] Received SASL authentication start
3: I1122 05:26:14.942417 18305 authenticator.cpp:326] Authentication requires more steps
3: I1122 05:26:14.942503 18305 authenticatee.cpp:259] Received SASL authentication step
3: I1122 05:26:14.942612 18297 authenticator.cpp:232] Received SASL authentication step
3: I1122 05:26:14.942638 18297 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '441d7a7e00ba' server FQDN: '441d7a7e00ba' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1122 05:26:14.942646 18297 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1122 05:26:14.942677 18297 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1122 05:26:14.942693 18297 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '441d7a7e00ba' server FQDN: '441d7a7e00ba' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1122 05:26:14.942703 18297 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1122 05:26:14.942708 18297 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1122 05:26:14.942719 18297 authenticator.cpp:318] Authentication success
3: I1122 05:26:14.942811 18304 authenticatee.cpp:299] Authentication success
3: I1122 05:26:14.942844 18316 master.cpp:8336] Successfully authenticated principal 'test-principal'
at scheduler-59461bb5-388b-42dc-ab94-dafb770945e5@172.17.0.4:39249
3: I1122 05:26:14.942878 18302 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1058)@172.17.0.4:39249
3: I1122 05:26:14.943066 18312 sched.cpp:502] Successfully authenticated with master master@172.17.0.4:39249
3: I1122 05:26:14.943089 18312 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.4:39249
3: I1122 05:26:14.943195 18312 sched.cpp:857] Will retry registration in 465.535723ms if necessary
3: I1122 05:26:14.943349 18308 master.cpp:2963] Received SUBSCRIBE call for framework 'default'
at scheduler-59461bb5-388b-42dc-ab94-dafb770945e5@172.17.0.4:39249
3: I1122 05:26:14.943419 18308 master.cpp:2280] Authorizing framework principal 'test-principal'
to receive offers for roles '{ role }'
3: I1122 05:26:14.943789 18298 master.cpp:3043] Subscribing framework default with checkpointing
disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1122 05:26:14.944401 18298 sched.cpp:751] Framework registered with bae28168-4f3c-460c-8d11-184dfcaf5550-0001
3: I1122 05:26:14.944447 18298 sched.cpp:765] Scheduler::registered took 18072ns
3: I1122 05:26:14.944677 18318 hierarchical.cpp:2175] Filtered offer with ports:[31000-32000];
cpus(reservations: [(DYNAMIC,role,test-principal)]):1; disk:367489; mem(reservations: [(DYNAMIC,role,test-principal)]):512
on agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0 for role role of framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
3: I1122 05:26:14.944857 18318 hierarchical.cpp:1457] Performed allocation for 1 agents in
884473ns
3: I1122 05:26:14.945349 18318 hierarchical.cpp:306] Added framework bae28168-4f3c-460c-8d11-184dfcaf5550-0001
3: I1122 05:26:14.947867 18318 hierarchical.cpp:1457] Performed allocation for 1 agents in
2.307727ms
3: I1122 05:26:14.948374 18309 master.cpp:8136] Sending 1 offers to framework bae28168-4f3c-460c-8d11-184dfcaf5550-0001
(default) at scheduler-59461bb5-388b-42dc-ab94-dafb770945e5@172.17.0.4:39249
3: I1122 05:26:14.948828 18311 sched.cpp:921] Scheduler::resourceOffers took 102670ns
3: I1122 05:26:14.949228 18294 sched.cpp:2009] Asked to stop the driver
3: I1122 05:26:14.949308 18307 sched.cpp:1191] Stopping framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
3: I1122 05:26:14.949501 18295 master.cpp:8813] Processing TEARDOWN call for framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
(framework2) at scheduler-0cc18b0c-81c2-40e8-b795-8e0aa8e58f73@172.17.0.4:39249
3: I1122 05:26:14.949527 18295 master.cpp:8825] Removing framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
(framework2) at scheduler-0cc18b0c-81c2-40e8-b795-8e0aa8e58f73@172.17.0.4:39249
3: I1122 05:26:14.949538 18295 master.cpp:3333] Deactivating framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
(framework2) at scheduler-0cc18b0c-81c2-40e8-b795-8e0aa8e58f73@172.17.0.4:39249
3: I1122 05:26:14.949581 18294 sched.cpp:2009] Asked to stop the driver
3: I1122 05:26:14.949635 18317 hierarchical.cpp:419] Deactivated framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
3: I1122 05:26:14.949723 18303 sched.cpp:1191] Stopping framework bae28168-4f3c-460c-8d11-184dfcaf5550-0001
3: I1122 05:26:14.949723 18304 slave.cpp:3270] Asked to shut down framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
by master@172.17.0.4:39249
3: I1122 05:26:14.949779 18304 slave.cpp:3285] Cannot shut down unknown framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
3: I1122 05:26:14.949884 18314 hierarchical.cpp:358] Removed framework bae28168-4f3c-460c-8d11-184dfcaf5550-0000
3: I1122 05:26:14.949970 18316 master.cpp:8813] Processing TEARDOWN call for framework bae28168-4f3c-460c-8d11-184dfcaf5550-0001
(default) at scheduler-59461bb5-388b-42dc-ab94-dafb770945e5@172.17.0.4:39249
3: I1122 05:26:14.949997 18316 master.cpp:8825] Removing framework bae28168-4f3c-460c-8d11-184dfcaf5550-0001
(default) at scheduler-59461bb5-388b-42dc-ab94-dafb770945e5@172.17.0.4:39249
3: I1122 05:26:14.950007 18316 master.cpp:3333] Deactivating framework bae28168-4f3c-460c-8d11-184dfcaf5550-0001
(default) at scheduler-59461bb5-388b-42dc-ab94-dafb770945e5@172.17.0.4:39249
3: I1122 05:26:14.950109 18312 hierarchical.cpp:419] Deactivated framework bae28168-4f3c-460c-8d11-184dfcaf5550-0001
3: I1122 05:26:14.950598 18316 master.cpp:10045] Removing offer bae28168-4f3c-460c-8d11-184dfcaf5550-O2
3: I1122 05:26:14.950759 18318 slave.cpp:3270] Asked to shut down framework bae28168-4f3c-460c-8d11-184dfcaf5550-0001
by master@172.17.0.4:39249
3: I1122 05:26:14.950784 18318 slave.cpp:3285] Cannot shut down unknown framework bae28168-4f3c-460c-8d11-184dfcaf5550-0001
3: I1122 05:26:14.951236 18308 hierarchical.cpp:1132] Recovered ports(allocated: role):[31000-32000];
cpus(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):1; disk(allocated: role):367489;
mem(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):512 (total: ports:[31000-32000];
cpus(reservations: [(DYNAMIC,role,test-principal)]):1; disk:367489; mem(reservations: [(DYNAMIC,role,test-principal)]):512,
allocated: {}) on agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0 from framework bae28168-4f3c-460c-8d11-184dfcaf5550-0001
3: I1122 05:26:14.951525 18308 hierarchical.cpp:358] Removed framework bae28168-4f3c-460c-8d11-184dfcaf5550-0001
3: I1122 05:26:14.951606 18301 slave.cpp:883] Agent terminating
3: I1122 05:26:14.951757 18312 master.cpp:1311] Agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0
at slave(522)@172.17.0.4:39249 (441d7a7e00ba) disconnected
3: I1122 05:26:14.951778 18312 master.cpp:3370] Disconnecting agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0
at slave(522)@172.17.0.4:39249 (441d7a7e00ba)
3: I1122 05:26:14.951820 18312 master.cpp:3389] Deactivating agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0
at slave(522)@172.17.0.4:39249 (441d7a7e00ba)
3: I1122 05:26:14.951915 18316 hierarchical.cpp:697] Agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0
deactivated
3: I1122 05:26:14.952639 18310 hierarchical.cpp:1457] Performed allocation for 1 agents in
132495ns
3: I1122 05:26:14.959959 18294 master.cpp:1153] Master terminating
3: I1122 05:26:14.960698 18316 hierarchical.cpp:633] Removed agent bae28168-4f3c-460c-8d11-184dfcaf5550-S0
3: I1122 05:26:14.960911 18316 hierarchical.cpp:1457] Performed allocation for 0 agents in
55329ns
3: [       OK ] ReservationTest.ReserveShareWithinRole (80 ms)
3: [ RUN      ] ReservationTest.DropReserveTooLarge
3: I1122 05:26:14.969665 18294 cluster.cpp:162] Creating default 'local' authorizer
3: I1122 05:26:14.973646 18316 master.cpp:448] Master 9bd9a95b-4846-4bb7-9a84-345b2c2bb223
(441d7a7e00ba) started on 172.17.0.4:39249
3: I1122 05:26:14.973668 18316 master.cpp:450] 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/blNDPj/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="/usr/local/share/mesos/webui" --work_dir="/tmp/blNDPj/master"
--zk_session_timeout="10secs"
3: I1122 05:26:14.973978 18316 master.cpp:499] Master only allowing authenticated frameworks
to register
3: I1122 05:26:14.973985 18316 master.cpp:505] Master only allowing authenticated agents to
register
3: I1122 05:26:14.973989 18316 master.cpp:511] Master only allowing authenticated HTTP frameworks
to register
3: I1122 05:26:14.973995 18316 credentials.hpp:37] Loading credentials for authentication
from '/tmp/blNDPj/credentials'
3: I1122 05:26:14.974270 18316 master.cpp:555] Using default 'crammd5' authenticator
3: I1122 05:26:14.974406 18316 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readonly'
3: I1122 05:26:14.974551 18316 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readwrite'
3: I1122 05:26:14.974670 18316 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-scheduler'
3: I1122 05:26:14.974797 18316 master.cpp:634] Authorization enabled
3: W1122 05:26:14.974810 18316 master.cpp:697] The '--roles' flag is deprecated. This flag
will be removed in the future. See the Mesos 0.27 upgrade notes for more information
3: I1122 05:26:14.975021 18304 hierarchical.cpp:173] Initialized hierarchical allocator process
3: I1122 05:26:14.975051 18309 whitelist_watcher.cpp:77] No whitelist given
3: I1122 05:26:14.978873 18298 master.cpp:2215] Elected as the leading master!
3: I1122 05:26:14.978927 18298 master.cpp:1695] Recovering from registrar
3: I1122 05:26:14.979251 18317 registrar.cpp:347] Recovering registrar
3: I1122 05:26:14.980207 18317 registrar.cpp:391] Successfully fetched the registry (0B) in
887040ns
3: I1122 05:26:14.980361 18317 registrar.cpp:495] Applied 1 operations in 43347ns; attempting
to update the registry
3: I1122 05:26:14.980445 18305 hierarchical.cpp:1457] Performed allocation for 0 agents in
41301ns
3: I1122 05:26:14.981220 18317 registrar.cpp:552] Successfully updated the registry in 775168ns
3: I1122 05:26:14.981408 18317 registrar.cpp:424] Successfully recovered registrar
3: I1122 05:26:14.982028 18310 hierarchical.cpp:211] Skipping recovery of hierarchical allocator:
nothing to recover
3: I1122 05:26:14.982025 18315 master.cpp:1808] Recovered 0 agents from the registry (129B);
allowing 10mins for agents to re-register
3: I1122 05:26:14.986515 18304 hierarchical.cpp:1457] Performed allocation for 0 agents in
34864ns
3: W1122 05:26:14.989728 18294 process.cpp:2756] Attempted to spawn already running process
files@172.17.0.4:39249
3: I1122 05:26:14.990844 18294 containerizer.cpp:301] Using isolation { environment_secret,
posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1122 05:26:14.991549 18294 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend
requires root privileges
3: W1122 05:26:14.991703 18294 backend.cpp:76] Failed to create 'bind' backend: BindBackend
requires root privileges
3: I1122 05:26:14.991753 18294 provisioner.cpp:259] Using default backend 'copy'
3: I1122 05:26:14.991988 18311 hierarchical.cpp:1457] Performed allocation for 0 agents in
34778ns
3: I1122 05:26:14.994154 18294 cluster.cpp:448] Creating default 'local' authorizer
3: I1122 05:26:14.996862 18304 slave.cpp:262] Mesos agent started on (523)@172.17.0.4:39249
3: I1122 05:26:14.996882 18304 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"
--appc_store_dir="/tmp/ReservationTest_DropReserveTooLarge_5UEk6k/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/ReservationTest_DropReserveTooLarge_5UEk6k/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/ReservationTest_DropReserveTooLarge_5UEk6k/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/ReservationTest_DropReserveTooLarge_5UEk6k/executor_secret_key"
--executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ReservationTest_DropReserveTooLarge_5UEk6k/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/ReservationTest_DropReserveTooLarge_5UEk6k/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO"
--max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs"
--perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms"
--resources="cpus:1;mem:512" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ReservationTest_DropReserveTooLarge_5UEk6k"
--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/ReservationTest_DropReserveTooLarge_bkgYsP"
--zk_session_timeout="10secs"
3: I1122 05:26:14.997289 18304 credentials.hpp:86] Loading credential for authentication from
'/tmp/ReservationTest_DropReserveTooLarge_5UEk6k/credential'
3: W1122 05:26:14.997375 18294 process.cpp:2756] Attempted to spawn already running process
version@172.17.0.4:39249
3: I1122 05:26:14.997460 18304 slave.cpp:295] Agent using credential for: test-principal
3: I1122 05:26:14.997480 18304 credentials.hpp:37] Loading credentials for authentication
from '/tmp/ReservationTest_DropReserveTooLarge_5UEk6k/http_credentials'
3: I1122 05:26:14.997766 18304 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-executor'
3: I1122 05:26:14.997880 18304 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-executor'
3: I1122 05:26:14.998101 18304 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readonly'
3: I1122 05:26:14.998198 18304 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-readonly'
3: I1122 05:26:14.998420 18304 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readwrite'
3: I1122 05:26:14.998538 18304 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-readwrite'
3: I1122 05:26:14.998594 18294 sched.cpp:232] Version: 1.5.0
3: I1122 05:26:14.999058 18306 sched.cpp:336] New master detected at master@172.17.0.4:39249
3: I1122 05:26:14.999274 18306 sched.cpp:396] Authenticating with master master@172.17.0.4:39249
3: I1122 05:26:14.999302 18306 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1122 05:26:14.999608 18315 authenticatee.cpp:121] Creating new client SASL connection
3: I1122 05:26:14.999972 18296 master.cpp:8306] Authenticating scheduler-d661bbf9-d512-45c4-a395-ba728cc187c2@172.17.0.4:39249
3: I1122 05:26:15.000073 18307 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(1059)@172.17.0.4:39249
3: I1122 05:26:14.999919 18304 slave.cpp:593] Agent resources: [{"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":512.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":367489.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1122 05:26:15.000164 18304 slave.cpp:601] Agent attributes: [  ]
3: I1122 05:26:15.000174 18304 slave.cpp:610] Agent hostname: 441d7a7e00ba
3: I1122 05:26:15.000304 18311 authenticator.cpp:98] Creating new server SASL connection
3: I1122 05:26:15.000412 18298 task_status_update_manager.cpp:181] Pausing sending task status
updates
3: I1122 05:26:15.000424 18318 hierarchical.cpp:1457] Performed allocation for 0 agents in
35447ns
3: I1122 05:26:15.000515 18313 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1122 05:26:15.000547 18313 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1122 05:26:15.000650 18318 authenticator.cpp:204] Received SASL authentication start
3: I1122 05:26:15.000705 18318 authenticator.cpp:326] Authentication requires more steps
3: I1122 05:26:15.000809 18305 authenticatee.cpp:259] Received SASL authentication step
3: I1122 05:26:15.000936 18299 authenticator.cpp:232] Received SASL authentication step
3: I1122 05:26:15.000963 18299 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '441d7a7e00ba' server FQDN: '441d7a7e00ba' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1122 05:26:15.000972 18299 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1122 05:26:15.000999 18299 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1122 05:26:15.001018 18299 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '441d7a7e00ba' server FQDN: '441d7a7e00ba' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1122 05:26:15.001029 18299 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1122 05:26:15.001034 18299 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1122 05:26:15.001045 18299 authenticator.cpp:318] Authentication success
3: I1122 05:26:15.001201 18302 authenticatee.cpp:299] Authentication success
3: I1122 05:26:15.001222 18309 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1059)@172.17.0.4:39249
3: I1122 05:26:15.001353 18314 master.cpp:8336] Successfully authenticated principal 'test-principal'
at scheduler-d661bbf9-d512-45c4-a395-ba728cc187c2@172.17.0.4:39249
3: I1122 05:26:15.001608 18317 sched.cpp:502] Successfully authenticated with master master@172.17.0.4:39249
3: I1122 05:26:15.001629 18317 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.4:39249
3: I1122 05:26:15.001724 18317 sched.cpp:857] Will retry registration in 1.729723578secs if
necessary
3: I1122 05:26:15.001911 18300 master.cpp:2963] Received SUBSCRIBE call for framework 'default'
at scheduler-d661bbf9-d512-45c4-a395-ba728cc187c2@172.17.0.4:39249
3: I1122 05:26:15.001993 18300 master.cpp:2280] Authorizing framework principal 'test-principal'
to receive offers for roles '{ role }'
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1511326391-22979
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user yujie.jay@gmail.com


Mime
View raw message