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/04/29 00:48:15 UTC
Build failed in Jenkins: Mesos-Ubuntu-distcheck #62
See <https://builds.apache.org/job/Mesos-Ubuntu-distcheck/62/changes>
Changes:
[benjamin.hindman] [MESOS-1252]: Support ENV MAVEN_HOME on the build.
------------------------------------------
[...truncated 20929 lines...]
I0428 22:48:04.319443 31511 status_update_manager.cpp:167] New master detected at master@67.195.138.8:38083
I0428 22:48:04.319469 31511 authenticatee.hpp:128] Creating new client SASL connection
I0428 22:48:04.319550 31511 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0428 22:48:04.319564 31511 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0428 22:48:04.319598 31511 master.cpp:2795] Authenticating slave(54)@67.195.138.8:38083
I0428 22:48:04.319660 31511 authenticator.hpp:254] Received SASL authentication start
I0428 22:48:04.319695 31511 authenticator.hpp:342] Authentication requires more steps
I0428 22:48:04.319722 31511 authenticator.hpp:148] Creating new server SASL connection
I0428 22:48:04.319788 31511 authenticatee.hpp:265] Received SASL authentication step
I0428 22:48:04.319829 31511 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0428 22:48:04.319840 31511 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0428 22:48:04.319861 31511 authenticator.hpp:282] Received SASL authentication step
I0428 22:48:04.319876 31511 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: 'minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0428 22:48:04.319885 31511 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0428 22:48:04.319896 31511 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0428 22:48:04.319906 31511 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: 'minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0428 22:48:04.319913 31511 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0428 22:48:04.319918 31511 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0428 22:48:04.319931 31511 authenticator.hpp:334] Authentication success
I0428 22:48:04.319954 31511 authenticator.hpp:254] Received SASL authentication start
I0428 22:48:04.319980 31511 authenticator.hpp:342] Authentication requires more steps
I0428 22:48:04.320000 31511 authenticatee.hpp:305] Authentication success
I0428 22:48:04.320021 31511 master.cpp:2835] Successfully authenticated scheduler(34)@67.195.138.8:38083
I0428 22:48:04.320072 31511 authenticatee.hpp:265] Received SASL authentication step
I0428 22:48:04.320116 31511 sched.cpp:342] Successfully authenticated with master master@67.195.138.8:38083
I0428 22:48:04.320127 31511 sched.cpp:461] Sending registration request to master@67.195.138.8:38083
I0428 22:48:04.320159 31511 authenticator.hpp:282] Received SASL authentication step
I0428 22:48:04.320171 31511 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: 'minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0428 22:48:04.320178 31511 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0428 22:48:04.320186 31511 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0428 22:48:04.320195 31511 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: 'minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0428 22:48:04.320201 31511 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0428 22:48:04.320206 31511 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0428 22:48:04.320215 31511 authenticator.hpp:334] Authentication success
I0428 22:48:04.320247 31511 master.cpp:981] Received registration request from scheduler(34)@67.195.138.8:38083
I0428 22:48:04.320276 31511 master.cpp:999] Registering framework 20140428-224804-143311683-38083-31484-0000 at scheduler(34)@67.195.138.8:38083
I0428 22:48:04.320324 31511 master.cpp:2835] Successfully authenticated slave(54)@67.195.138.8:38083
I0428 22:48:04.320360 31511 authenticatee.hpp:305] Authentication success
I0428 22:48:04.320386 31511 sched.cpp:392] Framework registered with 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.320407 31511 sched.cpp:406] Scheduler::registered took 11557ns
I0428 22:48:04.320444 31511 hierarchical_allocator_process.hpp:332] Added framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.320452 31511 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0428 22:48:04.320458 31511 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 6135ns
I0428 22:48:04.320488 31511 slave.cpp:642] Successfully authenticated with master master@67.195.138.8:38083
I0428 22:48:04.320525 31511 slave.cpp:871] Will retry registration in 6.7002347secs if necessary
I0428 22:48:04.320672 31511 registrar.cpp:379] Attempting to update the 'registry'
I0428 22:48:04.322056 31511 log.cpp:680] Attempting to append 333 bytes to the log
I0428 22:48:04.322109 31511 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 3
I0428 22:48:04.322388 31511 replica.cpp:508] Replica received write request for position 3
I0428 22:48:04.322633 31511 leveldb.cpp:341] Persisting action (352 bytes) to leveldb took 230342ns
I0428 22:48:04.322659 31511 replica.cpp:664] Persisted action at 3
I0428 22:48:04.322849 31511 replica.cpp:643] Replica received learned notice for position 3
I0428 22:48:04.322949 31511 leveldb.cpp:341] Persisting action (354 bytes) to leveldb took 90676ns
I0428 22:48:04.322959 31511 replica.cpp:664] Persisted action at 3
I0428 22:48:04.322967 31511 replica.cpp:649] Replica learned APPEND action at position 3
I0428 22:48:04.323269 31511 registrar.cpp:427] Successfully updated 'registry'
I0428 22:48:04.323339 31511 log.cpp:699] Attempting to truncate the log to 3
I0428 22:48:04.323387 31511 master.cpp:2169] Admitted slave on minerva.apache.org at slave(54)@67.195.138.8:38083
I0428 22:48:04.323398 31511 master.cpp:3283] Adding slave 20140428-224804-143311683-38083-31484-0 at minerva.apache.org with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0428 22:48:04.323495 31511 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 4
I0428 22:48:04.323551 31511 slave.cpp:675] Registered with master master@67.195.138.8:38083; given slave ID 20140428-224804-143311683-38083-31484-0
I0428 22:48:04.323616 31511 hierarchical_allocator_process.hpp:445] Added slave 20140428-224804-143311683-38083-31484-0 (minerva.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available)
I0428 22:48:04.323671 31511 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140428-224804-143311683-38083-31484-0 to framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.323776 31511 hierarchical_allocator_process.hpp:708] Performed allocation for slave 20140428-224804-143311683-38083-31484-0 in 134245ns
I0428 22:48:04.323861 31511 master.hpp:586] Adding offer 20140428-224804-143311683-38083-31484-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140428-224804-143311683-38083-31484-0 (minerva.apache.org)
I0428 22:48:04.323902 31511 master.cpp:2744] Sending 1 offers to framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.324170 31511 sched.cpp:529] Scheduler::resourceOffers took 155317ns
I0428 22:48:04.324347 31511 master.hpp:596] Removing offer 20140428-224804-143311683-38083-31484-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140428-224804-143311683-38083-31484-0 (minerva.apache.org)
I0428 22:48:04.324393 31511 master.cpp:1806] Processing reply for offers: [ 20140428-224804-143311683-38083-31484-0 ] on slave 20140428-224804-143311683-38083-31484-0 (minerva.apache.org) for framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.324460 31511 master.hpp:558] Adding task 0 with resources cpus(*):1; mem(*):512 on slave 20140428-224804-143311683-38083-31484-0 (minerva.apache.org)
I0428 22:48:04.324483 31511 master.cpp:2919] Launching task 0 of framework 20140428-224804-143311683-38083-31484-0000 with resources cpus(*):1; mem(*):512 on slave 20140428-224804-143311683-38083-31484-0 (minerva.apache.org)
I0428 22:48:04.324640 31511 slave.cpp:905] Got assigned task 0 for framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.324796 31511 slave.cpp:1015] Launching task 0 for framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.326722 31509 hierarchical_allocator_process.hpp:547] Framework 20140428-224804-143311683-38083-31484-0000 left cpus(*):1; mem(*):512; disk(*):1024; ports(*):[31000-32000] unused on slave 20140428-224804-143311683-38083-31484-0
I0428 22:48:04.326814 31509 hierarchical_allocator_process.hpp:590] Framework 20140428-224804-143311683-38083-31484-0000 filtered slave 20140428-224804-143311683-38083-31484-0 for 5secs
I0428 22:48:04.326956 31509 replica.cpp:508] Replica received write request for position 4
I0428 22:48:04.327091 31509 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 121777ns
I0428 22:48:04.327101 31509 replica.cpp:664] Persisted action at 4
I0428 22:48:04.327289 31509 replica.cpp:643] Replica received learned notice for position 4
I0428 22:48:04.327390 31509 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 90441ns
I0428 22:48:04.327410 31509 leveldb.cpp:399] Deleting ~2 keys from leveldb took 11362ns
I0428 22:48:04.327419 31509 replica.cpp:664] Persisted action at 4
I0428 22:48:04.327426 31509 replica.cpp:649] Replica learned TRUNCATE action at position 4
I0428 22:48:04.328454 31511 exec.cpp:131] Version: 0.19.0
I0428 22:48:04.328549 31507 exec.cpp:181] Executor started at: executor(7)@67.195.138.8:38083 with pid 31484
I0428 22:48:04.328685 31511 slave.cpp:1125] Queuing task '0' for executor default of framework '20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.328729 31511 slave.cpp:486] Successfully attached file '/tmp/MasterTest_ReconcileTaskTest_eoR94O/slaves/20140428-224804-143311683-38083-31484-0/frameworks/20140428-224804-143311683-38083-31484-0000/executors/default/runs/9b251d8c-f887-4e7d-b62c-c6e307ffa5b7'
I0428 22:48:04.328754 31511 slave.cpp:1598] Got registration for executor 'default' of framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.328981 31513 exec.cpp:205] Executor registered on slave 20140428-224804-143311683-38083-31484-0
I0428 22:48:04.330076 31513 exec.cpp:217] Executor::registered took 13334ns
I0428 22:48:04.330138 31511 slave.cpp:1717] Flushing queued task 0 for executor 'default' of framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.330309 31506 exec.cpp:292] Executor asked to run task '0'
I0428 22:48:04.330344 31506 exec.cpp:301] Executor::launchTask took 22898ns
I0428 22:48:04.332511 31506 exec.cpp:524] Executor sending status update TASK_RUNNING (UUID: 846208dc-2a42-4b22-bff5-56e866ba25d6) for task 0 of framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.332625 31511 slave.cpp:2282] Monitoring executor 'default' of framework '20140428-224804-143311683-38083-31484-0000' in container '9b251d8c-f887-4e7d-b62c-c6e307ffa5b7'
I0428 22:48:04.332774 31511 slave.cpp:1953] Handling status update TASK_RUNNING (UUID: 846208dc-2a42-4b22-bff5-56e866ba25d6) for task 0 of framework 20140428-224804-143311683-38083-31484-0000 from executor(7)@67.195.138.8:38083
I0428 22:48:04.333009 31511 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 846208dc-2a42-4b22-bff5-56e866ba25d6) for task 0 of framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.333118 31511 status_update_manager.cpp:499] Creating StatusUpdate stream for task 0 of framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.333261 31511 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: 846208dc-2a42-4b22-bff5-56e866ba25d6) for task 0 of framework 20140428-224804-143311683-38083-31484-0000 to master@67.195.138.8:38083
I0428 22:48:04.333436 31511 master.cpp:2444] Status update TASK_RUNNING (UUID: 846208dc-2a42-4b22-bff5-56e866ba25d6) for task 0 of framework 20140428-224804-143311683-38083-31484-0000 from slave(54)@67.195.138.8:38083
I0428 22:48:04.333495 31508 slave.cpp:2070] Status update manager successfully handled status update TASK_RUNNING (UUID: 846208dc-2a42-4b22-bff5-56e866ba25d6) for task 0 of framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.333700 31508 slave.cpp:2076] Sending acknowledgement for status update TASK_RUNNING (UUID: 846208dc-2a42-4b22-bff5-56e866ba25d6) for task 0 of framework 20140428-224804-143311683-38083-31484-0000 to executor(7)@67.195.138.8:38083
I0428 22:48:04.333575 31509 sched.cpp:620] Scheduler::statusUpdate took 22197ns
I0428 22:48:04.333855 31508 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 846208dc-2a42-4b22-bff5-56e866ba25d6) for task 0 of framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.333907 31508 slave.cpp:1538] Status update manager successfully handled status update acknowledgement (UUID: 846208dc-2a42-4b22-bff5-56e866ba25d6) for task 0 of framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.333951 31509 exec.cpp:338] Executor received status update acknowledgement 846208dc-2a42-4b22-bff5-56e866ba25d6 for task 0 of framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.340344 31513 master.cpp:2583] Performing best-effort task state reconciliation for framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.341577 31513 master.cpp:2444] Status update TASK_RUNNING (UUID: 3fe8bce4-517b-4540-8c09-f493a598480e) for task 0 of framework 20140428-224804-143311683-38083-31484-0000 from @0.0.0.0:0
I0428 22:48:04.341684 31513 sched.cpp:620] Scheduler::statusUpdate took 17539ns
I0428 22:48:04.341929 31484 master.cpp:551] Master terminating
I0428 22:48:04.342110 31484 master.hpp:576] Removing task 0 with resources cpus(*):1; mem(*):512 on slave 20140428-224804-143311683-38083-31484-0 (minerva.apache.org)
I0428 22:48:04.342006 31512 sched.cpp:731] Stopping framework '20140428-224804-143311683-38083-31484-0000'
I0428 22:48:04.344306 31512 slave.cpp:2151] master@67.195.138.8:38083 exited
W0428 22:48:04.344321 31512 slave.cpp:2154] Master disconnected! Waiting for a new master to be elected
I0428 22:48:04.344463 31484 slave.cpp:414] Slave terminating
I0428 22:48:04.344703 31484 slave.cpp:1309] Asked to shut down framework 20140428-224804-143311683-38083-31484-0000 by @0.0.0.0:0
I0428 22:48:04.344781 31484 slave.cpp:1334] Shutting down framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.344851 31484 slave.cpp:2625] Shutting down executor 'default' of framework 20140428-224804-143311683-38083-31484-0000
I0428 22:48:04.346400 31484 exec.cpp:378] Executor asked to shutdown
I0428 22:48:04.346482 31484 exec.cpp:393] Executor::shutdown took 12342ns
[ OK ] MasterTest.ReconcileTaskTest (131 ms)
[ RUN ] MasterTest.StatusUpdateAck
I0428 22:48:04.361136 31484 leveldb.cpp:174] Opened db in 13.470012ms
I0428 22:48:04.361611 31484 leveldb.cpp:181] Compacted db in 266962ns
I0428 22:48:04.361683 31484 leveldb.cpp:196] Created db iterator in 5820ns
I0428 22:48:04.361744 31484 leveldb.cpp:202] Seeked to beginning of db in 860ns
I0428 22:48:04.361799 31484 leveldb.cpp:271] Iterated through 0 keys in the db in 349ns
I0428 22:48:04.361891 31484 replica.cpp:729] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0428 22:48:04.362298 31510 recover.cpp:425] Starting replica recovery
I0428 22:48:04.362387 31510 recover.cpp:451] Replica is in EMPTY status
I0428 22:48:04.362823 31506 replica.cpp:626] Replica in EMPTY status received a broadcasted recover request
I0428 22:48:04.362886 31506 recover.cpp:188] Received a recover response from a replica in EMPTY status
I0428 22:48:04.362970 31506 recover.cpp:542] Updating replica status to STARTING
I0428 22:48:04.363553 31506 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 526484ns
I0428 22:48:04.363564 31506 replica.cpp:320] Persisted replica status to STARTING
I0428 22:48:04.363626 31506 recover.cpp:451] Replica is in STARTING status
I0428 22:48:04.363903 31506 replica.cpp:626] Replica in STARTING status received a broadcasted recover request
I0428 22:48:04.363947 31506 recover.cpp:188] Received a recover response from a replica in STARTING status
I0428 22:48:04.364038 31506 recover.cpp:542] Updating replica status to VOTING
I0428 22:48:04.364161 31506 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 82325ns
I0428 22:48:04.364171 31506 replica.cpp:320] Persisted replica status to VOTING
I0428 22:48:04.364202 31506 recover.cpp:556] Successfully joined the Paxos group
I0428 22:48:04.364251 31506 recover.cpp:440] Recover process terminated
I0428 22:48:04.365274 31506 master.cpp:266] Master 20140428-224804-143311683-38083-31484 (minerva.apache.org) started on 67.195.138.8:38083
I0428 22:48:04.365293 31506 master.cpp:303] Master only allowing authenticated frameworks to register
I0428 22:48:04.365298 31506 master.cpp:308] Master only allowing authenticated slaves to register
I0428 22:48:04.365303 31506 credentials.hpp:35] Loading credentials for authentication
W0428 22:48:04.365341 31506 credentials.hpp:48] Failed to stat credentials file 'file:///tmp/MasterTest_StatusUpdateAck_qRn7uE/credentials': No such file or directory
I0428 22:48:04.365746 31509 hierarchical_allocator_process.hpp:302] Initializing hierarchical allocator process with master : master@67.195.138.8:38083
I0428 22:48:04.365778 31509 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0428 22:48:04.366163 31506 master.cpp:922] The newly elected leader is master@67.195.138.8:38083 with id 20140428-224804-143311683-38083-31484
I0428 22:48:04.366173 31506 master.cpp:932] Elected as the leading master!
I0428 22:48:04.366179 31506 master.cpp:753] Recovering from registrar
I0428 22:48:04.366235 31506 registrar.cpp:275] Recovering registrar
I0428 22:48:04.366492 31506 log.cpp:656] Attempting to start the writer
I0428 22:48:04.366874 31506 replica.cpp:474] Replica received implicit promise request with proposal 1
I0428 22:48:04.366981 31506 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 94179ns
I0428 22:48:04.366991 31506 replica.cpp:342] Persisted promised to 1
I0428 22:48:04.367162 31506 coordinator.cpp:229] Coordinator attemping to fill missing position
I0428 22:48:04.367497 31506 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2
I0428 22:48:04.367640 31506 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 130356ns
I0428 22:48:04.367652 31506 replica.cpp:664] Persisted action at 0
I0428 22:48:04.367990 31509 replica.cpp:508] Replica received write request for position 0
I0428 22:48:04.368013 31509 leveldb.cpp:436] Reading position from leveldb took 12859ns
I0428 22:48:04.368098 31509 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 73600ns
I0428 22:48:04.368108 31509 replica.cpp:664] Persisted action at 0
I0428 22:48:04.368232 31509 replica.cpp:643] Replica received learned notice for position 0
I0428 22:48:04.368335 31509 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 92973ns
I0428 22:48:04.368345 31509 replica.cpp:664] Persisted action at 0
I0428 22:48:04.368351 31509 replica.cpp:649] Replica learned NOP action at position 0
I0428 22:48:04.368536 31507 log.cpp:672] Writer started with ending position 0
I0428 22:48:04.368837 31507 leveldb.cpp:436] Reading position from leveldb took 6707ns
I0428 22:48:04.370183 31507 registrar.cpp:308] Successfully recovered registrar
I0428 22:48:04.370201 31507 registrar.cpp:379] Attempting to update the 'registry'
I0428 22:48:04.371517 31507 log.cpp:680] Attempting to append 137 bytes to the log
I0428 22:48:04.371556 31507 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 1
I0428 22:48:04.371743 31507 replica.cpp:508] Replica received write request for position 1
I0428 22:48:04.371901 31507 leveldb.cpp:341] Persisting action (156 bytes) to leveldb took 145982ns
I0428 22:48:04.371912 31507 replica.cpp:664] Persisted action at 1
I0428 22:48:04.372144 31507 replica.cpp:643] Replica received learned notice for position 1
I0428 22:48:04.372269 31507 leveldb.cpp:341] Persisting action (158 bytes) to leveldb took 113573ns
I0428 22:48:04.372280 31507 replica.cpp:664] Persisted action at 1
I0428 22:48:04.372287 31507 replica.cpp:649] Replica learned APPEND action at position 1
I0428 22:48:04.372522 31507 registrar.cpp:427] Successfully updated 'registry'
I0428 22:48:04.372580 31507 log.cpp:699] Attempting to truncate the log to 1
I0428 22:48:04.372628 31507 master.cpp:780] Recovered 0 slaves from the Registry (99B) ; allowing 10mins for slaves to re-register
I0428 22:48:04.372670 31507 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 2
I0428 22:48:04.372827 31507 replica.cpp:508] Replica received write request for position 2
I0428 22:48:04.372920 31507 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 82568ns
I0428 22:48:04.372931 31507 replica.cpp:664] Persisted action at 2
I0428 22:48:04.373162 31513 replica.cpp:643] Replica received learned notice for position 2
I0428 22:48:04.373242 31513 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 68701ns
I0428 22:48:04.373260 31513 leveldb.cpp:399] Deleting ~1 keys from leveldb took 8881ns
I0428 22:48:04.373268 31513 replica.cpp:664] Persisted action at 2
I0428 22:48:04.373275 31513 replica.cpp:649] Replica learned TRUNCATE action at position 2
I0428 22:48:05.366616 31506 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0428 22:48:05.366708 31506 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 179534ns
I0428 22:48:06.369921 31506 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0428 22:48:06.369994 31506 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 93409ns
I0428 22:48:07.371203 31506 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0428 22:48:07.371274 31506 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 88137ns
2014-04-28 22:48:07,634:31484(0x2b891d7a7700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:37168] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I0428 22:48:08.372499 31506 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0428 22:48:08.372563 31506 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 81365ns
I0428 22:48:09.366308 31506 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0428 22:48:09.373541 31506 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0428 22:48:09.373594 31506 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 62197ns
I0428 22:48:10.373879 31506 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0428 22:48:10.373930 31506 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 63962ns
2014-04-28 22:48:10,969:31484(0x2b891d7a7700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:37168] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I0428 22:48:11.374265 31506 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0428 22:48:11.374318 31506 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 67642ns
I0428 22:48:12.375505 31509 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0428 22:48:12.375601 31509 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 139035ns
I0428 22:48:13.376659 31508 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0428 22:48:13.376709 31508 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 90850ns
2014-04-28 22:48:14,305:31484(0x2b891d7a7700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:37168] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I0428 22:48:14.366760 31513 master.cpp:104] No whitelist given. Advertising offers for all slaves
F0428 22:48:14.373994 31484 cluster.hpp:373] Failed to wait for _recover
*** Check failure stack trace: ***
I0428 22:48:14.377063 31512 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0428 22:48:14.408171 31512 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 31.093344ms
@ 0x2b8805d4d2bd google::LogMessage::Fail()
@ 0x2b8805d4f34f google::LogMessage::SendToLog()
@ 0x2b8805d4ceac google::LogMessage::Flush()
@ 0x2b8805d4fbbd google::LogMessageFatal::~LogMessageFatal()
@ 0x72b4d9 mesos::internal::tests::Cluster::Masters::start()
@ 0x726213 mesos::internal::tests::MesosTest::StartMaster()
@ 0x705c39 MasterTest_StatusUpdateAck_Test::TestBody()
@ 0x8a56fd testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x89dd31 testing::Test::Run()
@ 0x89de16 testing::TestInfo::Run()
@ 0x89df57 testing::TestCase::Run()
@ 0x89e2be testing::internal::UnitTestImpl::RunAllTests()
@ 0x8a527d testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x89d38e testing::UnitTest::Run()
@ 0x4a2b70 main
@ 0x2b880719a76d (unknown)
@ 0x4adf61 (unknown)
make[4]: *** [check-local] Aborted
make[4]: Leaving directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.19.0/_build/src'>
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.19.0/_build/src'>
make[2]: *** [check] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.19.0/_build/src'>
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.19.0/_build'>
make: *** [distcheck] Error 1
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Ubuntu-distcheck #63
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Ubuntu-distcheck/63/changes>