More logging clean-ups. Static type-checking for RPC calls and
[invirt/third/libt4.git] / rpc / rpc.cc
index a08e287..964102f 100644 (file)
@@ -70,7 +70,7 @@ rpcc::rpcc(const string & d, bool retrans) :
     dst_(make_sockaddr(d)), srv_nonce_(0), bind_done_(false), xid_(1), lossytest_(0),
     retrans_(retrans), reachable_(true), chan_(), destroy_wait_ (false), xid_rep_done_(-1)
 {
-    if(retrans){
+    if (retrans) {
         set_rand_seed();
         clt_nonce_ = (unsigned int)random();
     } else {
@@ -81,7 +81,7 @@ rpcc::rpcc(const string & d, bool retrans) :
     }
 
     char *loss_env = getenv("RPC_LOSSY");
-    if(loss_env)
+    if (loss_env)
         lossytest_ = atoi(loss_env);
 
     // xid starts with 1 and latest received reply starts with 0
@@ -95,15 +95,15 @@ rpcc::rpcc(const string & d, bool retrans) :
 rpcc::~rpcc() {
     cancel();
     IF_LEVEL(2) LOG("delete nonce " << clt_nonce_ << " channo=" << (chan_?chan_->channo():-1));
-    if(chan_)
+    if (chan_)
         chan_->closeconn();
     VERIFY(calls_.size() == 0);
 }
 
 int rpcc::bind(milliseconds to) {
     unsigned int r;
-    int ret = call_timeout(rpc_const::bind, to, r, 0);
-    if(ret == 0){
+    int ret = call_timeout(rpc_protocol::bind, to, r, 0);
+    if (ret == 0) {
         lock ml(m_);
         bind_done_ = true;
         srv_nonce_ = r;
@@ -118,58 +118,57 @@ void rpcc::cancel(void) {
     lock ml(m_);
     if (calls_.size()) {
         LOG("force callers to fail");
-        for(auto &p : calls_){
+        for (auto &p : calls_) {
             caller *ca = p.second;
 
             IF_LEVEL(2) LOG("force caller to fail");
-            {
-                lock cl(ca->m);
-                ca->done = true;
-                ca->intret = rpc_const::cancel_failure;
-                ca->c.notify_one();
-            }
+
+            lock cl(ca->m);
+            ca->done = true;
+            ca->intret = rpc_protocol::cancel_failure;
+            ca->c.notify_one();
         }
 
-        while (calls_.size () > 0){
-            destroy_wait_ = true;
+        destroy_wait_ = true;
+        while (calls_.size () > 0)
             destroy_wait_c_.wait(ml);
-        }
+
         LOG("done");
     }
 }
 
-int rpcc::call1(proc_t proc, marshall &req, string &rep, milliseconds to) {
+int rpcc::call1(proc_id_t proc, marshall &req, string &rep, milliseconds to) {
 
     caller ca(0, &rep);
     int xid_rep;
     {
         lock ml(m_);
 
-        if((proc != rpc_const::bind && !bind_done_) ||
-                (proc == rpc_const::bind && bind_done_)){
+        if ((proc != rpc_protocol::bind.id && !bind_done_) || (proc == rpc_protocol::bind.id && bind_done_)) {
             IF_LEVEL(1) LOG("rpcc has not been bound to dst or binding twice");
-            return rpc_const::bind_failure;
+            return rpc_protocol::bind_failure;
         }
 
-        if(destroy_wait_){
-          return rpc_const::cancel_failure;
-        }
+        if (destroy_wait_)
+            return rpc_protocol::cancel_failure;
 
         ca.xid = xid_++;
         calls_[ca.xid] = &ca;
 
-        req.pack_header(request_header{ca.xid, proc, clt_nonce_, srv_nonce_, xid_rep_window_.front()});
+        req.pack_header(rpc_protocol::request_header{
+                ca.xid, proc, clt_nonce_, srv_nonce_, xid_rep_window_.front()
+                });
         xid_rep = xid_rep_window_.front();
     }
 
     milliseconds curr_to = rpc::to_min;
-    auto finaldeadline = steady_clock::now() + to, nextdeadline = finaldeadline;
+    auto finaldeadline = steady_clock::now() + to;
 
     bool transmit = true;
     shared_ptr<connection> ch;
 
     while (1) {
-        if(transmit) {
+        if (transmit) {
             get_refconn(ch);
             if (ch) {
                 if (reachable_) {
@@ -192,36 +191,32 @@ int rpcc::call1(proc_t proc, marshall &req, string &rep, milliseconds to) {
             transmit = false; // only send once on a given channel
         }
 
-        if(finaldeadline == time_point<steady_clock>::min())
-            break;
-
-        nextdeadline = steady_clock::now() + curr_to;
-        if(nextdeadline > finaldeadline) {
-            nextdeadline = finaldeadline;
-            finaldeadline = time_point<steady_clock>::min();
-        }
+        auto nextdeadline = min(steady_clock::now() + curr_to, finaldeadline);
+        curr_to *= 2;
 
         {
             lock cal(ca.m);
-            while (!ca.done){
+            while (!ca.done) {
                 IF_LEVEL(2) LOG("wait");
-                if(ca.c.wait_until(cal, nextdeadline) == cv_status::timeout){
+                if (ca.c.wait_until(cal, nextdeadline) == cv_status::timeout) {
                     IF_LEVEL(2) LOG("timeout");
                     break;
                 }
             }
-            if(ca.done){
+            if (ca.done) {
                 IF_LEVEL(2) LOG("reply received");
                 break;
             }
         }
 
-        if(retrans_ && (!ch || ch->isdead())) {
+        if (nextdeadline >= finaldeadline)
+            break;
+
+        if (retrans_ && (!ch || ch->isdead())) {
             // since connection is dead, retransmit
             // on the new connection
             transmit = true;
         }
-        curr_to *= 2;
     }
 
     {
@@ -233,9 +228,8 @@ int rpcc::call1(proc_t proc, marshall &req, string &rep, milliseconds to) {
         // I don't think there's any harm in maybe doing it twice
         update_xid_rep(ca.xid);
 
-        if(destroy_wait_){
-          destroy_wait_c_.notify_one();
-        }
+        if (destroy_wait_)
+            destroy_wait_c_.notify_one();
     }
 
     if (ca.done && lossytest_)
@@ -256,15 +250,13 @@ int rpcc::call1(proc_t proc, marshall &req, string &rep, milliseconds to) {
                     ntoh(dst_.sin_port) << " done? " << ca.done << " ret " << ca.intret);
 
     // destruction of req automatically frees its buffer
-    return (ca.done? ca.intret : rpc_const::timeout_failure);
+    return (ca.done? ca.intret : rpc_protocol::timeout_failure);
 }
 
-void
-rpcc::get_refconn(shared_ptr<connection> & ch)
-{
+void rpcc::get_refconn(shared_ptr<connection> & ch) {
     lock ml(chan_m_);
     if (!chan_ || chan_->isdead())
-        chan_ = connect_to_dst(dst_, this, lossytest_);
+        chan_ = connection::to_dst(dst_, this, lossytest_);
 
     if (chan_)
         ch = chan_;
@@ -279,10 +271,10 @@ bool
 rpcc::got_pdu(const shared_ptr<connection> &, const string & b)
 {
     unmarshall rep(b, true);
-    reply_header h;
+    rpc_protocol::reply_header h;
     rep.unpack_header(h);
 
-    if(!rep.ok()){
+    if (!rep.ok()) {
         IF_LEVEL(1) LOG("unmarshall header failed!!!");
         return true;
     }
@@ -291,17 +283,17 @@ rpcc::got_pdu(const shared_ptr<connection> &, const string & b)
 
     update_xid_rep(h.xid);
 
-    if(calls_.find(h.xid) == calls_.end()){
+    if (calls_.find(h.xid) == calls_.end()) {
         IF_LEVEL(2) LOG("xid " << h.xid << " no pending request");
         return true;
     }
     caller *ca = calls_[h.xid];
 
     lock cl(ca->m);
-    if(!ca->done){
+    if (!ca->done) {
         *ca->rep = b;
         ca->intret = h.ret;
-        if(ca->intret < 0){
+        if (ca->intret < 0) {
             IF_LEVEL(2) LOG("RPC reply error for xid " << h.xid << " intret " << ca->intret);
         }
         ca->done = 1;
@@ -314,12 +306,11 @@ rpcc::got_pdu(const shared_ptr<connection> &, const string & b)
 void
 rpcc::update_xid_rep(int xid)
 {
-    if(xid <= xid_rep_window_.front()){
+    if (xid <= xid_rep_window_.front())
         return;
-    }
 
-    for (auto it = xid_rep_window_.begin(); it != xid_rep_window_.end(); it++){
-        if(*it > xid){
+    for (auto it = xid_rep_window_.begin(); it != xid_rep_window_.end(); it++) {
+        if (*it > xid) {
             xid_rep_window_.insert(it, xid);
             goto compress;
         }
@@ -328,7 +319,7 @@ rpcc::update_xid_rep(int xid)
 
 compress:
     auto it = xid_rep_window_.begin();
-    for (it++; it != xid_rep_window_.end(); it++){
+    for (it++; it != xid_rep_window_.end(); it++) {
         while (xid_rep_window_.front() + 1 == *it)
             xid_rep_window_.pop_front();
     }
@@ -341,8 +332,8 @@ rpcs::rpcs(in_port_t p1, size_t count)
     nonce_ = (unsigned int)random();
     IF_LEVEL(2) LOG("created with nonce " << nonce_);
 
-    reg(rpc_const::bind, &rpcs::rpcbind, this);
-    dispatchpool_ = unique_ptr<ThrPool>(new ThrPool(6, false));
+    reg(rpc_protocol::bind, &rpcs::rpcbind, this);
+    dispatchpool_ = unique_ptr<thread_pool>(new thread_pool(6, false));
 }
 
 void rpcs::start() {
@@ -350,18 +341,15 @@ void rpcs::start() {
     listener_ = unique_ptr<tcpsconn>(new tcpsconn(this, port_, loss_env ? atoi(loss_env) : 0));
 }
 
-rpcs::~rpcs()
-{
+rpcs::~rpcs() {
     // must delete listener before dispatchpool
     listener_ = nullptr;
     dispatchpool_ = nullptr;
     free_reply_window();
 }
 
-bool
-rpcs::got_pdu(const shared_ptr<connection> & c, const string & b)
-{
-    if(!reachable_){
+bool rpcs::got_pdu(const shared_ptr<connection> & c, const string & b) {
+    if (!reachable_) {
         IF_LEVEL(1) LOG("not reachable");
         return true;
     }
@@ -369,22 +357,18 @@ rpcs::got_pdu(const shared_ptr<connection> & c, const string & b)
     return dispatchpool_->addJob(bind(&rpcs::dispatch, this, c, b));
 }
 
-void
-rpcs::reg1(proc_t proc, handler *h)
-{
+void rpcs::reg1(proc_id_t proc, handler *h) {
     lock pl(procs_m_);
     VERIFY(procs_.count(proc) == 0);
     procs_[proc] = h;
     VERIFY(procs_.count(proc) >= 1);
 }
 
-void
-rpcs::updatestat(proc_t proc)
-{
+void rpcs::updatestat(proc_id_t proc) {
     lock cl(count_m_);
     counts_[proc]++;
     curr_counts_--;
-    if(curr_counts_ == 0){
+    if (curr_counts_ == 0) {
         LOG("RPC STATS: ");
         for (auto i = counts_.begin(); i != counts_.end(); i++)
             LOG(hex << i->first << ":" << dec << i->second);
@@ -394,7 +378,7 @@ rpcs::updatestat(proc_t proc)
         size_t totalrep = 0, maxrep = 0;
         for (auto clt : reply_window_) {
             totalrep += clt.second.size();
-            if(clt.second.size() > maxrep)
+            if (clt.second.size() > maxrep)
                 maxrep = clt.second.size();
         }
         IF_LEVEL(1) LOG("REPLY WINDOW: clients " << (reply_window_.size()-1) << " total reply " <<
@@ -406,9 +390,9 @@ rpcs::updatestat(proc_t proc)
 void rpcs::dispatch(shared_ptr<connection> c, const string & buf) {
     unmarshall req(buf, true);
 
-    request_header h;
+    rpc_protocol::request_header h;
     req.unpack_header(h);
-    proc_t proc = h.proc;
+    proc_id_t proc = h.proc;
 
     if (!req.ok()) {
         IF_LEVEL(1) LOG("unmarshall header failed");
@@ -419,13 +403,13 @@ void rpcs::dispatch(shared_ptr<connection> c, const string & buf) {
                     dec << h.xid_rep << ") from clt " << h.clt_nonce << " for srv instance " << h.srv_nonce);
 
     marshall rep;
-    reply_header rh{h.xid,0};
+    rpc_protocol::reply_header rh{h.xid,0};
 
     // is client sending to an old instance of server?
-    if(h.srv_nonce != 0 && h.srv_nonce != nonce_){
+    if (h.srv_nonce != 0 && h.srv_nonce != nonce_) {
         IF_LEVEL(2) LOG("rpc for an old server instance " << h.srv_nonce <<
                         " (current " << nonce_ << ") proc " << hex << h.proc);
-        rh.ret = rpc_const::oldsrv_failure;
+        rh.ret = rpc_protocol::oldsrv_failure;
         rep.pack_header(rh);
         c->send(rep);
         return;
@@ -435,7 +419,7 @@ void rpcs::dispatch(shared_ptr<connection> c, const string & buf) {
     // is RPC proc a registered procedure?
     {
         lock pl(procs_m_);
-        if(procs_.count(proc) < 1){
+        if (procs_.count(proc) < 1) {
             LOG("unknown proc 0x" << hex << proc << " with h.srv_nonce=" << h.srv_nonce << ", my srv_nonce=" << nonce_);
             VERIFY(0);
             return;
@@ -447,12 +431,12 @@ void rpcs::dispatch(shared_ptr<connection> c, const string & buf) {
     rpcs::rpcstate_t stat;
     string b1;
 
-    if(h.clt_nonce){
+    if (h.clt_nonce) {
         // have i seen this client before?
         {
             lock rwl(reply_window_m_);
             // if we don't know about this clt_nonce, create a cleanup object
-            if(reply_window_.find(h.clt_nonce) == reply_window_.end()){
+            if (reply_window_.find(h.clt_nonce) == reply_window_.end()) {
                 VERIFY (reply_window_[h.clt_nonce].size() == 0); // create
                 reply_window_[h.clt_nonce].push_back(reply_t(-1)); // store starting reply xid
                 IF_LEVEL(2) LOG("new client " << h.clt_nonce << " xid " << h.xid <<
@@ -465,7 +449,7 @@ void rpcs::dispatch(shared_ptr<connection> c, const string & buf) {
             lock rwl(conns_m_);
             if (conns_.find(h.clt_nonce) == conns_.end())
                 conns_[h.clt_nonce] = c;
-            else if(conns_[h.clt_nonce]->create_time() < c->create_time())
+            else if (conns_[h.clt_nonce]->create_time() < c->create_time())
                 conns_[h.clt_nonce] = c;
         }
 
@@ -481,10 +465,10 @@ void rpcs::dispatch(shared_ptr<connection> c, const string & buf) {
                 updatestat(proc);
 
             rh.ret = (*f)(req, rep);
-            if (rh.ret == rpc_const::unmarshal_args_failure) {
-                cerr << "failed to unmarshall the arguments. You are " <<
-                        "probably calling RPC 0x" << hex << proc << " with the wrong " <<
-                        "types of arguments." << endl;
+            if (rh.ret == rpc_protocol::unmarshal_args_failure) {
+                LOG("failed to unmarshall the arguments. You are " <<
+                    "probably calling RPC 0x" << hex << proc << " with the wrong " <<
+                    "types of arguments.");
                 VERIFY(0);
             }
             VERIFY(rh.ret >= 0);
@@ -516,7 +500,7 @@ void rpcs::dispatch(shared_ptr<connection> c, const string & buf) {
             break;
         case FORGOTTEN: // very old request and we don't have the response anymore
             IF_LEVEL(2) LOG("very old request " << h.xid << " from " << h.clt_nonce);
-            rh.ret = rpc_const::atmostonce_failure;
+            rh.ret = rpc_protocol::atmostonce_failure;
             rep.pack_header(rh);
             c->send(rep);
             break;
@@ -596,7 +580,7 @@ void rpcs::add_reply(unsigned int clt_nonce, int xid, const string & b) {
     for (it++; it != l.end() && it->xid < xid; it++);
     // there should already be an entry, so whine if there isn't
     if (it == l.end() || it->xid != xid) {
-        cerr << "Could not find reply struct in add_reply" << endl;
+        LOG("Could not find reply struct in add_reply");
         l.insert(it, reply_t(xid, b));
     } else {
         *it = reply_t(xid, b);
@@ -614,30 +598,27 @@ int rpcs::rpcbind(unsigned int &r, int) {
     return 0;
 }
 
-static sockaddr_in make_sockaddr(const string &host, const string &port);
-
 static sockaddr_in make_sockaddr(const string &hostandport) {
+    string host = "127.0.0.1";
+    string port = hostandport;
     auto colon = hostandport.find(':');
-    if (colon == string::npos)
-        return make_sockaddr("127.0.0.1", hostandport);
-    else
-        return make_sockaddr(hostandport.substr(0, colon), hostandport.substr(colon+1));
-}
+    if (colon != string::npos) {
+        host = hostandport.substr(0, colon);
+        port = hostandport.substr(colon+1);
+    }
 
-static sockaddr_in make_sockaddr(const string &host, const string &port) {
-    sockaddr_in dst;
-    bzero(&dst, sizeof(dst));
+    sockaddr_in dst{}; // zero initialize
     dst.sin_family = AF_INET;
 
     struct in_addr a{inet_addr(host.c_str())};
 
-    if(a.s_addr != INADDR_NONE)
+    if (a.s_addr != INADDR_NONE)
         dst.sin_addr.s_addr = a.s_addr;
     else {
         struct hostent *hp = gethostbyname(host.c_str());
 
         if (!hp || hp->h_length != 4 || hp->h_addrtype != AF_INET) {
-            cerr << "cannot find host name " << host << endl;
+            LOG_NONMEMBER("cannot find host name " << host);
             exit(1);
         }
         memcpy(&a, hp->h_addr_list[0], sizeof(in_addr_t));