You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/12/04 02:35:03 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #2329
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2329/changes>
Changes:
[benjamin.mahler] Fixed a compiler warning from clang.
[yujie.jay] Splitted resource and resource usage checkers.
[yujie.jay] Added basic DiskInfo check in master.
[yujie.jay] Added tests for basic DiskInfo checker.
[yujie.jay] Added duplicated persistence id check in ResourceChecker.
------------------------------------------
[...truncated 74528 lines...]
I1204 01:34:52.652901 4416 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 399464ns
I1204 01:34:52.652927 4416 replica.cpp:342] Persisted promised to 1
I1204 01:34:52.653471 4413 coordinator.cpp:230] Coordinator attemping to fill missing position
I1204 01:34:52.654616 4412 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2
I1204 01:34:52.655025 4412 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 373991ns
I1204 01:34:52.655052 4412 replica.cpp:676] Persisted action at 0
I1204 01:34:52.656024 4414 replica.cpp:508] Replica received write request for position 0
I1204 01:34:52.656085 4414 leveldb.cpp:438] Reading position from leveldb took 28260ns
I1204 01:34:52.656519 4414 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 396443ns
I1204 01:34:52.656549 4414 replica.cpp:676] Persisted action at 0
I1204 01:34:52.657115 4408 replica.cpp:655] Replica received learned notice for position 0
I1204 01:34:52.657701 4408 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 548886ns
I1204 01:34:52.657730 4408 replica.cpp:676] Persisted action at 0
I1204 01:34:52.657752 4408 replica.cpp:661] Replica learned NOP action at position 0
I1204 01:34:52.658342 4419 log.cpp:672] Writer started with ending position 0
I1204 01:34:52.659327 4409 leveldb.cpp:438] Reading position from leveldb took 30931ns
I1204 01:34:52.662343 4420 registrar.cpp:346] Successfully fetched the registry (0B) in 11.43296ms
I1204 01:34:52.662487 4420 registrar.cpp:445] Applied 1 operations in 21664ns; attempting to update the 'registry'
I1204 01:34:52.665410 4410 log.cpp:680] Attempting to append 138 bytes to the log
I1204 01:34:52.665590 4411 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1
I1204 01:34:52.666290 4420 replica.cpp:508] Replica received write request for position 1
I1204 01:34:52.666707 4420 leveldb.cpp:343] Persisting action (157 bytes) to leveldb took 374672ns
I1204 01:34:52.666741 4420 replica.cpp:676] Persisted action at 1
I1204 01:34:52.667330 4408 replica.cpp:655] Replica received learned notice for position 1
I1204 01:34:52.667712 4408 leveldb.cpp:343] Persisting action (159 bytes) to leveldb took 352510ns
I1204 01:34:52.667747 4408 replica.cpp:676] Persisted action at 1
I1204 01:34:52.667780 4408 replica.cpp:661] Replica learned APPEND action at position 1
I1204 01:34:52.668567 4416 registrar.cpp:490] Successfully updated the 'registry' in 6.025728ms
I1204 01:34:52.668716 4416 registrar.cpp:376] Successfully recovered registrar
I1204 01:34:52.668808 4418 log.cpp:699] Attempting to truncate the log to 1
I1204 01:34:52.668975 4419 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 2
I1204 01:34:52.668978 4420 master.cpp:1068] Recovered 0 slaves from the Registry (100B) ; allowing 10mins for slaves to re-register
I1204 01:34:52.669848 4418 replica.cpp:508] Replica received write request for position 2
I1204 01:34:52.670197 4418 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 312969ns
I1204 01:34:52.670227 4418 replica.cpp:676] Persisted action at 2
I1204 01:34:52.670811 4411 replica.cpp:655] Replica received learned notice for position 2
I1204 01:34:52.671159 4411 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 316829ns
I1204 01:34:52.671219 4411 leveldb.cpp:401] Deleting ~1 keys from leveldb took 33212ns
I1204 01:34:52.671242 4411 replica.cpp:676] Persisted action at 2
I1204 01:34:52.671264 4411 replica.cpp:661] Replica learned TRUNCATE action at position 2
I1204 01:34:52.680135 4394 containerizer.cpp:101] Using isolation: posix/cpu,posix/mem
I1204 01:34:52.689748 4412 slave.cpp:170] Slave started on 235)@67.195.81.190:50701
I1204 01:34:52.689785 4412 credentials.hpp:84] Loading credential for authentication from '/tmp/SlaveTest_MesosExecutorCommandTaskWithArgsList_FhRXaU/credential'
I1204 01:34:52.689929 4412 slave.cpp:279] Slave using credential for: test-principal
I1204 01:34:52.690191 4412 slave.cpp:292] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1204 01:34:52.690316 4412 slave.cpp:321] Slave hostname: pietas.apache.org
I1204 01:34:52.690340 4412 slave.cpp:322] Slave checkpoint: false
W1204 01:34:52.690353 4412 slave.cpp:324] Disabling checkpointing is deprecated and the --checkpoint flag will be removed in a future release. Please avoid using this flag
I1204 01:34:52.691076 4409 state.cpp:33] Recovering state from '/tmp/SlaveTest_MesosExecutorCommandTaskWithArgsList_FhRXaU/meta'
I1204 01:34:52.691243 4415 status_update_manager.cpp:197] Recovering status update manager
I1204 01:34:52.691396 4420 containerizer.cpp:291] Recovering containerizer
I1204 01:34:52.692051 4414 slave.cpp:3480] Finished recovery
I1204 01:34:52.692452 4409 status_update_manager.cpp:171] Pausing sending status updates
I1204 01:34:52.692459 4419 slave.cpp:605] New master detected at master@67.195.81.190:50701
I1204 01:34:52.692520 4419 slave.cpp:668] Authenticating with master master@67.195.81.190:50701
I1204 01:34:52.692543 4419 slave.cpp:673] Using default CRAM-MD5 authenticatee
I1204 01:34:52.692654 4419 slave.cpp:641] Detecting new master
I1204 01:34:52.692787 4411 authenticatee.hpp:138] Creating new client SASL connection
I1204 01:34:52.692968 4419 master.cpp:3908] Authenticating slave(235)@67.195.81.190:50701
I1204 01:34:52.692993 4419 master.cpp:3919] Using default CRAM-MD5 authenticator
I1204 01:34:52.693305 4422 authenticator.hpp:170] Creating new server SASL connection
I1204 01:34:52.693449 4421 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5
I1204 01:34:52.693475 4421 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5'
I1204 01:34:52.693564 4419 authenticator.hpp:276] Received SASL authentication start
I1204 01:34:52.693614 4419 authenticator.hpp:398] Authentication requires more steps
I1204 01:34:52.693702 4417 authenticatee.hpp:275] Received SASL authentication step
I1204 01:34:52.693804 4412 authenticator.hpp:304] Received SASL authentication step
I1204 01:34:52.693832 4412 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'pietas.apache.org' server FQDN: 'pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1204 01:34:52.693847 4412 auxprop.cpp:171] Looking up auxiliary property '*userPassword'
I1204 01:34:52.693877 4412 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1204 01:34:52.693899 4412 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'pietas.apache.org' server FQDN: 'pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1204 01:34:52.693908 4412 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1204 01:34:52.693915 4412 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1204 01:34:52.693928 4412 authenticator.hpp:390] Authentication success
I1204 01:34:52.693994 4417 authenticatee.hpp:315] Authentication success
I1204 01:34:52.694016 4410 master.cpp:3966] Successfully authenticated principal 'test-principal' at slave(235)@67.195.81.190:50701
I1204 01:34:52.694211 4420 slave.cpp:739] Successfully authenticated with master master@67.195.81.190:50701
I1204 01:34:52.694303 4420 slave.cpp:1067] Will retry registration in 6.722248ms if necessary
I1204 01:34:52.694475 4417 master.cpp:3066] Registering slave at slave(235)@67.195.81.190:50701 (pietas.apache.org) with id 20141204-013452-3193029443-50701-4394-S0
I1204 01:34:52.694792 4415 registrar.cpp:445] Applied 1 operations in 43556ns; attempting to update the 'registry'
I1204 01:34:52.696018 4394 sched.cpp:148] Version: 0.22.0
I1204 01:34:52.696496 4422 sched.cpp:245] New master detected at master@67.195.81.190:50701
I1204 01:34:52.696549 4422 sched.cpp:301] Authenticating with master master@67.195.81.190:50701
I1204 01:34:52.696571 4422 sched.cpp:308] Using default CRAM-MD5 authenticatee
I1204 01:34:52.696784 4423 authenticatee.hpp:138] Creating new client SASL connection
I1204 01:34:52.696934 4420 master.cpp:3908] Authenticating scheduler-b86deaa8-613c-4ba0-a146-53b1086fb5f0@67.195.81.190:50701
I1204 01:34:52.696960 4420 master.cpp:3919] Using default CRAM-MD5 authenticator
I1204 01:34:52.697170 4423 authenticator.hpp:170] Creating new server SASL connection
I1204 01:34:52.697317 4410 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5
I1204 01:34:52.697341 4410 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5'
I1204 01:34:52.697423 4416 authenticator.hpp:276] Received SASL authentication start
I1204 01:34:52.697456 4423 log.cpp:680] Attempting to append 314 bytes to the log
I1204 01:34:52.697482 4416 authenticator.hpp:398] Authentication requires more steps
I1204 01:34:52.697562 4410 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3
I1204 01:34:52.697599 4411 authenticatee.hpp:275] Received SASL authentication step
I1204 01:34:52.697729 4408 authenticator.hpp:304] Received SASL authentication step
I1204 01:34:52.697759 4408 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'pietas.apache.org' server FQDN: 'pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1204 01:34:52.697772 4408 auxprop.cpp:171] Looking up auxiliary property '*userPassword'
I1204 01:34:52.697794 4408 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1204 01:34:52.697828 4408 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'pietas.apache.org' server FQDN: 'pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1204 01:34:52.697840 4408 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1204 01:34:52.697847 4408 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1204 01:34:52.697872 4408 authenticator.hpp:390] Authentication success
I1204 01:34:52.697953 4417 authenticatee.hpp:315] Authentication success
I1204 01:34:52.697978 4409 master.cpp:3966] Successfully authenticated principal 'test-principal' at scheduler-b86deaa8-613c-4ba0-a146-53b1086fb5f0@67.195.81.190:50701
I1204 01:34:52.698128 4413 replica.cpp:508] Replica received write request for position 3
I1204 01:34:52.698233 4417 sched.cpp:389] Successfully authenticated with master master@67.195.81.190:50701
I1204 01:34:52.698257 4417 sched.cpp:512] Sending registration request to master@67.195.81.190:50701
I1204 01:34:52.698314 4417 sched.cpp:545] Will retry registration in 1.347343533secs if necessary
I1204 01:34:52.698379 4413 leveldb.cpp:343] Persisting action (333 bytes) to leveldb took 216378ns
I1204 01:34:52.698392 4412 master.cpp:1330] Received registration request for framework 'default' at scheduler-b86deaa8-613c-4ba0-a146-53b1086fb5f0@67.195.81.190:50701
I1204 01:34:52.698400 4413 replica.cpp:676] Persisted action at 3
I1204 01:34:52.698482 4412 master.cpp:1289] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1204 01:34:52.698825 4418 master.cpp:1394] Registering framework 20141204-013452-3193029443-50701-4394-0000 (default) at scheduler-b86deaa8-613c-4ba0-a146-53b1086fb5f0@67.195.81.190:50701
I1204 01:34:52.698966 4413 replica.cpp:655] Replica received learned notice for position 3
I1204 01:34:52.699097 4415 hierarchical_allocator_process.hpp:297] Added framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.699126 4415 hierarchical_allocator_process.hpp:679] No resources available to allocate!
I1204 01:34:52.699142 4415 hierarchical_allocator_process.hpp:645] Performed allocation for 0 slaves in 19807ns
I1204 01:34:52.699349 4423 sched.cpp:439] Framework registered with 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.699386 4423 sched.cpp:453] Scheduler::registered took 15283ns
I1204 01:34:52.699539 4413 leveldb.cpp:343] Persisting action (335 bytes) to leveldb took 547904ns
I1204 01:34:52.699563 4413 replica.cpp:676] Persisted action at 3
I1204 01:34:52.699584 4413 replica.cpp:661] Replica learned APPEND action at position 3
I1204 01:34:52.700456 4423 registrar.cpp:490] Successfully updated the 'registry' in 5.583104ms
I1204 01:34:52.700712 4417 log.cpp:699] Attempting to truncate the log to 3
I1204 01:34:52.700836 4418 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4
I1204 01:34:52.701053 4414 slave.cpp:2551] Received ping from slave-observer(216)@67.195.81.190:50701
I1204 01:34:52.701256 4416 master.cpp:3120] Registered slave 20141204-013452-3193029443-50701-4394-S0 at slave(235)@67.195.81.190:50701 (pietas.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1204 01:34:52.701306 4409 slave.cpp:773] Registered with master master@67.195.81.190:50701; given slave ID 20141204-013452-3193029443-50701-4394-S0
I1204 01:34:52.701333 4415 hierarchical_allocator_process.hpp:425] Added slave 20141204-013452-3193029443-50701-4394-S0 (pietas.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available)
I1204 01:34:52.701452 4419 status_update_manager.cpp:178] Resuming sending status updates
I1204 01:34:52.701565 4418 replica.cpp:508] Replica received write request for position 4
I1204 01:34:52.701549 4415 hierarchical_allocator_process.hpp:716] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141204-013452-3193029443-50701-4394-S0 to framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.701813 4415 hierarchical_allocator_process.hpp:663] Performed allocation for slave 20141204-013452-3193029443-50701-4394-S0 in 429405ns
I1204 01:34:52.701946 4418 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 340355ns
I1204 01:34:52.701972 4418 replica.cpp:676] Persisted action at 4
I1204 01:34:52.702056 4423 master.cpp:3850] Sending 1 offers to framework 20141204-013452-3193029443-50701-4394-0000 (default) at scheduler-b86deaa8-613c-4ba0-a146-53b1086fb5f0@67.195.81.190:50701
I1204 01:34:52.702512 4422 sched.cpp:602] Scheduler::resourceOffers took 101408ns
I1204 01:34:52.702608 4409 replica.cpp:655] Replica received learned notice for position 4
I1204 01:34:52.702968 4409 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 327379ns
I1204 01:34:52.703047 4409 leveldb.cpp:401] Deleting ~2 keys from leveldb took 46876ns
I1204 01:34:52.703075 4409 replica.cpp:676] Persisted action at 4
I1204 01:34:52.703104 4409 replica.cpp:661] Replica learned TRUNCATE action at position 4
I1204 01:34:52.703742 4422 master.cpp:2396] Processing reply for offers: [ 20141204-013452-3193029443-50701-4394-O0 ] on slave 20141204-013452-3193029443-50701-4394-S0 at slave(235)@67.195.81.190:50701 (pietas.apache.org) for framework 20141204-013452-3193029443-50701-4394-0000 (default) at scheduler-b86deaa8-613c-4ba0-a146-53b1086fb5f0@67.195.81.190:50701
I1204 01:34:52.703775 4422 master.cpp:2495] Authorizing framework principal 'test-principal' to launch task 1 as user 'jenkins'
I1204 01:34:52.704638 4419 master.hpp:759] Adding task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141204-013452-3193029443-50701-4394-S0 (pietas.apache.org)
I1204 01:34:52.704782 4419 master.cpp:2694] Launching task 1 of framework 20141204-013452-3193029443-50701-4394-0000 (default) at scheduler-b86deaa8-613c-4ba0-a146-53b1086fb5f0@67.195.81.190:50701 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141204-013452-3193029443-50701-4394-S0 at slave(235)@67.195.81.190:50701 (pietas.apache.org)
I1204 01:34:52.705086 4415 slave.cpp:1098] Got assigned task 1 for framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.705761 4415 slave.cpp:1208] Launching task 1 for framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.708417 4415 slave.cpp:3862] Launching executor 1 of framework 20141204-013452-3193029443-50701-4394-0000 in work directory '/tmp/SlaveTest_MesosExecutorCommandTaskWithArgsList_FhRXaU/slaves/20141204-013452-3193029443-50701-4394-S0/frameworks/20141204-013452-3193029443-50701-4394-0000/executors/1/runs/9ae12e36-bdd6-4933-94cc-44bfd191001d'
I1204 01:34:52.708870 4415 slave.cpp:1331] Queuing task '1' for executor 1 of framework '20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.708890 4421 containerizer.cpp:436] Starting container '9ae12e36-bdd6-4933-94cc-44bfd191001d' for executor '1' of framework '20141204-013452-3193029443-50701-4394-0000'
I1204 01:34:52.709012 4415 slave.cpp:558] Successfully attached file '/tmp/SlaveTest_MesosExecutorCommandTaskWithArgsList_FhRXaU/slaves/20141204-013452-3193029443-50701-4394-S0/frameworks/20141204-013452-3193029443-50701-4394-0000/executors/1/runs/9ae12e36-bdd6-4933-94cc-44bfd191001d'
I1204 01:34:52.712910 4421 launcher.cpp:137] Forked child with pid '9963' for container '9ae12e36-bdd6-4933-94cc-44bfd191001d'
I1204 01:34:52.714766 4420 slave.cpp:2853] Monitoring executor '1' of framework '20141204-013452-3193029443-50701-4394-0000' in container '9ae12e36-bdd6-4933-94cc-44bfd191001d'
I1204 01:34:52.828215 4414 slave.cpp:1875] Got registration for executor '1' of framework 20141204-013452-3193029443-50701-4394-0000 from executor(1)@67.195.81.190:41612
I1204 01:34:52.828996 4414 slave.cpp:1994] Flushing queued task 1 for executor '1' of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.837519 4421 slave.cpp:2228] Handling status update TASK_RUNNING (UUID: 990e3045-0a72-4948-b443-1d62323e54ae) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000 from executor(1)@67.195.81.190:41612
I1204 01:34:52.837826 4412 status_update_manager.cpp:317] Received status update TASK_RUNNING (UUID: 990e3045-0a72-4948-b443-1d62323e54ae) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.837874 4412 status_update_manager.cpp:494] Creating StatusUpdate stream for task 1 of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.838155 4412 status_update_manager.cpp:371] Forwarding update TASK_RUNNING (UUID: 990e3045-0a72-4948-b443-1d62323e54ae) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000 to the slave
I1204 01:34:52.838374 4415 slave.cpp:2471] Forwarding the update TASK_RUNNING (UUID: 990e3045-0a72-4948-b443-1d62323e54ae) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000 to master@67.195.81.190:50701
I1204 01:34:52.838611 4415 slave.cpp:2398] Status update manager successfully handled status update TASK_RUNNING (UUID: 990e3045-0a72-4948-b443-1d62323e54ae) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.838657 4415 slave.cpp:2404] Sending acknowledgement for status update TASK_RUNNING (UUID: 990e3045-0a72-4948-b443-1d62323e54ae) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000 to executor(1)@67.195.81.190:41612
I1204 01:34:52.838726 4417 master.cpp:3443] Forwarding status update TASK_RUNNING (UUID: 990e3045-0a72-4948-b443-1d62323e54ae) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.838888 4417 master.cpp:3415] Status update TASK_RUNNING (UUID: 990e3045-0a72-4948-b443-1d62323e54ae) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000 from slave 20141204-013452-3193029443-50701-4394-S0 at slave(235)@67.195.81.190:50701 (pietas.apache.org)
I1204 01:34:52.838963 4417 master.cpp:4707] Updating the latest state of task 1 of framework 20141204-013452-3193029443-50701-4394-0000 to TASK_RUNNING
I1204 01:34:52.839092 4413 sched.cpp:693] Scheduler::statusUpdate took 85144ns
I1204 01:34:52.839416 4416 master.cpp:2916] Forwarding status update acknowledgement 990e3045-0a72-4948-b443-1d62323e54ae for task 1 of framework 20141204-013452-3193029443-50701-4394-0000 (default) at scheduler-b86deaa8-613c-4ba0-a146-53b1086fb5f0@67.195.81.190:50701 to slave 20141204-013452-3193029443-50701-4394-S0 at slave(235)@67.195.81.190:50701 (pietas.apache.org)
I1204 01:34:52.839730 4415 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 990e3045-0a72-4948-b443-1d62323e54ae) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.839984 4422 slave.cpp:1815] Status update manager successfully handled status update acknowledgement (UUID: 990e3045-0a72-4948-b443-1d62323e54ae) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.938719 4421 slave.cpp:2228] Handling status update TASK_FINISHED (UUID: 37ba0ef9-e6c9-4360-ba83-d8db35c5925f) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000 from executor(1)@67.195.81.190:41612
I1204 01:34:52.938822 4421 slave.cpp:4170] Terminating task 1
I1204 01:34:52.939888 4414 status_update_manager.cpp:317] Received status update TASK_FINISHED (UUID: 37ba0ef9-e6c9-4360-ba83-d8db35c5925f) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.939982 4414 status_update_manager.cpp:371] Forwarding update TASK_FINISHED (UUID: 37ba0ef9-e6c9-4360-ba83-d8db35c5925f) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000 to the slave
I1204 01:34:52.940219 4418 slave.cpp:2471] Forwarding the update TASK_FINISHED (UUID: 37ba0ef9-e6c9-4360-ba83-d8db35c5925f) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000 to master@67.195.81.190:50701
I1204 01:34:52.940405 4418 slave.cpp:2398] Status update manager successfully handled status update TASK_FINISHED (UUID: 37ba0ef9-e6c9-4360-ba83-d8db35c5925f) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.940451 4418 slave.cpp:2404] Sending acknowledgement for status update TASK_FINISHED (UUID: 37ba0ef9-e6c9-4360-ba83-d8db35c5925f) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000 to executor(1)@67.195.81.190:41612
I1204 01:34:52.940495 4416 master.cpp:3443] Forwarding status update TASK_FINISHED (UUID: 37ba0ef9-e6c9-4360-ba83-d8db35c5925f) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.940620 4416 master.cpp:3415] Status update TASK_FINISHED (UUID: 37ba0ef9-e6c9-4360-ba83-d8db35c5925f) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000 from slave 20141204-013452-3193029443-50701-4394-S0 at slave(235)@67.195.81.190:50701 (pietas.apache.org)
I1204 01:34:52.940672 4416 master.cpp:4707] Updating the latest state of task 1 of framework 20141204-013452-3193029443-50701-4394-0000 to TASK_FINISHED
I1204 01:34:52.940853 4415 sched.cpp:693] Scheduler::statusUpdate took 96917ns
I1204 01:34:52.941045 4419 hierarchical_allocator_process.hpp:552] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on slave 20141204-013452-3193029443-50701-4394-S0 from framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.941092 4394 sched.cpp:1364] Asked to stop the driver
I1204 01:34:52.941208 4409 sched.cpp:805] Stopping framework '20141204-013452-3193029443-50701-4394-0000'
I1204 01:34:52.941261 4423 master.cpp:4766] Removing task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 20141204-013452-3193029443-50701-4394-0000 on slave 20141204-013452-3193029443-50701-4394-S0 at slave(235)@67.195.81.190:50701 (pietas.apache.org)
I1204 01:34:52.941463 4423 master.cpp:2916] Forwarding status update acknowledgement 37ba0ef9-e6c9-4360-ba83-d8db35c5925f for task 1 of framework 20141204-013452-3193029443-50701-4394-0000 (default) at scheduler-b86deaa8-613c-4ba0-a146-53b1086fb5f0@67.195.81.190:50701 to slave 20141204-013452-3193029443-50701-4394-S0 at slave(235)@67.195.81.190:50701 (pietas.apache.org)
I1204 01:34:52.941622 4423 master.cpp:645] Master terminating
I1204 01:34:52.941776 4420 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 37ba0ef9-e6c9-4360-ba83-d8db35c5925f) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.941876 4420 status_update_manager.cpp:525] Cleaning up status update stream for task 1 of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.942121 4409 slave.cpp:1815] Status update manager successfully handled status update acknowledgement (UUID: 37ba0ef9-e6c9-4360-ba83-d8db35c5925f) for task 1 of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:52.942181 4409 slave.cpp:4209] Completing task 1
I1204 01:34:52.942275 4415 slave.cpp:2636] master@67.195.81.190:50701 exited
W1204 01:34:52.942302 4415 slave.cpp:2639] Master disconnected! Waiting for a new master to be elected
I1204 01:34:52.957697 4409 containerizer.cpp:887] Destroying container '9ae12e36-bdd6-4933-94cc-44bfd191001d'
I1204 01:34:53.091491 4414 containerizer.cpp:1094] Executor for container '9ae12e36-bdd6-4933-94cc-44bfd191001d' has exited
I1204 01:34:53.092785 4416 slave.cpp:2911] Executor '1' of framework 20141204-013452-3193029443-50701-4394-0000 terminated with signal Killed
I1204 01:34:53.092965 4416 slave.cpp:3020] Cleaning up executor '1' of framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:53.093252 4412 gc.cpp:56] Scheduling '/tmp/SlaveTest_MesosExecutorCommandTaskWithArgsList_FhRXaU/slaves/20141204-013452-3193029443-50701-4394-S0/frameworks/20141204-013452-3193029443-50701-4394-0000/executors/1/runs/9ae12e36-bdd6-4933-94cc-44bfd191001d' for gc 6.99999892193481days in the future
I1204 01:34:53.093354 4416 slave.cpp:3097] Cleaning up framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:53.093477 4412 gc.cpp:56] Scheduling '/tmp/SlaveTest_MesosExecutorCommandTaskWithArgsList_FhRXaU/slaves/20141204-013452-3193029443-50701-4394-S0/frameworks/20141204-013452-3193029443-50701-4394-0000/executors/1' for gc 6.99999892015111days in the future
I1204 01:34:53.093531 4408 status_update_manager.cpp:279] Closing status update streams for framework 20141204-013452-3193029443-50701-4394-0000
I1204 01:34:53.093560 4416 slave.cpp:487] Slave terminating
I1204 01:34:53.093600 4412 gc.cpp:56] Scheduling '/tmp/SlaveTest_MesosExecutorCommandTaskWithArgsList_FhRXaU/slaves/20141204-013452-3193029443-50701-4394-S0/frameworks/20141204-013452-3193029443-50701-4394-0000' for gc 6.99999891788444days in the future
[ OK ] SlaveTest.MesosExecutorCommandTaskWithArgsList (469 ms)
[----------] 15 tests from SlaveTest (3532 ms total)
[----------] 1 test from MesosContainerizerDestroyTest
[ RUN ] MesosContainerizerDestroyTest.DestroyWhileFetching
Using temporary directory '/tmp/MesosContainerizerDestroyTest_DestroyWhileFetching_ZvZG5g'
I1204 01:34:53.102087 4413 containerizer.cpp:436] Starting container 'test_container' for executor 'executor' of framework ''
I1204 01:34:53.105131 4413 launcher.cpp:137] Forked child with pid '10009' for container 'test_container'
I1204 01:34:53.106456 4413 containerizer.cpp:887] Destroying container 'test_container'
I1204 01:34:53.106508 4413 containerizer.cpp:909] Waiting for the isolators to complete for container 'test_container'
ABORT: (src/subprocess.cpp:165): Failed to os::execvpe in childMain
*** Aborted at 1417656893 (unix time) try "date -d @1417656893" if you are using GNU date ***
PC: @ 0x2b33cf98cbb9 (unknown)
*** SIGABRT (@0x95b00002719) received by PID 10009 (TID 0x2b33d23ba700) from PID 10009; stack trace: ***
@ 0x2b33cf748340 (unknown)
@ 0x2b33cf98cbb9 (unknown)
@ 0x2b33cf98ffc8 (unknown)
@ 0x8d2097 _Abort()
@ 0x2b33cd4443e7 process::childMain()
@ 0x2b33cd449292 _ZNSt5_BindIFPFiRKSsPPcRKN7process10Subprocess2IOES8_S8_PN2os7ExecEnvERK6OptionISt8functionIFivEEEPiSJ_SJ_ESsS3_S6_S6_S6_SB_SG_SJ_SJ_SJ_EE6__callIiJEJLm0ELm1ELm2ELm3ELm4ELm5ELm6ELm7ELm8ELm9EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
2014-12-04 01:34:53,162:4394(0x2b3727fad700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:43360] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I1204 01:34:53.192342 4417 containerizer.cpp:1094] Executor for container 'test_container' has exited
[ OK ] MesosContainerizerDestroyTest.DestroyWhileFetching (93 ms)
[----------] 1 test from MesosContainerizerDestroyTest (93 ms total)
[----------] Global test environment tear-down
[==========] 489 tests from 77 test cases ran. (232594 ms total)
[ PASSED ] 488 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ExamplesTest.LowLevelSchedulerPthread
1 FAILED TEST
YOU HAVE 5 DISABLED TESTS
make[3]: *** [check-local] Error 1
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make: *** [check-recursive] Error 1
Test finished
Exit status: 2
Build step 'Execute shell' marked build as failure
Recording test results
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #2330
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2330/changes>