See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1523/changes>
Changes: [niklas] Added stringifier.hpp to stout/Makefile.am. [rossgit] Printed timezone, full ISO 8601, with timestamps. [benjamin.hindman] Added min/max functions to stout which take two Options. ------------------------------------------ [...truncated 23162 lines...] I1211 14:40:37.761520 26086 group.cpp:280] Group process ((1533)@67.195.138.60:47859) connected to ZooKeeper I1211 14:40:37.761536 26086 group.cpp:675] Syncing group operations: queue size (joins, cancels, datas) = (0, 0, 0) I1211 14:40:37.761545 26086 group.cpp:337] Trying to create path '/znode' in ZooKeeper I1211 14:40:37.784162 26087 contender.cpp:203] New candidate (id='4', data='[email protected]:47859') has entered the contest for leadership I1211 14:40:37.784337 26082 detector.cpp:130] Detected a new leader (id='4') I1211 14:40:37.784454 26080 group.cpp:562] Trying to get '/znode/0000000004' in ZooKeeper I1211 14:40:37.784579 26084 detector.cpp:130] Detected a new leader (id='4') I1211 14:40:37.784664 26087 group.cpp:562] Trying to get '/znode/0000000004' in ZooKeeper I1211 14:40:37.784806 26086 detector.cpp:130] Detected a new leader (id='4') I1211 14:40:37.784867 26086 group.cpp:562] Trying to get '/znode/0000000004' in ZooKeeper I1211 14:40:37.785302 26080 detector.cpp:322] A new leading master ([email protected]:47859) is detected I1211 14:40:37.785352 26084 master.cpp:746] The newly elected leader is [email protected]:47859 I1211 14:40:37.785369 26084 master.cpp:750] Elected as the leading master! I1211 14:40:37.785823 26084 detector.cpp:322] A new leading master ([email protected]:47859) is detected I1211 14:40:37.785852 26087 detector.cpp:322] A new leading master ([email protected]:47859) is detected I1211 14:40:37.785859 26084 slave.cpp:497] New master detected at [email protected]:47859 I1211 14:40:37.785888 26087 status_update_manager.cpp:160] New master detected at [email protected]:47859 I1211 14:40:37.785925 26084 slave.cpp:524] Detecting new master I1211 14:40:37.785924 26081 sched.cpp:207] New master detected at [email protected]:47859 I1211 14:40:37.785956 26085 master.cpp:1366] Attempting to register slave on janus.apache.org at slave(136)@67.195.138.60:47859 I1211 14:40:37.785956 26081 sched.cpp:260] Authenticating with master [email protected]:47859 I1211 14:40:37.785974 26085 master.cpp:2628] Adding slave 201312111440-1015726915-47859-26059-0 at janus.apache.org with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] I1211 14:40:37.786031 26081 sched.cpp:229] Detecting new master I1211 14:40:37.786038 26087 authenticatee.hpp:124] Creating new client SASL connection I1211 14:40:37.786039 26086 slave.cpp:542] Registered with master [email protected]:47859; given slave ID 201312111440-1015726915-47859-26059-0 I1211 14:40:37.786139 26082 hierarchical_allocator_process.hpp:445] Added slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] available) I1211 14:40:37.786170 26086 master.cpp:1849] Authenticating framework at scheduler(131)@67.195.138.60:47859 I1211 14:40:37.786185 26082 hierarchical_allocator_process.hpp:708] Performed allocation for slave 201312111440-1015726915-47859-26059-0 in 9416ns I1211 14:40:37.786239 26087 authenticator.hpp:140] Creating new server SASL connection I1211 14:40:37.786339 26087 authenticatee.hpp:212] Received SASL authentication mechanisms: CRAM-MD5 I1211 14:40:37.786360 26087 authenticatee.hpp:238] Attempting to authenticate with mechanism 'CRAM-MD5' I1211 14:40:37.786386 26087 authenticator.hpp:243] Received SASL authentication start I1211 14:40:37.786465 26087 authenticator.hpp:325] Authentication requires more steps I1211 14:40:37.786491 26087 authenticatee.hpp:258] Received SASL authentication step I1211 14:40:37.786531 26087 authenticator.hpp:271] Received SASL authentication step I1211 14:40:37.786550 26087 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'janus.apache.org' server FQDN: 'janus.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1211 14:40:37.786559 26087 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1211 14:40:37.786576 26087 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1211 14:40:37.786589 26087 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'janus.apache.org' server FQDN: 'janus.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1211 14:40:37.786597 26087 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1211 14:40:37.786602 26087 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1211 14:40:37.786615 26087 authenticator.hpp:317] Authentication success I1211 14:40:37.786653 26085 master.cpp:1889] Successfully authenticated framework at scheduler(131)@67.195.138.60:47859 I1211 14:40:37.786659 26081 authenticatee.hpp:298] Authentication success I1211 14:40:37.786778 26081 sched.cpp:334] Successfully authenticated with master [email protected]:47859 I1211 14:40:37.786839 26087 master.cpp:801] Received registration request from scheduler(131)@67.195.138.60:47859 I1211 14:40:37.786998 26087 master.cpp:819] Registering framework 201312111440-1015726915-47859-26059-0000 at scheduler(131)@67.195.138.60:47859 I1211 14:40:37.787061 26081 sched.cpp:383] Framework registered with 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.787075 26085 hierarchical_allocator_process.hpp:332] Added framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.787091 26081 sched.cpp:397] Scheduler::registered took 14795ns I1211 14:40:37.787114 26085 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 201312111440-1015726915-47859-26059-0 to framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.787240 26085 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 151501ns I1211 14:40:37.787288 26084 master.hpp:437] Adding offer 201312111440-1015726915-47859-26059-0 with resources cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) I1211 14:40:37.787344 26084 master.cpp:1804] Sending 1 offers to framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.787606 26084 sched.cpp:517] Scheduler::resourceOffers took 173365ns I1211 14:40:37.787706 26087 master.cpp:2141] Processing reply for offer 201312111440-1015726915-47859-26059-0 on slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) for framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.787806 26087 master.hpp:409] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) I1211 14:40:37.787837 26087 master.cpp:2265] Launching task 0 of framework 201312111440-1015726915-47859-26059-0000 with resources cpus(*):1; mem(*):500 on slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) I1211 14:40:37.787905 26084 slave.cpp:727] Got assigned task 0 for framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.787992 26087 master.hpp:447] Removing offer 201312111440-1015726915-47859-26059-0 with resources cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) I1211 14:40:37.787994 26080 hierarchical_allocator_process.hpp:547] Framework 201312111440-1015726915-47859-26059-0000 left cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] unused on slave 201312111440-1015726915-47859-26059-0 I1211 14:40:37.788069 26084 slave.cpp:836] Launching task 0 for framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.788135 26080 hierarchical_allocator_process.hpp:590] Framework 201312111440-1015726915-47859-26059-0000 filtered slave 201312111440-1015726915-47859-26059-0 for 5secs I1211 14:40:37.789963 26084 slave.cpp:946] Queuing task '0' for executor default of framework '201312111440-1015726915-47859-26059-0000 I1211 14:40:37.790011 26084 slave.cpp:466] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_YMHpEZ/slaves/201312111440-1015726915-47859-26059-0/frameworks/201312111440-1015726915-47859-26059-0000/executors/default/runs/53497ae9-6c56-484b-8459-28c1d2c6a5f5' I1211 14:40:37.791718 26087 exec.cpp:178] Executor started at: executor(45)@67.195.138.60:47859 with pid 26059 I1211 14:40:37.791728 26080 slave.cpp:2089] Monitoring executor default of framework 201312111440-1015726915-47859-26059-0000 forked at pid 26059 I1211 14:40:37.791805 26080 slave.cpp:1422] Got registration for executor 'default' of framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.791982 26080 slave.cpp:1543] Flushing queued task 0 for executor 'default' of framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.791992 26086 exec.cpp:202] Executor registered on slave 201312111440-1015726915-47859-26059-0 I1211 14:40:37.793479 26086 exec.cpp:214] Executor::registered took 17231ns I1211 14:40:37.793526 26086 exec.cpp:289] Executor asked to run task '0' I1211 14:40:37.793562 26086 exec.cpp:298] Executor::launchTask took 22999ns I1211 14:40:37.795044 26086 exec.cpp:521] Executor sending status update TASK_RUNNING (UUID: d28695aa-2d3e-4702-be50-a9e1be87cbae) for task 0 of framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.795112 26086 slave.cpp:1756] Handling status update TASK_RUNNING (UUID: d28695aa-2d3e-4702-be50-a9e1be87cbae) for task 0 of framework 201312111440-1015726915-47859-26059-0000 from executor(45)@67.195.138.60:47859 I1211 14:40:37.795189 26087 status_update_manager.cpp:312] Received status update TASK_RUNNING (UUID: d28695aa-2d3e-4702-be50-a9e1be87cbae) for task 0 of framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.795207 26087 status_update_manager.cpp:491] Creating StatusUpdate stream for task 0 of framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.795267 26087 status_update_manager.cpp:365] Forwarding status update TASK_RUNNING (UUID: d28695aa-2d3e-4702-be50-a9e1be87cbae) for task 0 of framework 201312111440-1015726915-47859-26059-0000 to [email protected]:47859 I1211 14:40:37.795371 26080 slave.cpp:1875] Status update manager successfully handled status update TASK_RUNNING (UUID: d28695aa-2d3e-4702-be50-a9e1be87cbae) for task 0 of framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.795388 26080 slave.cpp:1881] Sending acknowledgement for status update TASK_RUNNING (UUID: d28695aa-2d3e-4702-be50-a9e1be87cbae) for task 0 of framework 201312111440-1015726915-47859-26059-0000 to executor(45)@67.195.138.60:47859 I1211 14:40:37.795420 26086 master.cpp:1552] Status update TASK_RUNNING (UUID: d28695aa-2d3e-4702-be50-a9e1be87cbae) for task 0 of framework 201312111440-1015726915-47859-26059-0000 from slave(136)@67.195.138.60:47859 I1211 14:40:37.795446 26084 exec.cpp:335] Executor received status update acknowledgement d28695aa-2d3e-4702-be50-a9e1be87cbae for task 0 of framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.795491 26085 sched.cpp:608] Scheduler::statusUpdate took 27476ns I1211 14:40:37.795685 26086 status_update_manager.cpp:390] Received status update acknowledgement (UUID: d28695aa-2d3e-4702-be50-a9e1be87cbae) for task 0 of framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.795718 26059 master.cpp:556] Master terminating I1211 14:40:37.795753 26059 master.cpp:209] Shutting down master I1211 14:40:37.795778 26082 slave.cpp:1956] [email protected]:47859 exited W1211 14:40:37.795796 26082 slave.cpp:1959] Master disconnected! Waiting for a new master to be elected I1211 14:40:37.795795 26059 master.hpp:427] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) I1211 14:40:37.795819 26082 slave.cpp:1362] Status update manager successfully handled status update acknowledgement (UUID: d28695aa-2d3e-4702-be50-a9e1be87cbae) for task 0 of framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.795881 26059 master.cpp:252] Removing slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) I1211 14:40:37.795912 26086 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000]) on slave 201312111440-1015726915-47859-26059-0 from framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.851781 26080 contender.cpp:172] Now cancelling the membership: 4 I1211 14:40:37.851851 26087 group.cpp:519] Trying to remove '/znode/0000000004' in ZooKeeper 2013-12-11 14:40:37,864:26059(0x2aeb257467c0):ZOO_INFO@zookeeper_close@2304: Closing zookeeper sessionId=0x142e21a2c6c0006 to [127.0.0.1:48827] I1211 14:40:37.865260 26084 detector.cpp:116] The current leader (id=4) is lost I1211 14:40:37.865272 26084 detector.cpp:138] No new leader is elected after election I1211 14:40:37.865376 26087 sched.cpp:201] Scheduler::disconnected took 17402ns I1211 14:40:37.865387 26087 sched.cpp:223] No master detected I1211 14:40:37.865394 26087 sched.cpp:229] Detecting new master I1211 14:40:37.865586 26082 detector.cpp:116] The current leader (id=4) is lost I1211 14:40:37.865602 26082 detector.cpp:138] No new leader is elected after election I1211 14:40:37.865685 26082 slave.cpp:518] Lost leading master I1211 14:40:37.865697 26082 slave.cpp:524] Detecting new master 2013-12-11 14:40:37,865:26059(0x2aeb257467c0):ZOO_INFO@zookeeper_close@2304: Closing zookeeper sessionId=0x142e21a2c6c0007 to [127.0.0.1:48827] I1211 14:40:37.866741 26084 master.cpp:284] Master started on 67.195.138.60:47859 I1211 14:40:37.866770 26084 master.cpp:298] Master ID: 201312111440-1015726915-47859-26059 I1211 14:40:37.866776 26084 master.cpp:301] Master only allowing authenticated frameworks to register! I1211 14:40:37.866972 26082 master.cpp:84] No whitelist given. Advertising offers for all slaves I1211 14:40:37.867169 26080 hierarchical_allocator_process.hpp:302] Initializing hierarchical allocator process with master : [email protected]:47859 I1211 14:40:37.867280 26082 contender.cpp:122] Joining the ZK group with data: '[email protected]:47859' 2013-12-11 14:40:37,867:26059(0x2aeb25b4c700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-12-11 14:40:37,867:26059(0x2aeb25b4c700):ZOO_INFO@log_env@662: Client environment:host.name=janus 2013-12-11 14:40:37,867:26059(0x2aeb25b4c700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-12-11 14:40:37,867:26059(0x2aeb25b4c700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-57-generic 2013-12-11 14:40:37,867:26059(0x2aeb25b4c700):ZOO_INFO@log_env@671: Client environment:os.version=#87-Ubuntu SMP Tue Nov 12 21:35:10 UTC 2013 2013-12-11 14:40:37,867:26059(0x2aeb25f4e700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-12-11 14:40:37,867:26059(0x2aeb25f4e700):ZOO_INFO@log_env@662: Client environment:host.name=janus 2013-12-11 14:40:37,867:26059(0x2aeb25f4e700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-12-11 14:40:37,867:26059(0x2aeb25b4c700):ZOO_INFO@log_env@679: Client environment:user.name=(null) 2013-12-11 14:40:37,867:26059(0x2aeb25f4e700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-57-generic 2013-12-11 14:40:37,867:26059(0x2aeb25f4e700):ZOO_INFO@log_env@671: Client environment:os.version=#87-Ubuntu SMP Tue Nov 12 21:35:10 UTC 2013 2013-12-11 14:40:37,867:26059(0x2aeb25b4c700):ZOO_INFO@log_env@687: Client environment:user.home=/home/jenkins 2013-12-11 14:40:37,867:26059(0x2aeb25b4c700):ZOO_INFO@log_env@699: Client environment:user.dir=<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src> 2013-12-11 14:40:37,867:26059(0x2aeb25b4c700):ZOO_INFO@zookeeper_init@727: Initiating client connection, host=127.0.0.1:48827 sessionTimeout=10000 watcher=0x2aeb22ffa2d0 sessionId=0 sessionPasswd=<null> context=0x2aeb4018c4d0 flags=0 2013-12-11 14:40:37,867:26059(0x2aeb25f4e700):ZOO_INFO@log_env@679: Client environment:user.name=(null) 2013-12-11 14:40:37,867:26059(0x2aeb25f4e700):ZOO_INFO@log_env@687: Client environment:user.home=/home/jenkins 2013-12-11 14:40:37,867:26059(0x2aeb25f4e700):ZOO_INFO@log_env@699: Client environment:user.dir=<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src> 2013-12-11 14:40:37,867:26059(0x2aeb25f4e700):ZOO_INFO@zookeeper_init@727: Initiating client connection, host=127.0.0.1:48827 sessionTimeout=10000 watcher=0x2aeb22ffa2d0 sessionId=0 sessionPasswd=<null> context=0x2aeb28177590 flags=0 2013-12-11 14:40:37,867:26059(0x2aecbd51a700):ZOO_INFO@check_events@1585: initiated connection to server [127.0.0.1:48827] 2013-12-11 14:40:37,868:26059(0x2aecd8200700):ZOO_INFO@check_events@1585: initiated connection to server [127.0.0.1:48827] 2013-12-11 14:40:37,916:26059(0x2aecbd51a700):ZOO_INFO@check_events@1632: session establishment complete on server [127.0.0.1:48827], sessionId=0x142e21a2c6c000a, negotiated timeout=10000 I1211 14:40:37.916880 26087 group.cpp:280] Group process ((1546)@67.195.138.60:47859) connected to ZooKeeper I1211 14:40:37.916900 26087 group.cpp:675] Syncing group operations: queue size (joins, cancels, datas) = (1, 0, 0) I1211 14:40:37.916916 26087 group.cpp:337] Trying to create path '/znode' in ZooKeeper 2013-12-11 14:40:37,917:26059(0x2aecd8200700):ZOO_INFO@check_events@1632: session establishment complete on server [127.0.0.1:48827], sessionId=0x142e21a2c6c000b, negotiated timeout=10000 I1211 14:40:37.917409 26081 group.cpp:280] Group process ((1548)@67.195.138.60:47859) connected to ZooKeeper I1211 14:40:37.917423 26081 group.cpp:675] Syncing group operations: queue size (joins, cancels, datas) = (0, 0, 0) I1211 14:40:37.917431 26081 group.cpp:337] Trying to create path '/znode' in ZooKeeper I1211 14:40:37.936923 26086 contender.cpp:203] New candidate (id='6', data='[email protected]:47859') has entered the contest for leadership I1211 14:40:37.937067 26081 detector.cpp:130] Detected a new leader (id='6') I1211 14:40:37.937177 26081 group.cpp:562] Trying to get '/znode/0000000006' in ZooKeeper I1211 14:40:37.937597 26083 detector.cpp:130] Detected a new leader (id='6') I1211 14:40:37.937656 26084 detector.cpp:130] Detected a new leader (id='6') I1211 14:40:37.937700 26080 group.cpp:562] Trying to get '/znode/0000000006' in ZooKeeper I1211 14:40:37.937819 26084 group.cpp:562] Trying to get '/znode/0000000006' in ZooKeeper I1211 14:40:37.938117 26081 detector.cpp:322] A new leading master ([email protected]:47859) is detected I1211 14:40:37.938175 26083 master.cpp:746] The newly elected leader is [email protected]:47859 I1211 14:40:37.938190 26083 master.cpp:750] Elected as the leading master! I1211 14:40:37.938348 26080 detector.cpp:322] A new leading master ([email protected]:47859) is detected I1211 14:40:37.938446 26086 slave.cpp:497] New master detected at [email protected]:47859 I1211 14:40:37.938488 26080 status_update_manager.cpp:160] New master detected at [email protected]:47859 I1211 14:40:37.938534 26084 detector.cpp:322] A new leading master ([email protected]:47859) is detected I1211 14:40:37.938556 26086 slave.cpp:524] Detecting new master I1211 14:40:37.938609 26086 sched.cpp:207] New master detected at [email protected]:47859 I1211 14:40:37.938628 26086 sched.cpp:260] Authenticating with master [email protected]:47859 I1211 14:40:37.938634 26084 master.cpp:1455] Attempting to re-register slave 201312111440-1015726915-47859-26059-0 at slave(136)@67.195.138.60:47859 (janus.apache.org) I1211 14:40:37.938649 26084 master.cpp:2628] Adding slave 201312111440-1015726915-47859-26059-0 at janus.apache.org with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] I1211 14:40:37.938689 26086 sched.cpp:229] Detecting new master I1211 14:40:37.938705 26083 authenticatee.hpp:124] Creating new client SASL connection I1211 14:40:37.938746 26086 slave.cpp:592] Re-registered with master [email protected]:47859 I1211 14:40:37.938742 26084 master.hpp:409] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) W1211 14:40:37.938784 26084 master.cpp:2723] Possibly orphaned task 0 of framework 201312111440-1015726915-47859-26059-0000 running on slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) I1211 14:40:37.938941 26084 master.cpp:1849] Authenticating framework at scheduler(131)@67.195.138.60:47859 I1211 14:40:37.939043 26084 authenticator.hpp:140] Creating new server SASL connection I1211 14:40:37.939041 26087 hierarchical_allocator_process.hpp:445] Added slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] available) I1211 14:40:37.939081 26087 hierarchical_allocator_process.hpp:708] Performed allocation for slave 201312111440-1015726915-47859-26059-0 in 7854ns I1211 14:40:37.939254 26080 authenticatee.hpp:212] Received SASL authentication mechanisms: CRAM-MD5 I1211 14:40:37.939276 26080 authenticatee.hpp:238] Attempting to authenticate with mechanism 'CRAM-MD5' I1211 14:40:37.939306 26080 authenticator.hpp:243] Received SASL authentication start I1211 14:40:37.939379 26080 authenticator.hpp:325] Authentication requires more steps I1211 14:40:37.939406 26080 authenticatee.hpp:258] Received SASL authentication step I1211 14:40:37.939438 26080 authenticator.hpp:271] Received SASL authentication step I1211 14:40:37.939461 26080 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'janus.apache.org' server FQDN: 'janus.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1211 14:40:37.939471 26080 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1211 14:40:37.939486 26080 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1211 14:40:37.939499 26080 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'janus.apache.org' server FQDN: 'janus.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1211 14:40:37.939507 26080 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1211 14:40:37.939513 26080 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1211 14:40:37.939527 26080 authenticator.hpp:317] Authentication success I1211 14:40:37.939554 26080 authenticatee.hpp:298] Authentication success I1211 14:40:37.939574 26080 master.cpp:1889] Successfully authenticated framework at scheduler(131)@67.195.138.60:47859 I1211 14:40:37.939642 26080 sched.cpp:334] Successfully authenticated with master [email protected]:47859 I1211 14:40:37.939697 26080 master.cpp:889] Re-registering framework 201312111440-1015726915-47859-26059-0000 at scheduler(131)@67.195.138.60:47859 I1211 14:40:37.939904 26086 sched.cpp:383] Framework registered with 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.939954 26080 slave.cpp:1303] Updating framework 201312111440-1015726915-47859-26059-0000 pid to scheduler(131)@67.195.138.60:47859 I1211 14:40:37.939981 26086 sched.cpp:397] Scheduler::registered took 61035ns I1211 14:40:37.939997 26083 hierarchical_allocator_process.hpp:332] Added framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.940053 26083 hierarchical_allocator_process.hpp:752] Offering cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201312111440-1015726915-47859-26059-0 to framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.940201 26083 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 186687ns I1211 14:40:37.940249 26080 master.hpp:437] Adding offer 201312111440-1015726915-47859-26059-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) I1211 14:40:37.940310 26080 master.cpp:1804] Sending 1 offers to framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.940424 26087 sched.cpp:517] Scheduler::resourceOffers took 24368ns I1211 14:40:37.940608 26087 sched.cpp:719] Stopping framework '201312111440-1015726915-47859-26059-0000' I1211 14:40:37.940613 26080 master.cpp:556] Master terminating I1211 14:40:37.940650 26082 slave.cpp:1956] [email protected]:47859 exited W1211 14:40:37.940665 26082 slave.cpp:1959] Master disconnected! Waiting for a new master to be elected I1211 14:40:37.940668 26059 master.cpp:209] Shutting down master I1211 14:40:37.940721 26059 master.hpp:427] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) I1211 14:40:37.940821 26059 master.hpp:447] Removing offer 201312111440-1015726915-47859-26059-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) I1211 14:40:37.940892 26083 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201312111440-1015726915-47859-26059-0 from framework 201312111440-1015726915-47859-26059-0000 I1211 14:40:37.940914 26059 master.cpp:252] Removing slave 201312111440-1015726915-47859-26059-0 (janus.apache.org) 2013-12-11 14:40:37,958:26059(0x2aecf0200700):ZOO_ERROR@handle_socket_error_msg@1579: Socket [127.0.0.1:41674] zk retcode=-4, errno=111(Connection refused): server refused to accept the client I1211 14:40:38.013669 26059 contender.cpp:172] Now cancelling the membership: 6 I1211 14:40:38.013736 26086 group.cpp:519] Trying to remove '/znode/0000000006' in ZooKeeper 2013-12-11 14:40:38,023:26059(0x2aeb257467c0):ZOO_INFO@zookeeper_close@2304: Closing zookeeper sessionId=0x142e21a2c6c000a to [127.0.0.1:48827] I1211 14:40:38.023362 26080 detector.cpp:116] The current leader (id=6) is lost I1211 14:40:38.023377 26080 detector.cpp:138] No new leader is elected after election 2013-12-11 14:40:38,023:26059(0x2aeb257467c0):ZOO_INFO@zookeeper_close@2304: Closing zookeeper sessionId=0x142e21a2c6c000b to [127.0.0.1:48827] I1211 14:40:38.023794 26081 detector.cpp:116] The current leader (id=6) is lost I1211 14:40:38.023809 26081 detector.cpp:138] No new leader is elected after election I1211 14:40:38.023825 26080 detector.cpp:116] The current leader (id=6) is lost I1211 14:40:38.023840 26080 detector.cpp:138] No new leader is elected after election I1211 14:40:38.023907 26082 slave.cpp:518] Lost leading master I1211 14:40:38.023921 26082 slave.cpp:524] Detecting new master I1211 14:40:38.023954 26082 slave.cpp:391] Slave terminating I1211 14:40:38.023973 26082 slave.cpp:1133] Asked to shut down framework 201312111440-1015726915-47859-26059-0000 by @0.0.0.0:0 W1211 14:40:38.023983 26082 slave.cpp:1141] Ignoring shutdown framework message for 201312111440-1015726915-47859-26059-0000 because the slave has not yet registered with the master I1211 14:40:38.024045 26085 exec.cpp:455] Slave exited ... shutting down I1211 14:40:38.024070 26085 exec.cpp:472] Executor::shutdown took 12294ns 2013-12-11 14:40:38,024:26059(0x2aeb257467c0):ZOO_INFO@zookeeper_close@2304: Closing zookeeper sessionId=0x142e21a2c6c0009 to [127.0.0.1:48827] 2013-12-11 14:40:38,024:26059(0x2aeb257467c0):ZOO_INFO@zookeeper_close@2304: Closing zookeeper sessionId=0x142e21a2c6c0008 to [127.0.0.1:48827] [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (341 ms) I1211 14:40:38.031440 26059 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 48827 [----------] 2 tests from AllocatorZooKeeperTest/0 (2588 ms total) [----------] Global test environment tear-down [==========] 246 tests from 43 test cases ran. (204932 ms total) [ PASSED ] 245 tests. [ FAILED ] 1 test, listed below: [ FAILED ] ExamplesTest.PythonFramework 1 FAILED TEST YOU HAVE 2 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 Build step 'Execute shell' marked build as failure
