See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2227/changes>
Changes: [benjamin.hindman] Fixed signedness compilation bug. ------------------------------------------ [...truncated 69451 lines...] I1029 08:29:01.746940 32251 master.cpp:358] Master only allowing authenticated frameworks to register I1029 08:29:01.746958 32251 master.cpp:363] Master only allowing authenticated slaves to register I1029 08:29:01.746973 32251 credentials.hpp:36] Loading credentials for authentication from '/tmp/MasterSlaveReconciliationTest_ReconcileLostTask_dHQFbB/credentials' I1029 08:29:01.747167 32251 master.cpp:392] Authorization enabled I1029 08:29:01.747524 32261 master.cpp:120] No whitelist given. Advertising offers for all slaves I1029 08:29:01.747531 32260 replica.cpp:638] Replica in STARTING status received a broadcasted recover request I1029 08:29:01.747757 32253 hierarchical_allocator_process.hpp:299] Initializing hierarchical allocator process with master : master@67.195.81.187:40696 I1029 08:29:01.748066 32259 recover.cpp:188] Received a recover response from a replica in STARTING status I1029 08:29:01.748724 32251 master.cpp:1242] The newly elected leader is master@67.195.81.187:40696 with id 20141029-082901-3142697795-40696-32232 I1029 08:29:01.748754 32251 master.cpp:1255] Elected as the leading master! I1029 08:29:01.748769 32251 master.cpp:1073] Recovering from registrar I1029 08:29:01.748764 32255 recover.cpp:554] Updating replica status to VOTING I1029 08:29:01.749003 32249 registrar.cpp:313] Recovering registrar I1029 08:29:01.749435 32261 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 461205ns I1029 08:29:01.749469 32261 replica.cpp:320] Persisted replica status to VOTING I1029 08:29:01.749611 32255 recover.cpp:568] Successfully joined the Paxos group I1029 08:29:01.749806 32255 recover.cpp:452] Recover process terminated I1029 08:29:01.750274 32254 log.cpp:656] Attempting to start the writer I1029 08:29:01.751739 32259 replica.cpp:474] Replica received implicit promise request with proposal 1 I1029 08:29:01.752102 32259 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 335010ns I1029 08:29:01.752128 32259 replica.cpp:342] Persisted promised to 1 I1029 08:29:01.752923 32251 coordinator.cpp:230] Coordinator attemping to fill missing position I1029 08:29:01.754606 32261 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2 I1029 08:29:01.754984 32261 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 341669ns I1029 08:29:01.755012 32261 replica.cpp:676] Persisted action at 0 I1029 08:29:01.756240 32258 replica.cpp:508] Replica received write request for position 0 I1029 08:29:01.756314 32258 leveldb.cpp:438] Reading position from leveldb took 42163ns I1029 08:29:01.756639 32258 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 285232ns I1029 08:29:01.756667 32258 replica.cpp:676] Persisted action at 0 I1029 08:29:01.757210 32261 replica.cpp:655] Replica received learned notice for position 0 I1029 08:29:01.757375 32261 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 136938ns I1029 08:29:01.757402 32261 replica.cpp:676] Persisted action at 0 I1029 08:29:01.757426 32261 replica.cpp:661] Replica learned NOP action at position 0 I1029 08:29:01.757891 32256 log.cpp:672] Writer started with ending position 0 I1029 08:29:01.758985 32255 leveldb.cpp:438] Reading position from leveldb took 24641ns I1029 08:29:01.762171 32257 registrar.cpp:346] Successfully fetched the registry (0B) in 13.080064ms I1029 08:29:01.762275 32257 registrar.cpp:445] Applied 1 operations in 25968ns; attempting to update the 'registry' I1029 08:29:01.765403 32258 log.cpp:680] Attempting to append 139 bytes to the log I1029 08:29:01.765553 32256 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1 I1029 08:29:01.766399 32261 replica.cpp:508] Replica received write request for position 1 I1029 08:29:01.766787 32261 leveldb.cpp:343] Persisting action (158 bytes) to leveldb took 356567ns I1029 08:29:01.766810 32261 replica.cpp:676] Persisted action at 1 I1029 08:29:01.767429 32253 replica.cpp:655] Replica received learned notice for position 1 I1029 08:29:01.767731 32253 leveldb.cpp:343] Persisting action (160 bytes) to leveldb took 271318ns I1029 08:29:01.767755 32253 replica.cpp:676] Persisted action at 1 I1029 08:29:01.767773 32253 replica.cpp:661] Replica learned APPEND action at position 1 I1029 08:29:01.768581 32257 registrar.cpp:490] Successfully updated the 'registry' in 6.209792ms I1029 08:29:01.768728 32257 registrar.cpp:376] Successfully recovered registrar I1029 08:29:01.768862 32246 log.cpp:699] Attempting to truncate the log to 1 I1029 08:29:01.769008 32259 master.cpp:1100] Recovered 0 slaves from the Registry (101B) ; allowing 10mins for slaves to re-register I1029 08:29:01.769173 32261 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 2 I1029 08:29:01.769971 32252 replica.cpp:508] Replica received write request for position 2 I1029 08:29:01.770251 32252 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 249793ns I1029 08:29:01.770273 32252 replica.cpp:676] Persisted action at 2 I1029 08:29:01.770901 32247 replica.cpp:655] Replica received learned notice for position 2 I1029 08:29:01.771379 32247 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 439672ns I1029 08:29:01.771445 32247 leveldb.cpp:401] Deleting ~1 keys from leveldb took 32272ns I1029 08:29:01.771468 32247 replica.cpp:676] Persisted action at 2 I1029 08:29:01.771492 32247 replica.cpp:661] Replica learned TRUNCATE action at position 2 I1029 08:29:01.783360 32232 containerizer.cpp:100] Using isolation: posix/cpu,posix/mem I1029 08:29:01.784912 32248 slave.cpp:169] Slave started on 221)@67.195.81.187:40696 I1029 08:29:01.785006 32248 credentials.hpp:84] Loading credential for authentication from '/tmp/MasterSlaveReconciliationTest_ReconcileLostTask_tRTNTJ/credential' I1029 08:29:01.785164 32248 slave.cpp:276] Slave using credential for: test-principal I1029 08:29:01.785475 32248 slave.cpp:289] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1029 08:29:01.785598 32248 slave.cpp:318] Slave hostname: pomona.apache.org I1029 08:29:01.785616 32248 slave.cpp:319] Slave checkpoint: false W1029 08:29:01.785626 32248 slave.cpp:321] Disabling checkpointing is deprecated and the --checkpoint flag will be removed in a future release. Please avoid using this flag I1029 08:29:01.786360 32260 state.cpp:33] Recovering state from '/tmp/MasterSlaveReconciliationTest_ReconcileLostTask_tRTNTJ/meta' I1029 08:29:01.786676 32261 status_update_manager.cpp:197] Recovering status update manager I1029 08:29:01.786864 32246 containerizer.cpp:281] Recovering containerizer I1029 08:29:01.787552 32251 slave.cpp:3456] Finished recovery I1029 08:29:01.787946 32251 slave.cpp:602] New master detected at master@67.195.81.187:40696 I1029 08:29:01.787988 32256 status_update_manager.cpp:171] Pausing sending status updates I1029 08:29:01.787999 32251 slave.cpp:665] Authenticating with master master@67.195.81.187:40696 I1029 08:29:01.788132 32259 authenticatee.hpp:133] Creating new client SASL connection I1029 08:29:01.788136 32251 slave.cpp:638] Detecting new master I1029 08:29:01.788336 32259 master.cpp:3853] Authenticating slave(221)@67.195.81.187:40696 I1029 08:29:01.788488 32251 authenticator.hpp:161] Creating new server SASL connection I1029 08:29:01.788584 32259 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5 I1029 08:29:01.788611 32259 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5' I1029 08:29:01.788748 32250 authenticator.hpp:267] Received SASL authentication start I1029 08:29:01.788852 32250 authenticator.hpp:389] Authentication requires more steps I1029 08:29:01.788981 32254 authenticatee.hpp:270] Received SASL authentication step I1029 08:29:01.789067 32254 authenticator.hpp:295] Received SASL authentication step I1029 08:29:01.789090 32254 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 I1029 08:29:01.789099 32254 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1029 08:29:01.789124 32254 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1029 08:29:01.789141 32254 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 I1029 08:29:01.789149 32254 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1029 08:29:01.789155 32254 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1029 08:29:01.789166 32254 authenticator.hpp:381] Authentication success I1029 08:29:01.789247 32249 authenticatee.hpp:310] Authentication success I1029 08:29:01.789247 32256 master.cpp:3893] Successfully authenticated principal 'test-principal' at slave(221)@67.195.81.187:40696 I1029 08:29:01.789551 32254 slave.cpp:722] Successfully authenticated with master master@67.195.81.187:40696 I1029 08:29:01.789638 32254 slave.cpp:1050] Will retry registration in 14.558697ms if necessary I1029 08:29:01.789758 32247 master.cpp:3032] Registering slave at slave(221)@67.195.81.187:40696 (pomona.apache.org) with id 20141029-082901-3142697795-40696-32232-S0 I1029 08:29:01.790158 32250 registrar.cpp:445] Applied 1 operations in 55741ns; attempting to update the 'registry' I1029 08:29:01.790506 32232 sched.cpp:137] Version: 0.21.0 I1029 08:29:01.790932 32248 sched.cpp:233] New master detected at master@67.195.81.187:40696 I1029 08:29:01.790983 32248 sched.cpp:283] Authenticating with master master@67.195.81.187:40696 I1029 08:29:01.791153 32249 authenticatee.hpp:133] Creating new client SASL connection I1029 08:29:01.791319 32252 master.cpp:3853] Authenticating scheduler-57a5867a-c38f-4940-b626-3b457eb16121@67.195.81.187:40696 I1029 08:29:01.791473 32247 authenticator.hpp:161] Creating new server SASL connection I1029 08:29:01.791578 32258 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5 I1029 08:29:01.791600 32258 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5' I1029 08:29:01.791674 32249 authenticator.hpp:267] Received SASL authentication start I1029 08:29:01.791720 32249 authenticator.hpp:389] Authentication requires more steps I1029 08:29:01.791785 32258 authenticatee.hpp:270] Received SASL authentication step I1029 08:29:01.791873 32252 authenticator.hpp:295] Received SASL authentication step I1029 08:29:01.791899 32252 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 I1029 08:29:01.791908 32252 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1029 08:29:01.791929 32252 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1029 08:29:01.791949 32252 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 I1029 08:29:01.791959 32252 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1029 08:29:01.791965 32252 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1029 08:29:01.791975 32252 authenticator.hpp:381] Authentication success I1029 08:29:01.792035 32249 authenticatee.hpp:310] Authentication success I1029 08:29:01.792098 32247 master.cpp:3893] Successfully authenticated principal 'test-principal' at scheduler-57a5867a-c38f-4940-b626-3b457eb16121@67.195.81.187:40696 I1029 08:29:01.792270 32255 sched.cpp:357] Successfully authenticated with master master@67.195.81.187:40696 I1029 08:29:01.792302 32255 sched.cpp:476] Sending registration request to master@67.195.81.187:40696 I1029 08:29:01.792421 32247 master.cpp:1362] Received registration request for framework 'default' at scheduler-57a5867a-c38f-4940-b626-3b457eb16121@67.195.81.187:40696 I1029 08:29:01.792487 32247 master.cpp:1321] Authorizing framework principal 'test-principal' to receive offers for role '*' I1029 08:29:01.792841 32254 master.cpp:1426] Registering framework 20141029-082901-3142697795-40696-32232-0000 (default) at scheduler-57a5867a-c38f-4940-b626-3b457eb16121@67.195.81.187:40696 I1029 08:29:01.793159 32247 hierarchical_allocator_process.hpp:329] Added framework 20141029-082901-3142697795-40696-32232-0000 I1029 08:29:01.793190 32247 hierarchical_allocator_process.hpp:697] No resources available to allocate! I1029 08:29:01.793205 32247 hierarchical_allocator_process.hpp:659] Performed allocation for 0 slaves in 18354ns I1029 08:29:01.793267 32256 sched.cpp:407] Framework registered with 20141029-082901-3142697795-40696-32232-0000 I1029 08:29:01.793364 32258 log.cpp:680] Attempting to append 316 bytes to the log I1029 08:29:01.793377 32256 sched.cpp:421] Scheduler::registered took 18044ns I1029 08:29:01.793473 32253 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3 I1029 08:29:01.794193 32249 replica.cpp:508] Replica received write request for position 3 I1029 08:29:01.794703 32249 leveldb.cpp:343] Persisting action (335 bytes) to leveldb took 483307ns I1029 08:29:01.794726 32249 replica.cpp:676] Persisted action at 3 I1029 08:29:01.795399 32259 replica.cpp:655] Replica received learned notice for position 3 I1029 08:29:01.795842 32259 leveldb.cpp:343] Persisting action (337 bytes) to leveldb took 412342ns I1029 08:29:01.795874 32259 replica.cpp:676] Persisted action at 3 I1029 08:29:01.795899 32259 replica.cpp:661] Replica learned APPEND action at position 3 I1029 08:29:01.796898 32253 registrar.cpp:490] Successfully updated the 'registry' in 6.681856ms I1029 08:29:01.797178 32257 log.cpp:699] Attempting to truncate the log to 3 I1029 08:29:01.797451 32250 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4 I1029 08:29:01.797663 32256 slave.cpp:2522] Received ping from slave-observer(198)@67.195.81.187:40696 I1029 08:29:01.797827 32252 master.cpp:3086] Registered slave 20141029-082901-3142697795-40696-32232-S0 at slave(221)@67.195.81.187:40696 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1029 08:29:01.797948 32256 slave.cpp:756] Registered with master master@67.195.81.187:40696; given slave ID 20141029-082901-3142697795-40696-32232-S0 I1029 08:29:01.798121 32249 status_update_manager.cpp:178] Resuming sending status updates I1029 08:29:01.798086 32246 hierarchical_allocator_process.hpp:442] Added slave 20141029-082901-3142697795-40696-32232-S0 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I1029 08:29:01.798364 32246 hierarchical_allocator_process.hpp:734] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141029-082901-3142697795-40696-32232-S0 to framework 20141029-082901-3142697795-40696-32232-0000 I1029 08:29:01.798773 32246 hierarchical_allocator_process.hpp:679] Performed allocation for slave 20141029-082901-3142697795-40696-32232-S0 in 583233ns I1029 08:29:01.798876 32255 replica.cpp:508] Replica received write request for position 4 I1029 08:29:01.799067 32258 master.cpp:3795] Sending 1 offers to framework 20141029-082901-3142697795-40696-32232-0000 (default) at scheduler-57a5867a-c38f-4940-b626-3b457eb16121@67.195.81.187:40696 I1029 08:29:01.799401 32255 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 487021ns I1029 08:29:01.799437 32255 replica.cpp:676] Persisted action at 4 I1029 08:29:01.799464 32254 sched.cpp:544] Scheduler::resourceOffers took 74901ns I1029 08:29:01.800565 32249 replica.cpp:655] Replica received learned notice for position 4 I1029 08:29:01.800915 32249 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 317125ns I1029 08:29:01.800974 32249 leveldb.cpp:401] Deleting ~2 keys from leveldb took 30440ns I1029 08:29:01.800992 32249 replica.cpp:676] Persisted action at 4 I1029 08:29:01.801015 32249 replica.cpp:661] Replica learned TRUNCATE action at position 4 I1029 08:29:01.801224 32258 master.cpp:2321] Processing reply for offers: [ 20141029-082901-3142697795-40696-32232-O0 ] on slave 20141029-082901-3142697795-40696-32232-S0 at slave(221)@67.195.81.187:40696 (pomona.apache.org) for framework 20141029-082901-3142697795-40696-32232-0000 (default) at scheduler-57a5867a-c38f-4940-b626-3b457eb16121@67.195.81.187:40696 W1029 08:29:01.801374 32258 master.cpp:1969] Executor default for task 1 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1029 08:29:01.801412 32258 master.cpp:1980] Executor default for task 1 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1029 08:29:01.801522 32258 master.cpp:2417] Authorizing framework principal 'test-principal' to launch task 1 as user 'jenkins' I1029 08:29:01.802527 32254 master.hpp:877] Adding task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141029-082901-3142697795-40696-32232-S0 (pomona.apache.org) I1029 08:29:01.802645 32254 master.cpp:2480] Launching task 1 of framework 20141029-082901-3142697795-40696-32232-0000 (default) at scheduler-57a5867a-c38f-4940-b626-3b457eb16121@67.195.81.187:40696 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141029-082901-3142697795-40696-32232-S0 at slave(221)@67.195.81.187:40696 (pomona.apache.org) I1029 08:29:01.807513 32261 slave.cpp:602] New master detected at master@67.195.81.187:40696 I1029 08:29:01.807621 32260 status_update_manager.cpp:171] Pausing sending status updates I1029 08:29:01.807621 32261 slave.cpp:665] Authenticating with master master@67.195.81.187:40696 I1029 08:29:01.807837 32261 slave.cpp:638] Detecting new master I1029 08:29:01.807936 32248 authenticatee.hpp:133] Creating new client SASL connection I1029 08:29:01.808363 32258 master.cpp:3853] Authenticating slave(221)@67.195.81.187:40696 I1029 08:29:01.808797 32246 authenticator.hpp:161] Creating new server SASL connection I1029 08:29:01.809051 32256 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5 I1029 08:29:01.809103 32256 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5' I1029 08:29:01.809370 32257 authenticator.hpp:267] Received SASL authentication start I1029 08:29:01.809425 32257 authenticator.hpp:389] Authentication requires more steps I1029 08:29:01.809512 32257 authenticatee.hpp:270] Received SASL authentication step I1029 08:29:01.809648 32246 authenticator.hpp:295] Received SASL authentication step I1029 08:29:01.809682 32246 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 I1029 08:29:01.809695 32246 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1029 08:29:01.809736 32246 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1029 08:29:01.809777 32246 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 I1029 08:29:01.809793 32246 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1029 08:29:01.809803 32246 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1029 08:29:01.809819 32246 authenticator.hpp:381] Authentication success I1029 08:29:01.809927 32247 authenticatee.hpp:310] Authentication success I1029 08:29:01.809936 32257 master.cpp:3893] Successfully authenticated principal 'test-principal' at slave(221)@67.195.81.187:40696 I1029 08:29:01.810453 32251 slave.cpp:722] Successfully authenticated with master master@67.195.81.187:40696 I1029 08:29:01.810636 32251 slave.cpp:1050] Will retry registration in 12.388106ms if necessary I1029 08:29:01.810950 32253 master.cpp:3159] Re-registering slave 20141029-082901-3142697795-40696-32232-S0 at slave(221)@67.195.81.187:40696 (pomona.apache.org) W1029 08:29:01.811121 32253 master.cpp:3956] Task 1 of framework 20141029-082901-3142697795-40696-32232-0000 unknown to the slave 20141029-082901-3142697795-40696-32232-S0 at slave(221)@67.195.81.187:40696 (pomona.apache.org) during re-registration: reconciling with the slave W1029 08:29:01.811403 32253 master.cpp:4033] Executor default of framework 20141029-082901-3142697795-40696-32232-0000 possibly unknown to the slave 20141029-082901-3142697795-40696-32232-S0 at slave(221)@67.195.81.187:40696 (pomona.apache.org) I1029 08:29:01.811498 32253 master.cpp:4705] Removing executor 'default' with resources of framework 20141029-082901-3142697795-40696-32232-0000 on slave 20141029-082901-3142697795-40696-32232-S0 at slave(221)@67.195.81.187:40696 (pomona.apache.org) I1029 08:29:01.811636 32259 slave.cpp:824] Re-registered with master master@67.195.81.187:40696 W1029 08:29:01.811743 32259 slave.cpp:882] Slave reconciling task 1 of framework 20141029-082901-3142697795-40696-32232-0000 in state TASK_LOST: task unknown to the slave I1029 08:29:01.811813 32260 status_update_manager.cpp:178] Resuming sending status updates I1029 08:29:01.814267 32246 status_update_manager.cpp:317] Received status update TASK_LOST (UUID: 7b17c02a-8489-4887-b425-e9de19507164) for task 1 of framework 20141029-082901-3142697795-40696-32232-0000 I1029 08:29:01.814338 32246 status_update_manager.cpp:494] Creating StatusUpdate stream for task 1 of framework 20141029-082901-3142697795-40696-32232-0000 I1029 08:29:01.814589 32246 status_update_manager.cpp:371] Forwarding update TASK_LOST (UUID: 7b17c02a-8489-4887-b425-e9de19507164) for task 1 of framework 20141029-082901-3142697795-40696-32232-0000 to the slave I1029 08:29:01.814786 32260 slave.cpp:2442] Forwarding the update TASK_LOST (UUID: 7b17c02a-8489-4887-b425-e9de19507164) for task 1 of framework 20141029-082901-3142697795-40696-32232-0000 to master@67.195.81.187:40696 I1029 08:29:01.814998 32260 slave.cpp:2369] Status update manager successfully handled status update TASK_LOST (UUID: 7b17c02a-8489-4887-b425-e9de19507164) for task 1 of framework 20141029-082901-3142697795-40696-32232-0000 I1029 08:29:01.815536 32249 master.cpp:3410] Forwarding status update TASK_LOST (UUID: 7b17c02a-8489-4887-b425-e9de19507164) for task 1 of framework 20141029-082901-3142697795-40696-32232-0000 I1029 08:29:01.816026 32249 master.cpp:3382] Status update TASK_LOST (UUID: 7b17c02a-8489-4887-b425-e9de19507164) for task 1 of framework 20141029-082901-3142697795-40696-32232-0000 from slave 20141029-082901-3142697795-40696-32232-S0 at slave(221)@67.195.81.187:40696 (pomona.apache.org) I1029 08:29:01.816092 32249 master.cpp:4617] Updating the latest state of task 1 of framework 20141029-082901-3142697795-40696-32232-0000 to TASK_LOST I1029 08:29:01.816208 32259 sched.cpp:635] Scheduler::statusUpdate took 68988ns I1029 08:29:01.816612 32253 sched.cpp:745] Stopping framework '20141029-082901-3142697795-40696-32232-0000' I1029 08:29:01.816648 32249 master.cpp:677] Master terminating I1029 08:29:01.816759 32249 master.cpp:4676] Removing task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 20141029-082901-3142697795-40696-32232-0000 on slave 20141029-082901-3142697795-40696-32232-S0 at slave(221)@67.195.81.187:40696 (pomona.apache.org) I1029 08:29:01.816819 32251 hierarchical_allocator_process.hpp:563] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on slave 20141029-082901-3142697795-40696-32232-S0 from framework 20141029-082901-3142697795-40696-32232-0000 I1029 08:29:01.817541 32256 slave.cpp:2607] master@67.195.81.187:40696 exited W1029 08:29:01.817574 32256 slave.cpp:2610] Master disconnected! Waiting for a new master to be elected I1029 08:29:01.822736 32232 slave.cpp:484] Slave terminating [ OK ] MasterSlaveReconciliationTest.ReconcileLostTask (88 ms) [----------] 5 tests from MasterSlaveReconciliationTest (525 ms total) [----------] 1 test from HTTP [ RUN ] HTTP.ModelTask [ OK ] HTTP.ModelTask (1 ms) [----------] 1 test from HTTP (1 ms total) [----------] 2 tests from CpuIsolatorTest/2, where TypeParam = mesos::internal::tests::Module<mesos::internal::slave::Isolator, (mesos::internal::tests::ModuleID)1> [ RUN ] CpuIsolatorTest/2.SystemCpuUsage Using temporary directory '/tmp/CpuIsolatorTest_2_SystemCpuUsage_Kio5IS' I1029 08:29:01.832018 32232 launcher.cpp:137] Forked child with pid '5285' for container 'system_cpu_usage' [ OK ] CpuIsolatorTest/2.SystemCpuUsage (418 ms) [ RUN ] CpuIsolatorTest/2.UserCpuUsage Using temporary directory '/tmp/CpuIsolatorTest_2_UserCpuUsage_VeBKtb' I1029 08:29:02.249784 32232 launcher.cpp:137] Forked child with pid '5289' for container 'user_cpu_usage' [ OK ] CpuIsolatorTest/2.UserCpuUsage (406 ms) [----------] 2 tests from CpuIsolatorTest/2 (824 ms total) [----------] Global test environment tear-down [==========] 449 tests from 70 test cases ran. (317294 ms total) [ PASSED ] 448 tests. [ FAILED ] 1 test, listed below: [ FAILED ] MasterAuthorizationTest.DuplicateReregistration 1 FAILED TEST YOU HAVE 6 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
