mesos-dev 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-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui #2299
Date Thu, 07 Aug 2014 08:20:50 GMT
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2299/changes>

Changes:

[niklas] Made doxygen recursive to include nested files.

[vinodkone] Added 'process::inject::exited()' to simulate exited events in tests.

------------------------------------------
[...truncated 57876 lines...]
I0807 08:19:40.399049 21674 recover.cpp:542] Updating replica status to VOTING
I0807 08:19:40.399365 21668 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took
111576ns
I0807 08:19:40.399384 21668 replica.cpp:320] Persisted replica status to VOTING
I0807 08:19:40.399426 21668 recover.cpp:556] Successfully joined the Paxos group
I0807 08:19:40.399478 21668 recover.cpp:440] Recover process terminated
I0807 08:19:40.399590 21668 log.cpp:656] Attempting to start the writer
I0807 08:19:40.399881 21668 replica.cpp:474] Replica received implicit promise request with
proposal 1
I0807 08:19:40.399977 21668 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took
78409ns
I0807 08:19:40.399992 21668 replica.cpp:342] Persisted promised to 1
I0807 08:19:40.400161 21668 coordinator.cpp:230] Coordinator attemping to fill missing position
I0807 08:19:40.400545 21668 replica.cpp:375] Replica received explicit promise request for
position 0 with proposal 2
I0807 08:19:40.400825 21668 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 258849ns
I0807 08:19:40.400846 21668 replica.cpp:676] Persisted action at 0
I0807 08:19:40.401116 21668 replica.cpp:508] Replica received write request for position 0
I0807 08:19:40.401141 21668 leveldb.cpp:438] Reading position from leveldb took 9566ns
I0807 08:19:40.401238 21668 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took
80564ns
I0807 08:19:40.401254 21668 replica.cpp:676] Persisted action at 0
I0807 08:19:40.401401 21668 replica.cpp:655] Replica received learned notice for position
0
I0807 08:19:40.401550 21668 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took
131624ns
I0807 08:19:40.401566 21668 replica.cpp:676] Persisted action at 0
I0807 08:19:40.401576 21668 replica.cpp:661] Replica learned NOP action at position 0
I0807 08:19:40.401738 21668 log.cpp:672] Writer started with ending position 0
I0807 08:19:40.401969 21668 leveldb.cpp:438] Reading position from leveldb took 9388ns
I0807 08:19:40.403502 21668 registrar.cpp:346] Successfully fetched the registry (0B)
I0807 08:19:40.403523 21668 registrar.cpp:422] Attempting to update the 'registry'
I0807 08:19:40.405083 21668 log.cpp:680] Attempting to append 139 bytes to the log
I0807 08:19:40.405133 21668 coordinator.cpp:340] Coordinator attempting to write APPEND action
at position 1
I0807 08:19:40.405334 21668 replica.cpp:508] Replica received write request for position 1
I0807 08:19:40.405632 21668 leveldb.cpp:343] Persisting action (158 bytes) to leveldb took
263402ns
I0807 08:19:40.405683 21668 replica.cpp:676] Persisted action at 1
I0807 08:19:40.412703 21660 replica.cpp:655] Replica received learned notice for position
1
I0807 08:19:40.413161 21660 leveldb.cpp:343] Persisting action (160 bytes) to leveldb took
434880ns
I0807 08:19:40.413180 21660 replica.cpp:676] Persisted action at 1
I0807 08:19:40.413194 21660 replica.cpp:661] Replica learned APPEND action at position 1
I0807 08:19:40.413668 21663 registrar.cpp:479] Successfully updated 'registry'
I0807 08:19:40.413715 21663 registrar.cpp:372] Successfully recovered registrar
I0807 08:19:40.413766 21663 log.cpp:699] Attempting to truncate the log to 1
I0807 08:19:40.413856 21663 master.cpp:985] Recovered 0 slaves from the Registry (101B) ;
allowing 10mins for slaves to re-register
I0807 08:19:40.413915 21663 coordinator.cpp:340] Coordinator attempting to write TRUNCATE
action at position 2
I0807 08:19:40.416528 21675 replica.cpp:508] Replica received write request for position 2
I0807 08:19:40.416903 21675 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took
346464ns
I0807 08:19:40.416925 21675 replica.cpp:676] Persisted action at 2
I0807 08:19:40.417213 21675 replica.cpp:655] Replica received learned notice for position
2
I0807 08:19:40.417352 21675 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took
119560ns
I0807 08:19:40.417386 21675 leveldb.cpp:401] Deleting ~1 keys from leveldb took 16196ns
I0807 08:19:40.417402 21675 replica.cpp:676] Persisted action at 2
I0807 08:19:40.417413 21675 replica.cpp:661] Replica learned TRUNCATE action at position 2
I0807 08:19:40.427443 21669 slave.cpp:167] Slave started on 200)@67.195.81.187:56582
I0807 08:19:40.427469 21669 credentials.hpp:84] Loading credential for authentication from
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_xwhvVw/credential'
I0807 08:19:40.427587 21669 slave.cpp:265] Slave using credential for: test-principal
I0807 08:19:40.427711 21669 slave.cpp:278] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000]
I0807 08:19:40.427789 21669 slave.cpp:306] Slave hostname: pomona.apache.org
I0807 08:19:40.427803 21669 slave.cpp:307] Slave checkpoint: false
I0807 08:19:40.428391 21669 state.cpp:33] Recovering state from '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xwhvVw/meta'
I0807 08:19:40.428514 21669 status_update_manager.cpp:193] Recovering status update manager
I0807 08:19:40.428625 21669 slave.cpp:3175] Finished recovery
I0807 08:19:40.428923 21669 slave.cpp:589] New master detected at master@67.195.81.187:56582
I0807 08:19:40.428953 21669 slave.cpp:663] Authenticating with master master@67.195.81.187:56582
I0807 08:19:40.429007 21669 slave.cpp:636] Detecting new master
I0807 08:19:40.429052 21669 status_update_manager.cpp:167] New master detected at master@67.195.81.187:56582
I0807 08:19:40.429097 21669 authenticatee.hpp:128] Creating new client SASL connection
I0807 08:19:40.429206 21669 master.cpp:3546] Authenticating slave(200)@67.195.81.187:56582
I0807 08:19:40.429301 21669 authenticator.hpp:156] Creating new server SASL connection
I0807 08:19:40.429368 21669 authenticatee.hpp:219] Received SASL authentication mechanisms:
CRAM-MD5
I0807 08:19:40.429390 21669 authenticatee.hpp:245] Attempting to authenticate with mechanism
'CRAM-MD5'
I0807 08:19:40.429430 21669 authenticator.hpp:262] Received SASL authentication start
I0807 08:19:40.429474 21669 authenticator.hpp:384] Authentication requires more steps
I0807 08:19:40.429512 21669 authenticatee.hpp:265] Received SASL authentication step
I0807 08:19:40.429561 21669 authenticator.hpp:290] Received SASL authentication step
I0807 08:19:40.429584 21669 auxprop.cpp:81] Request to lookup properties for user: 'test-principal'
realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH:
false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0807 08:19:40.429597 21669 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0807 08:19:40.429615 21669 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0807 08:19:40.429630 21669 auxprop.cpp:81] Request to lookup properties for user: 'test-principal'
realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH:
false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0807 08:19:40.429641 21669 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I0807 08:19:40.429651 21669 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I0807 08:19:40.429671 21669 authenticator.hpp:376] Authentication success
I0807 08:19:40.429709 21669 authenticatee.hpp:305] Authentication success
I0807 08:19:40.429741 21669 master.cpp:3586] Successfully authenticated principal 'test-principal'
at slave(200)@67.195.81.187:56582
I0807 08:19:40.429822 21669 slave.cpp:720] Successfully authenticated with master master@67.195.81.187:56582
I0807 08:19:40.429868 21669 slave.cpp:971] Will retry registration in 9.885177ms if necessary
I0807 08:19:40.429949 21669 master.cpp:2768] Registering slave at slave(200)@67.195.81.187:56582
(pomona.apache.org) with id 20140807-081940-3142697795-56582-21640-0
I0807 08:19:40.430075 21669 registrar.cpp:422] Attempting to update the 'registry'
I0807 08:19:40.431555 21669 log.cpp:680] Attempting to append 334 bytes to the log
I0807 08:19:40.431617 21669 coordinator.cpp:340] Coordinator attempting to write APPEND action
at position 3
I0807 08:19:40.431874 21669 replica.cpp:508] Replica received write request for position 3
I0807 08:19:40.432062 21669 leveldb.cpp:343] Persisting action (353 bytes) to leveldb took
166238ns
I0807 08:19:40.432081 21669 replica.cpp:676] Persisted action at 3
I0807 08:19:40.432399 21662 replica.cpp:655] Replica received learned notice for position
3
I0807 08:19:40.432807 21662 leveldb.cpp:343] Persisting action (355 bytes) to leveldb took
387186ns
I0807 08:19:40.432827 21662 replica.cpp:676] Persisted action at 3
I0807 08:19:40.432840 21662 replica.cpp:661] Replica learned APPEND action at position 3
I0807 08:19:40.433182 21662 registrar.cpp:479] Successfully updated 'registry'
I0807 08:19:40.433269 21662 log.cpp:699] Attempting to truncate the log to 3
I0807 08:19:40.433332 21662 master.cpp:2808] Registered slave 20140807-081940-3142697795-56582-21640-0
at slave(200)@67.195.81.187:56582 (pomona.apache.org)
I0807 08:19:40.433351 21662 master.cpp:4019] Adding slave 20140807-081940-3142697795-56582-21640-0
at slave(200)@67.195.81.187:56582 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000]
I0807 08:19:40.433472 21662 coordinator.cpp:340] Coordinator attempting to write TRUNCATE
action at position 4
I0807 08:19:40.433586 21662 slave.cpp:754] Registered with master master@67.195.81.187:56582;
given slave ID 20140807-081940-3142697795-56582-21640-0
I0807 08:19:40.433696 21662 hierarchical_allocator_process.hpp:439] Added slave 20140807-081940-3142697795-56582-21640-0
(pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available)
I0807 08:19:40.433749 21662 hierarchical_allocator_process.hpp:670] Performed allocation for
slave 20140807-081940-3142697795-56582-21640-0 in 9398ns
I0807 08:19:40.433821 21662 slave.cpp:2324] Received ping from slave-observer(182)@67.195.81.187:56582
I0807 08:19:40.434074 21662 replica.cpp:508] Replica received write request for position 4
I0807 08:19:40.434193 21662 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took
99207ns
I0807 08:19:40.434211 21662 replica.cpp:676] Persisted action at 4
I0807 08:19:40.434427 21662 replica.cpp:655] Replica received learned notice for position
4
I0807 08:19:40.434542 21662 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took
97348ns
I0807 08:19:40.434581 21662 leveldb.cpp:401] Deleting ~2 keys from leveldb took 18417ns
I0807 08:19:40.434597 21662 replica.cpp:676] Persisted action at 4
I0807 08:19:40.434610 21662 replica.cpp:661] Replica learned TRUNCATE action at position 4
I0807 08:19:40.439595 21640 sched.cpp:139] Version: 0.20.0
I0807 08:19:40.439865 21668 sched.cpp:235] New master detected at master@67.195.81.187:56582
I0807 08:19:40.439888 21668 sched.cpp:285] Authenticating with master master@67.195.81.187:56582
I0807 08:19:40.439963 21675 authenticatee.hpp:128] Creating new client SASL connection
I0807 08:19:40.440068 21675 master.cpp:3546] Authenticating scheduler-9a028315-f623-48c6-bbdb-4b73bfe3ccac@67.195.81.187:56582
I0807 08:19:40.440145 21668 authenticator.hpp:156] Creating new server SASL connection
I0807 08:19:40.440209 21668 authenticatee.hpp:219] Received SASL authentication mechanisms:
CRAM-MD5
I0807 08:19:40.440229 21668 authenticatee.hpp:245] Attempting to authenticate with mechanism
'CRAM-MD5'
I0807 08:19:40.440263 21668 authenticator.hpp:262] Received SASL authentication start
I0807 08:19:40.440301 21668 authenticator.hpp:384] Authentication requires more steps
I0807 08:19:40.440337 21668 authenticatee.hpp:265] Received SASL authentication step
I0807 08:19:40.440382 21668 authenticator.hpp:290] Received SASL authentication step
I0807 08:19:40.440402 21668 auxprop.cpp:81] Request to lookup properties for user: 'test-principal'
realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH:
false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0807 08:19:40.440413 21668 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0807 08:19:40.440433 21668 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0807 08:19:40.440446 21668 auxprop.cpp:81] Request to lookup properties for user: 'test-principal'
realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH:
false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0807 08:19:40.440455 21668 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I0807 08:19:40.440465 21668 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I0807 08:19:40.440481 21668 authenticator.hpp:376] Authentication success
I0807 08:19:40.440618 21673 authenticatee.hpp:305] Authentication success
I0807 08:19:40.440696 21673 sched.cpp:359] Successfully authenticated with master master@67.195.81.187:56582
I0807 08:19:40.440703 21671 master.cpp:3586] Successfully authenticated principal 'test-principal'
at scheduler-9a028315-f623-48c6-bbdb-4b73bfe3ccac@67.195.81.187:56582
I0807 08:19:40.440711 21673 sched.cpp:478] Sending registration request to master@67.195.81.187:56582
I0807 08:19:40.440819 21673 master.cpp:1246] Received registration request from scheduler-9a028315-f623-48c6-bbdb-4b73bfe3ccac@67.195.81.187:56582
I0807 08:19:40.440841 21673 master.cpp:1206] Authorizing framework principal 'test-principal'
to receive offers for role '*'
I0807 08:19:40.440986 21673 master.cpp:1305] Registering framework 20140807-081940-3142697795-56582-21640-0000
at scheduler-9a028315-f623-48c6-bbdb-4b73bfe3ccac@67.195.81.187:56582
I0807 08:19:40.441090 21664 sched.cpp:409] Framework registered with 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.441133 21664 sched.cpp:423] Scheduler::registered took 23991ns
I0807 08:19:40.441231 21666 hierarchical_allocator_process.hpp:326] Added framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.441282 21666 hierarchical_allocator_process.hpp:714] Offering cpus(*):2; mem(*):1024;
disk(*):1024; ports(*):[31000-32000] on slave 20140807-081940-3142697795-56582-21640-0 to
framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.441412 21666 hierarchical_allocator_process.hpp:650] Performed allocation for
1 slaves in 162626ns
I0807 08:19:40.441488 21665 master.hpp:816] Adding offer 20140807-081940-3142697795-56582-21640-0
with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140807-081940-3142697795-56582-21640-0
(pomona.apache.org)
I0807 08:19:40.441562 21665 master.cpp:3493] Sending 1 offers to framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.441843 21665 sched.cpp:546] Scheduler::resourceOffers took 164428ns
I0807 08:19:40.442045 21660 master.hpp:826] Removing offer 20140807-081940-3142697795-56582-21640-0
with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140807-081940-3142697795-56582-21640-0
(pomona.apache.org)
I0807 08:19:40.442106 21660 master.cpp:2130] Processing reply for offers: [ 20140807-081940-3142697795-56582-21640-0
] on slave 20140807-081940-3142697795-56582-21640-0 at slave(200)@67.195.81.187:56582 (pomona.apache.org)
for framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.442133 21660 master.cpp:2216] Authorizing framework principal 'test-principal'
to launch task 0 as user 'jenkins'
I0807 08:19:40.442430 21665 master.hpp:788] Adding task 0 with resources cpus(*):2; mem(*):1024
on slave 20140807-081940-3142697795-56582-21640-0 (pomona.apache.org)
I0807 08:19:40.442462 21665 master.cpp:2282] Launching task 0 of framework 20140807-081940-3142697795-56582-21640-0000
with resources cpus(*):2; mem(*):1024 on slave 20140807-081940-3142697795-56582-21640-0 at
slave(200)@67.195.81.187:56582 (pomona.apache.org)
I0807 08:19:40.442616 21665 slave.cpp:1002] Got assigned task 0 for framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.442788 21668 hierarchical_allocator_process.hpp:560] Recovered disk(*):1024;
ports(*):[31000-32000] (total allocatable: disk(*):1024; ports(*):[31000-32000]) on slave
20140807-081940-3142697795-56582-21640-0 from framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.442816 21665 slave.cpp:1112] Launching task 0 for framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.442817 21668 hierarchical_allocator_process.hpp:591] Framework 20140807-081940-3142697795-56582-21640-0000
filtered slave 20140807-081940-3142697795-56582-21640-0 for 5secs
I0807 08:19:40.444720 21665 exec.cpp:131] Version: 0.20.0
I0807 08:19:40.444769 21666 exec.cpp:181] Executor started at: executor(62)@67.195.81.187:56582
with pid 21640
I0807 08:19:40.444802 21665 slave.cpp:1222] Queuing task '0' for executor default of framework
'20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.444840 21665 slave.cpp:543] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xwhvVw/slaves/20140807-081940-3142697795-56582-21640-0/frameworks/20140807-081940-3142697795-56582-21640-0000/executors/default/runs/ab075de8-31a3-4b8c-9f66-35fa539d5afd'
E0807 08:19:40.444883 21665 slave.cpp:2471] Container 'ab075de8-31a3-4b8c-9f66-35fa539d5afd'
for executor 'default' of framework '20140807-081940-3142697795-56582-21640-0000' failed to
start: TaskInfo/ExecutorInfo not supported
I0807 08:19:40.444906 21665 slave.cpp:1733] Got registration for executor 'default' of framework
20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.444974 21665 slave.cpp:1852] Flushing queued task 0 for executor 'default'
of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.445005 21666 exec.cpp:205] Executor registered on slave 20140807-081940-3142697795-56582-21640-0
I0807 08:19:40.446370 21666 exec.cpp:217] Executor::registered took 13568ns
I0807 08:19:40.446425 21666 exec.cpp:292] Executor asked to run task '0'
I0807 08:19:40.446461 21666 exec.cpp:301] Executor::launchTask took 20496ns
I0807 08:19:40.447831 21666 exec.cpp:524] Executor sending status update TASK_RUNNING (UUID:
aca62569-cf28-47f0-903a-7e9b1c841b18) for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.447911 21666 slave.cpp:2087] Handling status update TASK_RUNNING (UUID: aca62569-cf28-47f0-903a-7e9b1c841b18)
for task 0 of framework 20140807-081940-3142697795-56582-21640-0000 from executor(62)@67.195.81.187:56582
I0807 08:19:40.448021 21670 status_update_manager.cpp:320] Received status update TASK_RUNNING
(UUID: aca62569-cf28-47f0-903a-7e9b1c841b18) for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.448042 21670 status_update_manager.cpp:499] Creating StatusUpdate stream for
task 0 of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.448166 21670 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING
(UUID: aca62569-cf28-47f0-903a-7e9b1c841b18) for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
to master@67.195.81.187:56582
I0807 08:19:40.448271 21664 master.cpp:3137] Forwarding status update TASK_RUNNING (UUID:
aca62569-cf28-47f0-903a-7e9b1c841b18) for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.448324 21664 master.cpp:3103] Status update TASK_RUNNING (UUID: aca62569-cf28-47f0-903a-7e9b1c841b18)
for task 0 of framework 20140807-081940-3142697795-56582-21640-0000 from slave 20140807-081940-3142697795-56582-21640-0
at slave(200)@67.195.81.187:56582 (pomona.apache.org)
I0807 08:19:40.448401 21664 sched.cpp:637] Scheduler::statusUpdate took 17616ns
I0807 08:19:40.448531 21664 master.cpp:2618] Forwarding status update acknowledgement aca62569-cf28-47f0-903a-7e9b1c841b18
for task 0 of framework 20140807-081940-3142697795-56582-21640-0000 to slave 20140807-081940-3142697795-56582-21640-0
at slave(200)@67.195.81.187:56582 (pomona.apache.org)
I0807 08:19:40.448271 21670 slave.cpp:2245] Status update manager successfully handled status
update TASK_RUNNING (UUID: aca62569-cf28-47f0-903a-7e9b1c841b18) for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.448633 21670 slave.cpp:2251] Sending acknowledgement for status update TASK_RUNNING
(UUID: aca62569-cf28-47f0-903a-7e9b1c841b18) for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
to executor(62)@67.195.81.187:56582
I0807 08:19:40.448715 21670 exec.cpp:338] Executor received status update acknowledgement
aca62569-cf28-47f0-903a-7e9b1c841b18 for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.448750 21670 status_update_manager.cpp:398] Received status update acknowledgement
(UUID: aca62569-cf28-47f0-903a-7e9b1c841b18) for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.448799 21670 slave.cpp:1673] Status update manager successfully handled status
update acknowledgement (UUID: aca62569-cf28-47f0-903a-7e9b1c841b18) for task 0 of framework
20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.452625 21670 slave.cpp:2573] Executor 'default' of framework 20140807-081940-3142697795-56582-21640-0000
exited with status 0
I0807 08:19:40.454008 21670 slave.cpp:2087] Handling status update TASK_LOST (UUID: 03cbd445-e61c-4d27-b35d-087b0e1be29b)
for task 0 of framework 20140807-081940-3142697795-56582-21640-0000 from @0.0.0.0:0
I0807 08:19:40.454033 21670 slave.cpp:3877] Terminating task 0
E0807 08:19:40.454185 21670 slave.cpp:2843] Failed to unmonitor container for executor default
of framework 20140807-081940-3142697795-56582-21640-0000: Not monitored
I0807 08:19:40.454232 21670 master.cpp:3186] Executor default of framework 20140807-081940-3142697795-56582-21640-0000
on slave 20140807-081940-3142697795-56582-21640-0 at slave(200)@67.195.81.187:56582 (pomona.apache.org)
exited with status 0
I0807 08:19:40.454283 21670 status_update_manager.cpp:320] Received status update TASK_LOST
(UUID: 03cbd445-e61c-4d27-b35d-087b0e1be29b) for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.454306 21670 status_update_manager.cpp:373] Forwarding status update TASK_LOST
(UUID: 03cbd445-e61c-4d27-b35d-087b0e1be29b) for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
to master@67.195.81.187:56582
I0807 08:19:40.454427 21670 master.cpp:3137] Forwarding status update TASK_LOST (UUID: 03cbd445-e61c-4d27-b35d-087b0e1be29b)
for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.454468 21670 master.cpp:3103] Status update TASK_LOST (UUID: 03cbd445-e61c-4d27-b35d-087b0e1be29b)
for task 0 of framework 20140807-081940-3142697795-56582-21640-0000 from slave 20140807-081940-3142697795-56582-21640-0
at slave(200)@67.195.81.187:56582 (pomona.apache.org)
I0807 08:19:40.454506 21670 master.hpp:806] Removing task 0 with resources cpus(*):2; mem(*):1024
on slave 20140807-081940-3142697795-56582-21640-0 (pomona.apache.org)
I0807 08:19:40.454572 21670 slave.cpp:2245] Status update manager successfully handled status
update TASK_LOST (UUID: 03cbd445-e61c-4d27-b35d-087b0e1be29b) for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.454635 21670 sched.cpp:637] Scheduler::statusUpdate took 9946ns
I0807 08:19:40.454699 21670 hierarchical_allocator_process.hpp:560] Recovered cpus(*):2; mem(*):1024
(total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave
20140807-081940-3142697795-56582-21640-0 from framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.454725 21670 hierarchical_allocator_process.hpp:591] Framework 20140807-081940-3142697795-56582-21640-0000
filtered slave 20140807-081940-3142697795-56582-21640-0 for 5secs
I0807 08:19:40.454769 21670 master.cpp:2618] Forwarding status update acknowledgement 03cbd445-e61c-4d27-b35d-087b0e1be29b
for task 0 of framework 20140807-081940-3142697795-56582-21640-0000 to slave 20140807-081940-3142697795-56582-21640-0
at slave(200)@67.195.81.187:56582 (pomona.apache.org)
I0807 08:19:40.454840 21670 status_update_manager.cpp:398] Received status update acknowledgement
(UUID: 03cbd445-e61c-4d27-b35d-087b0e1be29b) for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.454864 21670 status_update_manager.cpp:530] Cleaning up status update stream
for task 0 of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.454929 21670 slave.cpp:1673] Status update manager successfully handled status
update acknowledgement (UUID: 03cbd445-e61c-4d27-b35d-087b0e1be29b) for task 0 of framework
20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.454946 21670 slave.cpp:3919] Completing task 0
I0807 08:19:40.454957 21670 slave.cpp:2709] Cleaning up executor 'default' of framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.455051 21670 slave.cpp:2784] Cleaning up framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.455114 21670 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xwhvVw/slaves/20140807-081940-3142697795-56582-21640-0/frameworks/20140807-081940-3142697795-56582-21640-0000/executors/default/runs/ab075de8-31a3-4b8c-9f66-35fa539d5afd'
for gc 6.9999947629037days in the future
I0807 08:19:40.455157 21670 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xwhvVw/slaves/20140807-081940-3142697795-56582-21640-0/frameworks/20140807-081940-3142697795-56582-21640-0000/executors/default'
for gc 6.9999947629037days in the future
I0807 08:19:40.455193 21670 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xwhvVw/slaves/20140807-081940-3142697795-56582-21640-0/frameworks/20140807-081940-3142697795-56582-21640-0000'
for gc 6.9999947629037days in the future
I0807 08:19:40.455225 21670 status_update_manager.cpp:282] Closing status update streams for
framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.480617 21667 slave.cpp:3030] Current usage 90.00%. Max allowed age: 0ns
I0807 08:19:40.480670 21667 gc.cpp:167] Pruning directories with remaining removal time 6.99998318882963days
I0807 08:19:40.480700 21667 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_xwhvVw/slaves/20140807-081940-3142697795-56582-21640-0/frameworks/20140807-081940-3142697795-56582-21640-0000/executors/default/runs/ab075de8-31a3-4b8c-9f66-35fa539d5afd
I0807 08:19:40.480777 21667 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xwhvVw/slaves/20140807-081940-3142697795-56582-21640-0/frameworks/20140807-081940-3142697795-56582-21640-0000/executors/default/runs/ab075de8-31a3-4b8c-9f66-35fa539d5afd'
I0807 08:19:40.480808 21667 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_xwhvVw/slaves/20140807-081940-3142697795-56582-21640-0/frameworks/20140807-081940-3142697795-56582-21640-0000/executors/default
I0807 08:19:40.480934 21667 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xwhvVw/slaves/20140807-081940-3142697795-56582-21640-0/frameworks/20140807-081940-3142697795-56582-21640-0000/executors/default'
I0807 08:19:40.480950 21667 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_xwhvVw/slaves/20140807-081940-3142697795-56582-21640-0/frameworks/20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.481040 21667 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_xwhvVw/slaves/20140807-081940-3142697795-56582-21640-0/frameworks/20140807-081940-3142697795-56582-21640-0000'
I0807 08:19:40.488507 21667 hierarchical_allocator_process.hpp:714] Offering disk(*):1024;
ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave 20140807-081940-3142697795-56582-21640-0
to framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.488628 21667 hierarchical_allocator_process.hpp:650] Performed allocation for
1 slaves in 163779ns
I0807 08:19:40.488697 21667 master.hpp:816] Adding offer 20140807-081940-3142697795-56582-21640-1
with resources disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave 20140807-081940-3142697795-56582-21640-0
(pomona.apache.org)
I0807 08:19:40.488756 21667 master.cpp:3493] Sending 1 offers to framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.488847 21667 sched.cpp:546] Scheduler::resourceOffers took 9618ns
I0807 08:19:40.512449 21667 process.cpp:3335] Handling HTTP event for process 'files' with
path: '/files/browse.json'
I0807 08:19:40.528550 21664 sched.cpp:747] Stopping framework '20140807-081940-3142697795-56582-21640-0000'
I0807 08:19:40.528635 21663 master.cpp:1539] Asked to unregister framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.528648 21663 master.cpp:3869] Removing framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.528718 21663 master.hpp:826] Removing offer 20140807-081940-3142697795-56582-21640-1
with resources disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave 20140807-081940-3142697795-56582-21640-0
(pomona.apache.org)
I0807 08:19:40.528810 21663 hierarchical_allocator_process.hpp:402] Deactivated framework
20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.528893 21663 hierarchical_allocator_process.hpp:560] Recovered disk(*):1024;
ports(*):[31000-32000]; cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024; ports(*):[31000-32000];
cpus(*):2; mem(*):1024) on slave 20140807-081940-3142697795-56582-21640-0 from framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.528921 21663 hierarchical_allocator_process.hpp:591] Framework 20140807-081940-3142697795-56582-21640-0000
filtered slave 20140807-081940-3142697795-56582-21640-0 for 5secs
I0807 08:19:40.528967 21663 hierarchical_allocator_process.hpp:357] Removed framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.528993 21663 slave.cpp:1406] Asked to shut down framework 20140807-081940-3142697795-56582-21640-0000
by master@67.195.81.187:56582
W0807 08:19:40.529006 21663 slave.cpp:1421] Cannot shut down unknown framework 20140807-081940-3142697795-56582-21640-0000
I0807 08:19:40.529307 21663 master.cpp:624] Master terminating
I0807 08:19:40.556573 21666 slave.cpp:2356] master@67.195.81.187:56582 exited
W0807 08:19:40.556593 21666 slave.cpp:2359] Master disconnected! Waiting for a new master
to be elected
I0807 08:19:40.561239 21640 slave.cpp:466] Slave terminating
[       OK ] GarbageCollectorIntegrationTest.DiskUsage (202 ms)
[----------] 5 tests from GarbageCollectorIntegrationTest (1173 ms total)

[----------] Global test environment tear-down
[==========] 358 tests from 57 test cases ran. (211780 ms total)
[  PASSED  ] 357 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] HealthCheckTest.ConsecutiveFailures

 1 FAILED TEST
  YOU HAVE 3 DISABLED TESTS

make[3]: *** [check-local] Error 1
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make: *** [check-recursive] Error 1
Build step 'Execute shell' marked build as failure

Mime
View raw message