See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1802/changes>
Changes: [bmahler] Moved slave state API functions to the top of the header. ------------------------------------------ [...truncated 23111 lines...] W1218 08:13:14.108582 32598 master.cpp:1335] Ignoring register slave message from juno.apache.org since not elected yet I1218 08:13:14.108732 32598 detector.cpp:322] A new leading master ([email protected]:48553) is detected I1218 08:13:14.108772 32600 master.cpp:746] The newly elected leader is [email protected]:48553 I1218 08:13:14.108790 32600 master.cpp:750] Elected as the leading master! I1218 08:13:14.108790 32598 detector.cpp:322] A new leading master ([email protected]:48553) is detected I1218 08:13:14.108846 32601 sched.cpp:207] New master detected at [email protected]:48553 I1218 08:13:14.108870 32601 sched.cpp:260] Authenticating with master [email protected]:48553 I1218 08:13:14.108937 32601 sched.cpp:229] Detecting new master I1218 08:13:14.108953 32600 authenticatee.hpp:124] Creating new client SASL connection I1218 08:13:14.109127 32601 master.cpp:1849] Authenticating framework at scheduler(131)@67.195.138.61:48553 I1218 08:13:14.109204 32600 authenticator.hpp:140] Creating new server SASL connection I1218 08:13:14.109328 32598 authenticatee.hpp:212] Received SASL authentication mechanisms: CRAM-MD5 I1218 08:13:14.109347 32598 authenticatee.hpp:238] Attempting to authenticate with mechanism 'CRAM-MD5' I1218 08:13:14.109374 32598 authenticator.hpp:243] Received SASL authentication start I1218 08:13:14.109457 32598 authenticator.hpp:325] Authentication requires more steps I1218 08:13:14.109491 32598 authenticatee.hpp:258] Received SASL authentication step I1218 08:13:14.109534 32598 authenticator.hpp:271] Received SASL authentication step I1218 08:13:14.109561 32598 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'juno.apache.org' server FQDN: 'juno.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1218 08:13:14.109575 32598 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1218 08:13:14.109591 32598 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1218 08:13:14.109603 32598 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'juno.apache.org' server FQDN: 'juno.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1218 08:13:14.109611 32598 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1218 08:13:14.109622 32598 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1218 08:13:14.109634 32598 authenticator.hpp:317] Authentication success I1218 08:13:14.109670 32598 master.cpp:1889] Successfully authenticated framework at scheduler(131)@67.195.138.61:48553 I1218 08:13:14.109700 32599 authenticatee.hpp:298] Authentication success I1218 08:13:14.109839 32599 sched.cpp:334] Successfully authenticated with master [email protected]:48553 I1218 08:13:14.109917 32601 master.cpp:801] Received registration request from scheduler(131)@67.195.138.61:48553 I1218 08:13:14.110117 32601 master.cpp:819] Registering framework 201312180813-1032504131-48553-32575-0000 at scheduler(131)@67.195.138.61:48553 I1218 08:13:14.110185 32599 sched.cpp:383] Framework registered with 201312180813-1032504131-48553-32575-0000 I1218 08:13:14.110189 32601 hierarchical_allocator_process.hpp:332] Added framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:14.110213 32601 hierarchical_allocator_process.hpp:726] No resources available to allocate! I1218 08:13:14.110221 32601 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 8499ns I1218 08:13:14.110223 32599 sched.cpp:397] Scheduler::registered took 18921ns I1218 08:13:14.957013 32595 hierarchical_allocator_process.hpp:726] No resources available to allocate! I1218 08:13:14.957046 32595 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 50353ns I1218 08:13:15.109369 32599 master.cpp:1366] Attempting to register slave on juno.apache.org at slave(136)@67.195.138.61:48553 I1218 08:13:15.109405 32599 master.cpp:2628] Adding slave 201312180813-1032504131-48553-32575-0 at juno.apache.org with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] I1218 08:13:15.109504 32600 slave.cpp:542] Registered with master [email protected]:48553; given slave ID 201312180813-1032504131-48553-32575-0 I1218 08:13:15.109604 32600 hierarchical_allocator_process.hpp:445] Added slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] available) I1218 08:13:15.109684 32600 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 201312180813-1032504131-48553-32575-0 to framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.109830 32600 hierarchical_allocator_process.hpp:708] Performed allocation for slave 201312180813-1032504131-48553-32575-0 in 179761ns I1218 08:13:15.109935 32594 master.hpp:437] Adding offer 201312180813-1032504131-48553-32575-0 with resources cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) I1218 08:13:15.110028 32594 master.cpp:1804] Sending 1 offers to framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.110340 32599 sched.cpp:517] Scheduler::resourceOffers took 176495ns I1218 08:13:15.110491 32598 master.cpp:2141] Processing reply for offer 201312180813-1032504131-48553-32575-0 on slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) for framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.110605 32598 master.hpp:409] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) I1218 08:13:15.110637 32598 master.cpp:2265] Launching task 0 of framework 201312180813-1032504131-48553-32575-0000 with resources cpus(*):1; mem(*):500 on slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) I1218 08:13:15.110752 32597 slave.cpp:727] Got assigned task 0 for framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.110833 32594 hierarchical_allocator_process.hpp:547] Framework 201312180813-1032504131-48553-32575-0000 left cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] unused on slave 201312180813-1032504131-48553-32575-0 I1218 08:13:15.110838 32598 master.hpp:447] Removing offer 201312180813-1032504131-48553-32575-0 with resources cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) I1218 08:13:15.110951 32597 slave.cpp:836] Launching task 0 for framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.111013 32594 hierarchical_allocator_process.hpp:590] Framework 201312180813-1032504131-48553-32575-0000 filtered slave 201312180813-1032504131-48553-32575-0 for 5secs I1218 08:13:15.112857 32597 slave.cpp:946] Queuing task '0' for executor default of framework '201312180813-1032504131-48553-32575-0000 I1218 08:13:15.112905 32597 slave.cpp:466] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_zHQiM2/slaves/201312180813-1032504131-48553-32575-0/frameworks/201312180813-1032504131-48553-32575-0000/executors/default/runs/c1ff6385-e210-4094-b0f7-477230156fe4' I1218 08:13:15.114550 32595 exec.cpp:178] Executor started at: executor(45)@67.195.138.61:48553 with pid 32575 I1218 08:13:15.114578 32601 slave.cpp:2089] Monitoring executor default of framework 201312180813-1032504131-48553-32575-0000 forked at pid 32575 I1218 08:13:15.114645 32601 slave.cpp:1422] Got registration for executor 'default' of framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.114820 32601 slave.cpp:1543] Flushing queued task 0 for executor 'default' of framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.114838 32595 exec.cpp:202] Executor registered on slave 201312180813-1032504131-48553-32575-0 I1218 08:13:15.116309 32595 exec.cpp:214] Executor::registered took 13146ns I1218 08:13:15.116363 32595 exec.cpp:289] Executor asked to run task '0' I1218 08:13:15.116407 32595 exec.cpp:298] Executor::launchTask took 32195ns I1218 08:13:15.117892 32595 exec.cpp:521] Executor sending status update TASK_RUNNING (UUID: 21a44cf0-3b37-4386-a894-457e1ec6651a) for task 0 of framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.117969 32595 slave.cpp:1756] Handling status update TASK_RUNNING (UUID: 21a44cf0-3b37-4386-a894-457e1ec6651a) for task 0 of framework 201312180813-1032504131-48553-32575-0000 from executor(45)@67.195.138.61:48553 I1218 08:13:15.118068 32601 status_update_manager.cpp:312] Received status update TASK_RUNNING (UUID: 21a44cf0-3b37-4386-a894-457e1ec6651a) for task 0 of framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.118084 32601 status_update_manager.cpp:491] Creating StatusUpdate stream for task 0 of framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.118149 32601 status_update_manager.cpp:365] Forwarding status update TASK_RUNNING (UUID: 21a44cf0-3b37-4386-a894-457e1ec6651a) for task 0 of framework 201312180813-1032504131-48553-32575-0000 to [email protected]:48553 I1218 08:13:15.118268 32596 slave.cpp:1875] Status update manager successfully handled status update TASK_RUNNING (UUID: 21a44cf0-3b37-4386-a894-457e1ec6651a) for task 0 of framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.118285 32595 master.cpp:1552] Status update TASK_RUNNING (UUID: 21a44cf0-3b37-4386-a894-457e1ec6651a) for task 0 of framework 201312180813-1032504131-48553-32575-0000 from slave(136)@67.195.138.61:48553 I1218 08:13:15.118299 32596 slave.cpp:1881] Sending acknowledgement for status update TASK_RUNNING (UUID: 21a44cf0-3b37-4386-a894-457e1ec6651a) for task 0 of framework 201312180813-1032504131-48553-32575-0000 to executor(45)@67.195.138.61:48553 I1218 08:13:15.118367 32598 sched.cpp:608] Scheduler::statusUpdate took 29240ns I1218 08:13:15.118376 32599 exec.cpp:335] Executor received status update acknowledgement 21a44cf0-3b37-4386-a894-457e1ec6651a for task 0 of framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.118494 32596 status_update_manager.cpp:390] Received status update acknowledgement (UUID: 21a44cf0-3b37-4386-a894-457e1ec6651a) for task 0 of framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.118561 32601 master.cpp:556] Master terminating I1218 08:13:15.118592 32599 slave.cpp:1362] Status update manager successfully handled status update acknowledgement (UUID: 21a44cf0-3b37-4386-a894-457e1ec6651a) for task 0 of framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.118638 32575 master.cpp:209] Shutting down master I1218 08:13:15.118644 32599 slave.cpp:1956] [email protected]:48553 exited W1218 08:13:15.118677 32599 slave.cpp:1959] Master disconnected! Waiting for a new master to be elected I1218 08:13:15.118729 32575 master.hpp:427] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) I1218 08:13:15.118852 32575 master.cpp:252] Removing slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) I1218 08:13:15.118893 32601 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000]) on slave 201312180813-1032504131-48553-32575-0 from framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.119287 32575 contender.cpp:172] Now cancelling the membership: 4 I1218 08:13:15.119434 32599 group.cpp:524] Trying to remove '/znode/0000000004' in ZooKeeper I1218 08:13:15.199285 32597 detector.cpp:116] The current leader (id=4) is lost I1218 08:13:15.199302 32597 detector.cpp:138] No new leader is elected after election 2013-12-18 08:13:15,199:32575(0x2ba6aa9027c0):ZOO_INFO@zookeeper_close@2304: Closing zookeeper sessionId=0x14304c4084d0006 to [127.0.0.1:60232] I1218 08:13:15.199452 32600 detector.cpp:116] The current leader (id=4) is lost I1218 08:13:15.199483 32600 detector.cpp:138] No new leader is elected after election I1218 08:13:15.199482 32596 sched.cpp:201] Scheduler::disconnected took 11747ns I1218 08:13:15.199517 32596 sched.cpp:223] No master detected I1218 08:13:15.199517 32597 detector.cpp:116] The current leader (id=4) is lost I1218 08:13:15.199527 32596 sched.cpp:229] Detecting new master I1218 08:13:15.199538 32597 detector.cpp:138] No new leader is elected after election I1218 08:13:15.199719 32597 slave.cpp:518] Lost leading master I1218 08:13:15.199729 32597 slave.cpp:524] Detecting new master 2013-12-18 08:13:15,200:32575(0x2ba6aa9027c0):ZOO_INFO@zookeeper_close@2304: Closing zookeeper sessionId=0x14304c4084d0008 to [127.0.0.1:60232] I1218 08:13:15.200811 32597 master.cpp:284] Master started on 67.195.138.61:48553 I1218 08:13:15.200844 32597 master.cpp:298] Master ID: 201312180813-1032504131-48553-32575 I1218 08:13:15.200851 32597 master.cpp:301] Master only allowing authenticated frameworks to register! 2013-12-18 08:13:15,201:32575(0x2ba6aaf09700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-12-18 08:13:15,201:32575(0x2ba6aaf09700):ZOO_INFO@log_env@662: Client environment:host.name=juno 2013-12-18 08:13:15,201:32575(0x2ba6aaf09700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-12-18 08:13:15,201:32575(0x2ba6aaf09700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-51-generic I1218 08:13:15.201159 32601 master.cpp:84] No whitelist given. Advertising offers for all slaves 2013-12-18 08:13:15,201:32575(0x2ba6ab30b700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-12-18 08:13:15,201:32575(0x2ba6ab30b700):ZOO_INFO@log_env@662: Client environment:host.name=juno 2013-12-18 08:13:15,201:32575(0x2ba6ab30b700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-12-18 08:13:15,201:32575(0x2ba6ab30b700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-51-generic 2013-12-18 08:13:15,201:32575(0x2ba6ab30b700):ZOO_INFO@log_env@671: Client environment:os.version=#77-Ubuntu SMP Wed Jul 24 20:18:19 UTC 2013 2013-12-18 08:13:15,201:32575(0x2ba6aaf09700):ZOO_INFO@log_env@671: Client environment:os.version=#77-Ubuntu SMP Wed Jul 24 20:18:19 UTC 2013 2013-12-18 08:13:15,201:32575(0x2ba6ab30b700):ZOO_INFO@log_env@679: Client environment:user.name=(null) 2013-12-18 08:13:15,201:32575(0x2ba6aaf09700):ZOO_INFO@log_env@679: Client environment:user.name=(null) 2013-12-18 08:13:15,201:32575(0x2ba6ab30b700):ZOO_INFO@log_env@687: Client environment:user.home=/home/jenkins 2013-12-18 08:13:15,201:32575(0x2ba6ab30b700):ZOO_INFO@log_env@699: Client environment:user.dir=<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src> 2013-12-18 08:13:15,201:32575(0x2ba6ab30b700):ZOO_INFO@zookeeper_init@727: Initiating client connection, host=127.0.0.1:60232 sessionTimeout=10000 watcher=0x2ba6a81b37a0 sessionId=0 sessionPasswd=<null> context=0x2ba6b8029af0 flags=0 I1218 08:13:15.201454 32595 hierarchical_allocator_process.hpp:302] Initializing hierarchical allocator process with master : [email protected]:48553 2013-12-18 08:13:15,201:32575(0x2ba6aaf09700):ZOO_INFO@log_env@687: Client environment:user.home=/home/jenkins 2013-12-18 08:13:15,201:32575(0x2ba6aaf09700):ZOO_INFO@log_env@699: Client environment:user.dir=<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src> 2013-12-18 08:13:15,201:32575(0x2ba6aaf09700):ZOO_INFO@zookeeper_init@727: Initiating client connection, host=127.0.0.1:60232 sessionTimeout=10000 watcher=0x2ba6a81b37a0 sessionId=0 sessionPasswd=<null> context=0x2ba6c002bba0 flags=0 I1218 08:13:15.201546 32600 contender.cpp:122] Joining the ZK group with data: '[email protected]:48553' 2013-12-18 08:13:15,201:32575(0x2ba834d16700):ZOO_INFO@check_events@1585: initiated connection to server [127.0.0.1:60232] 2013-12-18 08:13:15,201:32575(0x2ba850200700):ZOO_INFO@check_events@1585: initiated connection to server [127.0.0.1:60232] 2013-12-18 08:13:15,293:32575(0x2ba834d16700):ZOO_INFO@check_events@1632: session establishment complete on server [127.0.0.1:60232], sessionId=0x14304c4084d000a, negotiated timeout=10000 I1218 08:13:15.293489 32595 group.cpp:280] Group process ((1549)@67.195.138.61:48553) connected to ZooKeeper 2013-12-18 08:13:15,293:32575(0x2ba850200700):ZOO_INFO@check_events@1632: session establishment complete on server [127.0.0.1:60232], sessionId=0x14304c4084d000b, negotiated timeout=10000 I1218 08:13:15.293510 32595 group.cpp:680] Syncing group operations: queue size (joins, cancels, datas) = (0, 0, 0) I1218 08:13:15.293542 32595 group.cpp:337] Trying to create path '/znode' in ZooKeeper I1218 08:13:15.293660 32600 group.cpp:280] Group process ((1547)@67.195.138.61:48553) connected to ZooKeeper I1218 08:13:15.293685 32600 group.cpp:680] Syncing group operations: queue size (joins, cancels, datas) = (1, 0, 0) I1218 08:13:15.293702 32600 group.cpp:337] Trying to create path '/znode' in ZooKeeper I1218 08:13:15.323637 32598 contender.cpp:203] New candidate (id='6', data='[email protected]:48553') has entered the contest for leadership I1218 08:13:15.323957 32601 detector.cpp:130] Detected a new leader (id='6') I1218 08:13:15.324095 32596 group.cpp:567] Trying to get '/znode/0000000006' in ZooKeeper I1218 08:13:15.324245 32595 detector.cpp:130] Detected a new leader (id='6') I1218 08:13:15.324420 32598 group.cpp:567] Trying to get '/znode/0000000006' in ZooKeeper I1218 08:13:15.325031 32595 detector.cpp:322] A new leading master ([email protected]:48553) is detected I1218 08:13:15.325139 32596 master.cpp:746] The newly elected leader is [email protected]:48553 I1218 08:13:15.325151 32596 master.cpp:750] Elected as the leading master! I1218 08:13:15.325223 32597 detector.cpp:130] Detected a new leader (id='6') I1218 08:13:15.325297 32594 detector.cpp:322] A new leading master ([email protected]:48553) is detected I1218 08:13:15.325397 32600 slave.cpp:497] New master detected at [email protected]:48553 I1218 08:13:15.325423 32594 group.cpp:567] Trying to get '/znode/0000000006' in ZooKeeper I1218 08:13:15.325469 32598 status_update_manager.cpp:160] New master detected at [email protected]:48553 I1218 08:13:15.325539 32600 slave.cpp:524] Detecting new master I1218 08:13:15.325602 32598 master.cpp:1455] Attempting to re-register slave 201312180813-1032504131-48553-32575-0 at slave(136)@67.195.138.61:48553 (juno.apache.org) I1218 08:13:15.325619 32598 master.cpp:2628] Adding slave 201312180813-1032504131-48553-32575-0 at juno.apache.org with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] I1218 08:13:15.325716 32600 slave.cpp:592] Re-registered with master [email protected]:48553 I1218 08:13:15.325732 32598 master.hpp:409] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) W1218 08:13:15.325762 32598 master.cpp:2723] Possibly orphaned task 0 of framework 201312180813-1032504131-48553-32575-0000 running on slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) I1218 08:13:15.326071 32601 hierarchical_allocator_process.hpp:445] Added slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] available) I1218 08:13:15.326118 32601 hierarchical_allocator_process.hpp:708] Performed allocation for slave 201312180813-1032504131-48553-32575-0 in 7992ns I1218 08:13:15.326233 32598 detector.cpp:322] A new leading master ([email protected]:48553) is detected I1218 08:13:15.326292 32598 sched.cpp:207] New master detected at [email protected]:48553 I1218 08:13:15.326315 32598 sched.cpp:260] Authenticating with master [email protected]:48553 I1218 08:13:15.326375 32598 sched.cpp:229] Detecting new master I1218 08:13:15.326385 32600 authenticatee.hpp:124] Creating new client SASL connection I1218 08:13:15.326540 32598 master.cpp:1849] Authenticating framework at scheduler(131)@67.195.138.61:48553 I1218 08:13:15.326658 32595 authenticator.hpp:140] Creating new server SASL connection I1218 08:13:15.326808 32601 authenticatee.hpp:212] Received SASL authentication mechanisms: CRAM-MD5 I1218 08:13:15.326829 32601 authenticatee.hpp:238] Attempting to authenticate with mechanism 'CRAM-MD5' I1218 08:13:15.326855 32601 authenticator.hpp:243] Received SASL authentication start I1218 08:13:15.326916 32601 authenticator.hpp:325] Authentication requires more steps I1218 08:13:15.326941 32601 authenticatee.hpp:258] Received SASL authentication step I1218 08:13:15.326978 32601 authenticator.hpp:271] Received SASL authentication step I1218 08:13:15.326997 32601 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'juno.apache.org' server FQDN: 'juno.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1218 08:13:15.327007 32601 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1218 08:13:15.327021 32601 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1218 08:13:15.327033 32601 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'juno.apache.org' server FQDN: 'juno.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1218 08:13:15.327041 32601 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1218 08:13:15.327050 32601 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1218 08:13:15.327066 32601 authenticator.hpp:317] Authentication success I1218 08:13:15.327096 32595 authenticatee.hpp:298] Authentication success I1218 08:13:15.327100 32601 master.cpp:1889] Successfully authenticated framework at scheduler(131)@67.195.138.61:48553 I1218 08:13:15.327225 32595 sched.cpp:334] Successfully authenticated with master [email protected]:48553 I1218 08:13:15.327276 32596 master.cpp:889] Re-registering framework 201312180813-1032504131-48553-32575-0000 at scheduler(131)@67.195.138.61:48553 I1218 08:13:15.327435 32595 sched.cpp:383] Framework registered with 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.327462 32595 sched.cpp:397] Scheduler::registered took 10296ns I1218 08:13:15.327483 32595 slave.cpp:1303] Updating framework 201312180813-1032504131-48553-32575-0000 pid to scheduler(131)@67.195.138.61:48553 I1218 08:13:15.327571 32595 hierarchical_allocator_process.hpp:332] Added framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.327615 32595 hierarchical_allocator_process.hpp:752] Offering cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201312180813-1032504131-48553-32575-0 to framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.327749 32595 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 165131ns I1218 08:13:15.327813 32598 master.hpp:437] Adding offer 201312180813-1032504131-48553-32575-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) I1218 08:13:15.327869 32598 master.cpp:1804] Sending 1 offers to framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.328007 32594 sched.cpp:517] Scheduler::resourceOffers took 26669ns I1218 08:13:15.328243 32594 sched.cpp:719] Stopping framework '201312180813-1032504131-48553-32575-0000' I1218 08:13:15.328260 32575 master.cpp:556] Master terminating I1218 08:13:15.328320 32575 master.cpp:209] Shutting down master I1218 08:13:15.328325 32598 slave.cpp:1956] [email protected]:48553 exited W1218 08:13:15.328349 32598 slave.cpp:1959] Master disconnected! Waiting for a new master to be elected I1218 08:13:15.328392 32575 master.hpp:427] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) I1218 08:13:15.328505 32575 master.hpp:447] Removing offer 201312180813-1032504131-48553-32575-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) I1218 08:13:15.328604 32594 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201312180813-1032504131-48553-32575-0 from framework 201312180813-1032504131-48553-32575-0000 I1218 08:13:15.328670 32575 master.cpp:252] Removing slave 201312180813-1032504131-48553-32575-0 (juno.apache.org) I1218 08:13:15.438138 32575 contender.cpp:172] Now cancelling the membership: 6 I1218 08:13:15.438232 32598 group.cpp:524] Trying to remove '/znode/0000000006' in ZooKeeper 2013-12-18 08:13:15,481:32575(0x2ba6aa9027c0):ZOO_INFO@zookeeper_close@2304: Closing zookeeper sessionId=0x14304c4084d000b to [127.0.0.1:60232] I1218 08:13:15.482565 32594 detector.cpp:116] The current leader (id=6) is lost I1218 08:13:15.482589 32594 detector.cpp:138] No new leader is elected after election I1218 08:13:15.482640 32600 detector.cpp:116] The current leader (id=6) is lost I1218 08:13:15.482658 32600 detector.cpp:138] No new leader is elected after election I1218 08:13:15.482707 32595 slave.cpp:518] Lost leading master I1218 08:13:15.482729 32595 slave.cpp:524] Detecting new master I1218 08:13:15.482786 32597 detector.cpp:116] The current leader (id=6) is lost I1218 08:13:15.482808 32597 detector.cpp:138] No new leader is elected after election 2013-12-18 08:13:15,483:32575(0x2ba6aa9027c0):ZOO_INFO@zookeeper_close@2304: Closing zookeeper sessionId=0x14304c4084d000a to [127.0.0.1:60232] I1218 08:13:15.483340 32575 slave.cpp:391] Slave terminating I1218 08:13:15.483371 32575 slave.cpp:1133] Asked to shut down framework 201312180813-1032504131-48553-32575-0000 by @0.0.0.0:0 W1218 08:13:15.483381 32575 slave.cpp:1141] Ignoring shutdown framework message for 201312180813-1032504131-48553-32575-0000 because the slave has not yet registered with the master I1218 08:13:15.483453 32598 exec.cpp:455] Slave exited ... shutting down I1218 08:13:15.483491 32598 exec.cpp:472] Executor::shutdown took 16319ns 2013-12-18 08:13:15,483:32575(0x2ba6aa9027c0):ZOO_INFO@zookeeper_close@2304: Closing zookeeper sessionId=0x14304c4084d0007 to [127.0.0.1:60232] 2013-12-18 08:13:15,484:32575(0x2ba6aa9027c0):ZOO_INFO@zookeeper_close@2304: Closing zookeeper sessionId=0x14304c4084d0009 to [127.0.0.1:60232] [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (1529 ms) I1218 08:13:15.531852 32575 zookeeper_test_server.cpp:121] Shutdown ZooKeeperTestServer on port 60232 [----------] 2 tests from AllocatorZooKeeperTest/0 (2830 ms total) [----------] Global test environment tear-down [==========] 247 tests from 43 test cases ran. (216188 ms total) [ PASSED ] 247 tests. YOU HAVE 2 DISABLED TESTS make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> Making check in ec2 make[1]: Entering directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/ec2'> make[1]: Nothing to be done for `check'. make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/ec2'> Looks like the node went offline during the build. Check the slave log for the details.FATAL: /home/hudson/hudson/logs/slaves/ubuntu5/slave.log (No such file or directory) java.io.FileNotFoundException: /home/hudson/hudson/logs/slaves/ubuntu5/slave.log (No such file or directory) at java.io.RandomAccessFile.open(Native Method) at java.io.RandomAccessFile.<init>(RandomAccessFile.java:241) at org.kohsuke.stapler.framework.io.LargeText$FileSession.<init>(LargeText.java:397) at org.kohsuke.stapler.framework.io.LargeText$2.open(LargeText.java:120) at org.kohsuke.stapler.framework.io.LargeText.writeLogTo(LargeText.java:210) at hudson.console.AnnotatedLargeText.writeHtmlTo(AnnotatedLargeText.java:156) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:579) at hudson.model.Run.execute(Run.java:1677) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:46) at hudson.model.ResourceController.execute(ResourceController.java:88) at hudson.model.Executor.run(Executor.java:231)
