| // Copyright 2019 AT&T Intellectual Property |
| // Copyright 2019 Nokia |
| // |
| // Licensed under the Apache License, Version 2.0 (the "License"); |
| // you may not use this file except in compliance with the License. |
| // You may obtain a copy of the License at |
| // |
| // http://www.apache.org/licenses/LICENSE-2.0 |
| // |
| // Unless required by applicable law or agreed to in writing, software |
| // distributed under the License is distributed on an "AS IS" BASIS, |
| // WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied. |
| // See the License for the specific language governing permissions and |
| // limitations under the License. |
| |
| // This source code is part of the near-RT RIC (RAN Intelligent Controller) |
| // platform project (RICP). |
| |
| // TODO: High-level file comment. |
| |
| |
| |
| #include "sctpThread.h" |
| #include "BuildRunName.h" |
| |
| using namespace std; |
| //using namespace std::placeholders; |
| using namespace boost::filesystem; |
| |
| #ifdef __TRACING__ |
| using namespace opentracing; |
| #endif |
| //#ifdef __cplusplus |
| //extern "C" |
| //{ |
| //#endif |
| |
| // need to expose without the include of gcov |
| extern "C" void __gcov_flush(void); |
| |
| static void catch_function(int signal) { |
| __gcov_flush(); |
| exit(signal); |
| } |
| |
| |
| BOOST_LOG_INLINE_GLOBAL_LOGGER_DEFAULT(my_logger, src::logger_mt) |
| |
| boost::shared_ptr<sinks::synchronous_sink<sinks::text_file_backend>> boostLogger; |
| double cpuClock = 0.0; |
| bool jsonTrace = true; |
| |
| void init_log() { |
| mdclog_attr_t *attr; |
| mdclog_attr_init(&attr); |
| mdclog_attr_set_ident(attr, "E2Terminator"); |
| mdclog_init(attr); |
| mdclog_attr_destroy(attr); |
| } |
| auto start_time = std::chrono::high_resolution_clock::now(); |
| typedef std::chrono::duration<double, std::ratio<1,1>> seconds_t; |
| |
| double age() { |
| return seconds_t(std::chrono::high_resolution_clock::now() - start_time).count(); |
| } |
| |
| double approx_CPU_MHz(unsigned sleeptime) { |
| using namespace std::chrono_literals; |
| uint32_t aux = 0; |
| uint64_t cycles_start = rdtscp(aux); |
| double time_start = age(); |
| std::this_thread::sleep_for(sleeptime * 1ms); |
| uint64_t elapsed_cycles = rdtscp(aux) - cycles_start; |
| double elapsed_time = age() - time_start; |
| return elapsed_cycles / elapsed_time; |
| } |
| |
| //std::atomic<int64_t> rmrCounter{0}; |
| std::atomic<int64_t> num_of_messages{0}; |
| std::atomic<int64_t> num_of_XAPP_messages{0}; |
| static long transactionCounter = 0; |
| |
| int buildListeningPort(sctp_params_t &sctpParams) { |
| sctpParams.listenFD = socket (AF_INET6, SOCK_STREAM, IPPROTO_SCTP); |
| struct sockaddr_in6 servaddr {}; |
| servaddr.sin6_family = AF_INET6; |
| servaddr.sin6_addr = in6addr_any; |
| servaddr.sin6_port = htons(SRC_PORT); |
| if (bind(sctpParams.listenFD, (SA *)&servaddr, sizeof(servaddr)) < 0 ) { |
| mdclog_write(MDCLOG_ERR, "Error binding. %s\n", strerror(errno)); |
| return -1; |
| } |
| if (setSocketNoBlocking(sctpParams.listenFD) == -1) { |
| //mdclog_write(MDCLOG_ERR, "Error binding. %s", strerror(errno)); |
| return -1; |
| } |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| struct sockaddr_in6 cliaddr {}; |
| socklen_t len = sizeof(cliaddr); |
| getsockname(sctpParams.listenFD, (SA *)&cliaddr, &len); |
| char buff[1024] {}; |
| inet_ntop(AF_INET6, &cliaddr.sin6_addr, buff, sizeof(buff)); |
| mdclog_write(MDCLOG_DEBUG, "My address: %s, port %d\n", buff, htons(cliaddr.sin6_port)); |
| } |
| |
| if (listen(sctpParams.listenFD, SOMAXCONN) < 0) { |
| mdclog_write(MDCLOG_ERR, "Error listening. %s\n", strerror(errno)); |
| return -1; |
| } |
| struct epoll_event event {}; |
| event.events = EPOLLIN | EPOLLET; |
| event.data.fd = sctpParams.listenFD; |
| |
| // add listening port to epoll |
| if (epoll_ctl(sctpParams.epoll_fd, EPOLL_CTL_ADD, sctpParams.listenFD, &event)) { |
| printf("Failed to add descriptor to epoll\n"); |
| mdclog_write(MDCLOG_ERR, "Failed to add descriptor to epoll. %s\n", strerror(errno)); |
| return -1; |
| } |
| |
| return 0; |
| } |
| |
| int buildConfiguration(sctp_params_t &sctpParams) { |
| path p = (sctpParams.configFilePath + "/" + sctpParams.configFileName).c_str(); |
| if (exists(p)) { |
| const int size = 2048; |
| auto fileSize = file_size(p); |
| if (fileSize > size) { |
| mdclog_write(MDCLOG_ERR, "File %s larger than %d", p.string().c_str(), size); |
| return -1; |
| } |
| } else { |
| mdclog_write(MDCLOG_ERR, "Configuration File %s not exists", p.string().c_str()); |
| return -1; |
| } |
| |
| ReadConfigFile conf; |
| if (conf.openConfigFile(p.string()) == -1) { |
| mdclog_write(MDCLOG_ERR, "Filed to open config file %s, %s", |
| p.string().c_str(), strerror(errno)); |
| return -1; |
| } |
| int rmrPort = conf.getIntValue("nano"); |
| if (rmrPort == -1) { |
| mdclog_write(MDCLOG_ERR, "illigal RMR port "); |
| return -1; |
| } |
| sctpParams.rmrPort = (uint16_t)rmrPort; |
| snprintf(sctpParams.rmrAddress, sizeof(sctpParams.rmrAddress), "%d", (int) (sctpParams.rmrPort)); |
| |
| auto tmpStr = conf.getStringValue("loglevel"); |
| if (tmpStr.length() == 0) { |
| mdclog_write(MDCLOG_ERR, "illigal loglevel. Set loglevel to MDCLOG_INFO"); |
| tmpStr = "info"; |
| } |
| transform(tmpStr.begin(), tmpStr.end(), tmpStr.begin(), ::tolower); |
| |
| if ((tmpStr.compare("debug")) == 0) { |
| sctpParams.logLevel = MDCLOG_DEBUG; |
| } else if ((tmpStr.compare("info")) == 0) { |
| sctpParams.logLevel = MDCLOG_INFO; |
| } else if ((tmpStr.compare("warning")) == 0) { |
| sctpParams.logLevel = MDCLOG_WARN; |
| } else if ((tmpStr.compare("error")) == 0) { |
| sctpParams.logLevel = MDCLOG_ERR; |
| } else { |
| mdclog_write(MDCLOG_ERR, "illigal loglevel = %s. Set loglevel to MDCLOG_INFO", tmpStr.c_str()); |
| sctpParams.logLevel = MDCLOG_INFO; |
| } |
| mdclog_level_set(sctpParams.logLevel); |
| |
| tmpStr = conf.getStringValue("volume"); |
| if (tmpStr.length() == 0) { |
| mdclog_write(MDCLOG_ERR, "illigal volume."); |
| return -1; |
| } |
| |
| char tmpLogFilespec[VOLUME_URL_SIZE]; |
| tmpLogFilespec[0] = 0; |
| sctpParams.volume[0] = 0; |
| snprintf(sctpParams.volume, VOLUME_URL_SIZE, "%s", tmpStr.c_str()); |
| // copy the name to temp file as well |
| snprintf(tmpLogFilespec, VOLUME_URL_SIZE, "%s", tmpStr.c_str()); |
| |
| |
| // define the file name in the tmp directory under the volume |
| strcat(tmpLogFilespec,"/tmp/E2Term_%Y-%m-%d_%H-%M-%S.%N.tmpStr"); |
| |
| // std::string localIP = conf.getStringValue("local-ip"); |
| // if (localIP.length() == 0) { |
| // mdclog_write(MDCLOG_ERR, "illigal local-ip. environment variable"); |
| // exit(-1); |
| // } |
| |
| //sctpParams.myIP.assign(getenv(localIP.c_str())); |
| sctpParams.myIP = conf.getStringValue("local-ip"); |
| if (sctpParams.myIP.length() == 0) { |
| mdclog_write(MDCLOG_ERR, "illigal local-ip."); |
| return -1; |
| } |
| |
| sctpParams.fqdn = conf.getStringValue("external-fqdn"); |
| if (sctpParams.fqdn.length() == 0) { |
| mdclog_write(MDCLOG_ERR, "illigal external-fqdn"); |
| return -1; |
| } |
| |
| std::string pod = conf.getStringValue("pod_name"); |
| if (pod.length() == 0) { |
| mdclog_write(MDCLOG_ERR, "illigal pod_name in config file"); |
| return -1; |
| } |
| auto *podName = getenv(pod.c_str()); |
| if (podName == nullptr) { |
| mdclog_write(MDCLOG_ERR, "illigal pod_name or environment varible not exists : %s", pod.c_str()); |
| return -1; |
| |
| } else { |
| sctpParams.podName.assign(podName); |
| if (sctpParams.podName.length() == 0) { |
| mdclog_write(MDCLOG_ERR, "illigal pod_name"); |
| return -1; |
| } |
| } |
| |
| tmpStr = conf.getStringValue("trace"); |
| transform(tmpStr.begin(), tmpStr.end(), tmpStr.begin(), ::tolower); |
| if ((tmpStr.compare("start")) == 0) { |
| mdclog_write(MDCLOG_INFO, "Trace set to: start"); |
| sctpParams.trace = true; |
| } else if ((tmpStr.compare("stop")) == 0) { |
| mdclog_write(MDCLOG_INFO, "Trace set to: stop"); |
| sctpParams.trace = false; |
| } |
| jsonTrace = sctpParams.trace; |
| |
| sctpParams.ka_message_length = snprintf(sctpParams.ka_message, 4096, "{\"address\": \"%s:%d\"," |
| "\"fqdn\": \"%s\"," |
| "\"pod_name\": \"%s\"}", |
| (const char *)sctpParams.myIP.c_str(), |
| sctpParams.rmrPort, |
| sctpParams.fqdn.c_str(), |
| sctpParams.podName.c_str()); |
| |
| if (mdclog_level_get() >= MDCLOG_INFO) { |
| mdclog_mdc_add("RMR Port", to_string(sctpParams.rmrPort).c_str()); |
| mdclog_mdc_add("LogLevel", to_string(sctpParams.logLevel).c_str()); |
| mdclog_mdc_add("volume", sctpParams.volume); |
| mdclog_mdc_add("tmpLogFilespec", tmpLogFilespec); |
| mdclog_mdc_add("my ip", sctpParams.myIP.c_str()); |
| mdclog_mdc_add("pod name", sctpParams.podName.c_str()); |
| |
| mdclog_write(MDCLOG_INFO, "running parameters for instance : %s", sctpParams.ka_message); |
| } |
| mdclog_mdc_clean(); |
| |
| // Files written to the current working directory |
| boostLogger = logging::add_file_log( |
| keywords::file_name = tmpLogFilespec, // to temp directory |
| keywords::rotation_size = 10 * 1024 * 1024, |
| keywords::time_based_rotation = sinks::file::rotation_at_time_interval(posix_time::hours(1)), |
| keywords::format = "%Message%" |
| //keywords::format = "[%TimeStamp%]: %Message%" // use each tmpStr with time stamp |
| ); |
| |
| // Setup a destination folder for collecting rotated (closed) files --since the same volumn can use rename() |
| boostLogger->locked_backend()->set_file_collector(sinks::file::make_collector( |
| keywords::target = sctpParams.volume |
| )); |
| |
| // Upon restart, scan the directory for files matching the file_name pattern |
| boostLogger->locked_backend()->scan_for_files(); |
| |
| // Enable auto-flushing after each tmpStr record written |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| boostLogger->locked_backend()->auto_flush(true); |
| } |
| |
| return 0; |
| } |
| |
| int main(const int argc, char **argv) { |
| sctp_params_t sctpParams; |
| |
| #ifdef __TRACING__ |
| opentracing::Tracer::InitGlobal(tracelibcpp::createTracer("E2 Terminator")); |
| auto span = opentracing::Tracer::Global()->StartSpan(__FUNCTION__); |
| #else |
| otSpan span = 0; |
| #endif |
| |
| { |
| std::random_device device{}; |
| std::mt19937 generator(device()); |
| std::uniform_int_distribution<long> distribution(1, (long) 1e12); |
| transactionCounter = distribution(generator); |
| } |
| |
| // uint64_t st = 0; |
| // uint32_t aux1 = 0; |
| // st = rdtscp(aux1); |
| |
| unsigned num_cpus = std::thread::hardware_concurrency(); |
| init_log(); |
| mdclog_level_set(MDCLOG_INFO); |
| |
| if (std::signal(SIGINT, catch_function) == SIG_ERR) { |
| mdclog_write(MDCLOG_ERR, "Error initializing SIGINT"); |
| exit(1); |
| } |
| if (std::signal(SIGABRT, catch_function)== SIG_ERR) { |
| mdclog_write(MDCLOG_ERR, "Error initializing SIGABRT"); |
| exit(1); |
| } |
| if (std::signal(SIGTERM, catch_function)== SIG_ERR) { |
| mdclog_write(MDCLOG_ERR, "Error initializing SIGTERM"); |
| exit(1); |
| } |
| |
| cpuClock = approx_CPU_MHz(100); |
| |
| mdclog_write(MDCLOG_DEBUG, "CPU speed %11.11f", cpuClock); |
| |
| auto result = parse(argc, argv, sctpParams); |
| |
| if (buildConfiguration(sctpParams) != 0) { |
| exit(-1); |
| } |
| |
| // start epoll |
| sctpParams.epoll_fd = epoll_create1(0); |
| if (sctpParams.epoll_fd == -1) { |
| mdclog_write(MDCLOG_ERR, "failed to open epoll descriptor"); |
| exit(-1); |
| } |
| |
| getRmrContext(sctpParams, &span); |
| if (sctpParams.rmrCtx == nullptr) { |
| close(sctpParams.epoll_fd); |
| exit(-1); |
| } |
| |
| if (buildInotify(sctpParams) == -1) { |
| close(sctpParams.rmrListenFd); |
| rmr_close(sctpParams.rmrCtx); |
| close(sctpParams.epoll_fd); |
| exit(-1); |
| } |
| |
| if (buildListeningPort(sctpParams) != 0) { |
| close(sctpParams.rmrListenFd); |
| rmr_close(sctpParams.rmrCtx); |
| close(sctpParams.epoll_fd); |
| exit(-1); |
| } |
| |
| sctpParams.sctpMap = new mapWrapper(); |
| |
| std::vector<std::thread> threads(num_cpus); |
| // std::vector<std::thread> threads; |
| |
| num_cpus = 1; |
| for (unsigned int i = 0; i < num_cpus; i++) { |
| threads[i] = std::thread(listener, &sctpParams); |
| |
| cpu_set_t cpuset; |
| CPU_ZERO(&cpuset); |
| CPU_SET(i, &cpuset); |
| int rc = pthread_setaffinity_np(threads[i].native_handle(), sizeof(cpu_set_t), &cpuset); |
| if (rc != 0) { |
| mdclog_write(MDCLOG_ERR, "Error calling pthread_setaffinity_np: %d", rc); |
| } |
| } |
| |
| //loop over term_init until first message from xApp |
| handleTermInit(sctpParams); |
| |
| for (auto &t : threads) { |
| t.join(); |
| } |
| |
| #ifdef __TRACING__ |
| opentracing::Tracer::Global()->Close(); |
| #endif |
| return 0; |
| } |
| |
| void handleTermInit(sctp_params_t &sctpParams) { |
| sendTermInit(sctpParams); |
| //send to e2 manager init of e2 term |
| //E2_TERM_INIT |
| |
| int count = 0; |
| while (true) { |
| auto xappMessages = num_of_XAPP_messages.load(std::memory_order_acquire); |
| if (xappMessages > 0) { |
| if (mdclog_level_get() >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "Got a message from some appliction, stop sending E2_TERM_INIT"); |
| } |
| return; |
| } |
| usleep(100000); |
| count++; |
| if (count % 1000 == 0) { |
| mdclog_write(MDCLOG_ERR, "GOT No messages from any xApp"); |
| sendTermInit(sctpParams); |
| } |
| } |
| } |
| |
| void sendTermInit(sctp_params_t &sctpParams) { |
| rmr_mbuf_t *msg = rmr_alloc_msg(sctpParams.rmrCtx, sctpParams.ka_message_length); |
| auto count = 0; |
| while (true) { |
| msg->mtype = E2_TERM_INIT; |
| msg->state = 0; |
| rmr_bytes2payload(msg, (unsigned char *)sctpParams.ka_message, sctpParams.ka_message_length); |
| static unsigned char tx[32]; |
| auto txLen = snprintf((char *) tx, sizeof tx, "%15ld", transactionCounter++); |
| rmr_bytes2xact(msg, tx, txLen); |
| msg = rmr_send_msg(sctpParams.rmrCtx, msg); |
| if (msg == nullptr) { |
| msg = rmr_alloc_msg(sctpParams.rmrCtx, sctpParams.myIP.length()); |
| } else if (msg->state == 0) { |
| rmr_free_msg(msg); |
| if (mdclog_level_get() >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "E2_TERM_INIT succsesfuly sent "); |
| } |
| return; |
| } else { |
| if (count % 100 == 0) { |
| mdclog_write(MDCLOG_ERR, "Error sending E2_TERM_INIT cause : %d ", msg->state); |
| } |
| sleep(1); |
| } |
| count++; |
| } |
| } |
| |
| /** |
| * |
| * @param argc |
| * @param argv |
| * @param sctpParams |
| * @return |
| */ |
| cxxopts::ParseResult parse(int argc, char *argv[], sctp_params_t &sctpParams) { |
| cxxopts::Options options(argv[0], "e2 term help"); |
| options.positional_help("[optional args]").show_positional_help(); |
| options.allow_unrecognised_options().add_options() |
| ("p,path", "config file path", cxxopts::value<std::string>(sctpParams.configFilePath)->default_value("config")) |
| ("f,file", "config file name", cxxopts::value<std::string>(sctpParams.configFileName)->default_value("config.conf")) |
| ("h,help", "Print help"); |
| |
| auto result = options.parse(argc, argv); |
| |
| if (result.count("help")) { |
| std::cout << options.help({""}) << std::endl; |
| exit(0); |
| } |
| return result; |
| } |
| |
| /** |
| * |
| * @param sctpParams |
| * @return -1 failed 0 success |
| */ |
| int buildInotify(sctp_params_t &sctpParams) { |
| sctpParams.inotifyFD = inotify_init1(IN_NONBLOCK); |
| if (sctpParams.inotifyFD == -1) { |
| mdclog_write(MDCLOG_ERR, "Failed to init inotify (inotify_init1) %s", strerror(errno)); |
| close(sctpParams.rmrListenFd); |
| rmr_close(sctpParams.rmrCtx); |
| close(sctpParams.epoll_fd); |
| return -1; |
| } |
| |
| sctpParams.inotifyWD = inotify_add_watch(sctpParams.inotifyFD, |
| (const char *)sctpParams.configFilePath.c_str(), |
| (unsigned)IN_OPEN | (unsigned)IN_CLOSE_WRITE | (unsigned)IN_CLOSE_NOWRITE); //IN_CLOSE = (IN_CLOSE_WRITE | IN_CLOSE_NOWRITE) |
| if (sctpParams.inotifyWD == -1) { |
| mdclog_write(MDCLOG_ERR, "Failed to add directory : %s to inotify (inotify_add_watch) %s", |
| sctpParams.configFilePath.c_str(), |
| strerror(errno)); |
| close(sctpParams.inotifyFD); |
| return -1; |
| } |
| |
| struct epoll_event event{}; |
| event.events = (EPOLLIN); |
| event.data.fd = sctpParams.inotifyFD; |
| // add listening RMR FD to epoll |
| if (epoll_ctl(sctpParams.epoll_fd, EPOLL_CTL_ADD, sctpParams.inotifyFD, &event)) { |
| mdclog_write(MDCLOG_ERR, "Failed to add inotify FD to epoll"); |
| close(sctpParams.inotifyFD); |
| return -1; |
| } |
| return 0; |
| } |
| |
| /** |
| * |
| * @param args |
| * @return |
| */ |
| void listener(sctp_params_t *params) { |
| #ifdef __TRACING__ |
| auto span = opentracing::Tracer::Global()->StartSpan(__FUNCTION__); |
| #else |
| otSpan span = 0; |
| #endif |
| int num_of_SCTP_messages = 0; |
| auto totalTime = 0.0; |
| mdclog_mdc_clean(); |
| mdclog_level_set(params->logLevel); |
| |
| std::thread::id this_id = std::this_thread::get_id(); |
| //save cout |
| streambuf *oldCout = cout.rdbuf(); |
| ostringstream memCout; |
| // create new cout |
| cout.rdbuf(memCout.rdbuf()); |
| cout << this_id; |
| //return to the normal cout |
| cout.rdbuf(oldCout); |
| |
| char tid[32]; |
| memcpy(tid, memCout.str().c_str(), memCout.str().length() < 32 ? memCout.str().length() : 31); |
| tid[memCout.str().length()] = 0; |
| mdclog_mdc_add("thread id", tid); |
| |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "started thread number %s", tid); |
| } |
| |
| |
| RmrMessagesBuffer_t rmrMessageBuffer{}; |
| //create and init RMR |
| rmrMessageBuffer.rmrCtx = params->rmrCtx; |
| |
| auto *events = (struct epoll_event *) calloc(MAXEVENTS, sizeof(struct epoll_event)); |
| struct timespec end{0, 0}; |
| struct timespec start{0, 0}; |
| |
| rmrMessageBuffer.rcvMessage = rmr_alloc_msg(rmrMessageBuffer.rmrCtx, RECEIVE_XAPP_BUFFER_SIZE); |
| rmrMessageBuffer.sendMessage = rmr_alloc_msg(rmrMessageBuffer.rmrCtx, RECEIVE_XAPP_BUFFER_SIZE); |
| |
| memcpy(rmrMessageBuffer.ka_message, params->ka_message, params->ka_message_length); |
| rmrMessageBuffer.ka_message_len = params->ka_message_length; |
| rmrMessageBuffer.ka_message[rmrMessageBuffer.ka_message_len] = 0; |
| |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "keep alive message is : %s", rmrMessageBuffer.ka_message); |
| } |
| |
| ReportingMessages_t message {}; |
| |
| for (int i = 0; i < MAX_RMR_BUFF_ARRY; i++) { |
| rmrMessageBuffer.rcvBufferedMessages[i] = rmr_alloc_msg(rmrMessageBuffer.rmrCtx, RECEIVE_XAPP_BUFFER_SIZE); |
| rmrMessageBuffer.sendBufferedMessages[i] = rmr_alloc_msg(rmrMessageBuffer.rmrCtx, RECEIVE_XAPP_BUFFER_SIZE); |
| } |
| |
| while (true) { |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Start EPOLL Wait"); |
| } |
| auto numOfEvents = epoll_wait(params->epoll_fd, events, MAXEVENTS, -1); |
| if (numOfEvents < 0 && errno == EINTR) { |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "got EINTR : %s", strerror(errno)); |
| } |
| continue; |
| } |
| if (numOfEvents < 0) { |
| mdclog_write(MDCLOG_ERR, "Epoll wait failed, errno = %s", strerror(errno)); |
| return; |
| } |
| for (auto i = 0; i < numOfEvents; i++) { |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "handling epoll event %d out of %d", i + 1, numOfEvents); |
| } |
| clock_gettime(CLOCK_MONOTONIC, &message.message.time); |
| start.tv_sec = message.message.time.tv_sec; |
| start.tv_nsec = message.message.time.tv_nsec; |
| |
| |
| if ((events[i].events & EPOLLERR) || (events[i].events & EPOLLHUP)) { |
| handlepoll_error(events[i], message, rmrMessageBuffer, params, &span); |
| } else if (events[i].events & EPOLLOUT) { |
| handleEinprogressMessages(events[i], message, rmrMessageBuffer, params, &span); |
| } else if (params->listenFD == events[i].data.fd) { |
| // new connection is requested from RAN start build connection |
| while (true) { |
| struct sockaddr in_addr {}; |
| socklen_t in_len; |
| char hostBuff[NI_MAXHOST]; |
| char portBuff[NI_MAXSERV]; |
| |
| in_len = sizeof(in_addr); |
| auto *peerInfo = (ConnectedCU_t *)calloc(1, sizeof(ConnectedCU_t)); |
| peerInfo->sctpParams = params; |
| peerInfo->fileDescriptor = accept(params->listenFD, &in_addr, &in_len); |
| if (peerInfo->fileDescriptor == -1) { |
| if ((errno == EAGAIN) || (errno == EWOULDBLOCK)) { |
| /* We have processed all incoming connections. */ |
| break; |
| } else { |
| mdclog_write(MDCLOG_ERR, "Accept error, errno = %s", strerror(errno)); |
| break; |
| } |
| } |
| if (setSocketNoBlocking(peerInfo->fileDescriptor) == -1) { |
| mdclog_write(MDCLOG_ERR, "setSocketNoBlocking failed to set new connection %s on port %s\n", hostBuff, portBuff); |
| close(peerInfo->fileDescriptor); |
| break; |
| } |
| auto ans = getnameinfo(&in_addr, in_len, |
| peerInfo->hostName, NI_MAXHOST, |
| peerInfo->portNumber, NI_MAXSERV, NI_NUMERICHOST | NI_NUMERICSERV); |
| if (ans < 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to get info on connection request. %s\n", strerror(errno)); |
| close(peerInfo->fileDescriptor); |
| break; |
| } |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Accepted connection on descriptor %d (host=%s, port=%s)\n", peerInfo->fileDescriptor, peerInfo->hostName, peerInfo->portNumber); |
| } |
| peerInfo->isConnected = false; |
| peerInfo->gotSetup = false; |
| if (addToEpoll(params->epoll_fd, |
| peerInfo, |
| (EPOLLIN | EPOLLET), |
| params->sctpMap, nullptr, |
| 0, &span) != 0) { |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| break; |
| } |
| } |
| } else if (params->rmrListenFd == events[i].data.fd) { |
| // got message from XAPP |
| num_of_XAPP_messages.fetch_add(1, std::memory_order_release); |
| num_of_messages.fetch_add(1, std::memory_order_release); |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "new message from RMR"); |
| } |
| if (receiveXappMessages(params->epoll_fd, |
| params->sctpMap, |
| rmrMessageBuffer, |
| message.message.time, |
| &span) != 0) { |
| mdclog_write(MDCLOG_ERR, "Error handling Xapp message"); |
| } |
| } else if (params->inotifyFD == events[i].data.fd) { |
| mdclog_write(MDCLOG_INFO, "Got event from inotify (configuration update)"); |
| handleConfigChange(params); |
| } else { |
| /* We RMR_ERR_RETRY have data on the fd waiting to be read. Read and display it. |
| * We must read whatever data is available completely, as we are running |
| * in edge-triggered mode and won't get a notification again for the same data. */ |
| num_of_messages.fetch_add(1, std::memory_order_release); |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "new message from SCTP, epoll flags are : %0x", events[i].events); |
| } |
| receiveDataFromSctp(&events[i], |
| params->sctpMap, |
| num_of_SCTP_messages, |
| rmrMessageBuffer, |
| message.message.time, |
| &span); |
| } |
| |
| clock_gettime(CLOCK_MONOTONIC, &end); |
| if (mdclog_level_get() >= MDCLOG_INFO) { |
| totalTime += ((end.tv_sec + 1.0e-9 * end.tv_nsec) - |
| ((double) start.tv_sec + 1.0e-9 * start.tv_nsec)); |
| } |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "message handling is %ld seconds %ld nanoseconds", |
| end.tv_sec - start.tv_sec, |
| end.tv_nsec - start.tv_nsec); |
| } |
| } |
| } |
| #ifdef __TRACING__ |
| span->Finish(); |
| #else |
| |
| #endif |
| } |
| |
| /** |
| * |
| * @param sctpParams |
| */ |
| void handleConfigChange(sctp_params_t *sctpParams) { |
| char buf[4096] __attribute__ ((aligned(__alignof__(struct inotify_event)))); |
| const struct inotify_event *event; |
| char *ptr; |
| |
| path p = (sctpParams->configFilePath + "/" + sctpParams->configFileName).c_str(); |
| auto endlessLoop = true; |
| while (endlessLoop) { |
| auto len = read(sctpParams->inotifyFD, buf, sizeof buf); |
| if (len == -1) { |
| if (errno != EAGAIN) { |
| mdclog_write(MDCLOG_ERR, "read %s ", strerror(errno)); |
| endlessLoop = false; |
| continue; |
| } |
| else { |
| endlessLoop = false; |
| continue; |
| } |
| } |
| |
| for (ptr = buf; ptr < buf + len; ptr += sizeof(struct inotify_event) + event->len) { |
| event = (const struct inotify_event *)ptr; |
| if (event->mask & (uint32_t)IN_ISDIR) { |
| continue; |
| } |
| |
| // the directory name |
| if (sctpParams->inotifyWD == event->wd) { |
| // not the directory |
| } |
| if (event->len) { |
| if (!(sctpParams->configFileName.compare(event->name))) { |
| continue; |
| } |
| } |
| // only the file we want |
| if (event->mask & (uint32_t)IN_CLOSE_WRITE) { |
| if (exists(p)) { |
| const int size = 2048; |
| auto fileSize = file_size(p); |
| if (fileSize > size) { |
| mdclog_write(MDCLOG_ERR, "File %s larger than %d", p.string().c_str(), size); |
| return; |
| } |
| } else { |
| mdclog_write(MDCLOG_ERR, "Configuration File %s not exists", p.string().c_str()); |
| return; |
| } |
| |
| ReadConfigFile conf; |
| if (conf.openConfigFile(p.string()) == -1) { |
| mdclog_write(MDCLOG_ERR, "Filed to open config file %s, %s", |
| p.string().c_str(), strerror(errno)); |
| return; |
| } |
| |
| auto tmpStr = conf.getStringValue("loglevel"); |
| if (tmpStr.length() == 0) { |
| mdclog_write(MDCLOG_ERR, "illigal loglevel. Set loglevel to MDCLOG_INFO"); |
| tmpStr = "info"; |
| } |
| transform(tmpStr.begin(), tmpStr.end(), tmpStr.begin(), ::tolower); |
| |
| if ((tmpStr.compare("debug")) == 0) { |
| mdclog_write(MDCLOG_INFO, "Log level set to MDCLOG_DEBUG"); |
| sctpParams->logLevel = MDCLOG_DEBUG; |
| } else if ((tmpStr.compare("info")) == 0) { |
| mdclog_write(MDCLOG_INFO, "Log level set to MDCLOG_INFO"); |
| sctpParams->logLevel = MDCLOG_INFO; |
| } else if ((tmpStr.compare("warning")) == 0) { |
| mdclog_write(MDCLOG_INFO, "Log level set to MDCLOG_WARN"); |
| sctpParams->logLevel = MDCLOG_WARN; |
| } else if ((tmpStr.compare("error")) == 0) { |
| mdclog_write(MDCLOG_INFO, "Log level set to MDCLOG_ERR"); |
| sctpParams->logLevel = MDCLOG_ERR; |
| } else { |
| mdclog_write(MDCLOG_ERR, "illigal loglevel = %s. Set loglevel to MDCLOG_INFO", tmpStr.c_str()); |
| sctpParams->logLevel = MDCLOG_INFO; |
| } |
| mdclog_level_set(sctpParams->logLevel); |
| |
| |
| tmpStr = conf.getStringValue("trace"); |
| if (tmpStr.length() == 0) { |
| mdclog_write(MDCLOG_ERR, "illigal trace. Set trace to stop"); |
| tmpStr = "stop"; |
| } |
| |
| transform(tmpStr.begin(), tmpStr.end(), tmpStr.begin(), ::tolower); |
| if ((tmpStr.compare("start")) == 0) { |
| mdclog_write(MDCLOG_INFO, "Trace set to: start"); |
| sctpParams->trace = true; |
| } else if ((tmpStr.compare("stop")) == 0) { |
| mdclog_write(MDCLOG_INFO, "Trace set to: stop"); |
| sctpParams->trace = false; |
| } else { |
| mdclog_write(MDCLOG_ERR, "Trace was set to wrong value %s, set to stop", tmpStr.c_str()); |
| sctpParams->trace = false; |
| } |
| jsonTrace = sctpParams->trace; |
| endlessLoop = false; |
| } |
| } |
| } |
| } |
| |
| /** |
| * |
| * @param event |
| * @param message |
| * @param rmrMessageBuffer |
| * @param params |
| * @param pSpan |
| */ |
| void handleEinprogressMessages(struct epoll_event &event, |
| ReportingMessages_t &message, |
| RmrMessagesBuffer_t &rmrMessageBuffer, |
| sctp_params_t *params, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| auto *peerInfo = (ConnectedCU_t *)event.data.ptr; |
| memcpy(message.message.enodbName, peerInfo->enodbName, sizeof(peerInfo->enodbName)); |
| |
| mdclog_write(MDCLOG_INFO, "file descriptor %d got EPOLLOUT", peerInfo->fileDescriptor); |
| auto retVal = 0; |
| socklen_t retValLen = 0; |
| auto rc = getsockopt(peerInfo->fileDescriptor, SOL_SOCKET, SO_ERROR, &retVal, &retValLen); |
| if (rc != 0 || retVal != 0) { |
| if (rc != 0) { |
| rmrMessageBuffer.sendMessage->len = snprintf((char *)rmrMessageBuffer.sendMessage->payload, 256, |
| "%s|Failed SCTP Connection, after EINPROGRESS the getsockopt%s", |
| peerInfo->enodbName, strerror(errno)); |
| } else if (retVal != 0) { |
| rmrMessageBuffer.sendMessage->len = snprintf((char *)rmrMessageBuffer.sendMessage->payload, 256, |
| "%s|Failed SCTP Connection after EINPROGRESS, SO_ERROR", |
| peerInfo->enodbName); |
| } |
| |
| message.message.asndata = rmrMessageBuffer.sendMessage->payload; |
| message.message.asnLength = rmrMessageBuffer.sendMessage->len; |
| mdclog_write(MDCLOG_ERR, "%s", rmrMessageBuffer.sendMessage->payload); |
| message.message.direction = 'N'; |
| if (sendRequestToXapp(message, RIC_SCTP_CONNECTION_FAILURE, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "SCTP_CONNECTION_FAIL message failed to send to xAPP"); |
| } |
| memset(peerInfo->asnData, 0, peerInfo->asnLength); |
| peerInfo->asnLength = 0; |
| peerInfo->mtype = 0; |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return; |
| } |
| |
| peerInfo->isConnected = true; |
| |
| if (modifyToEpoll(params->epoll_fd, peerInfo, (EPOLLIN | EPOLLET), params->sctpMap, peerInfo->enodbName, |
| peerInfo->mtype, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "epoll_ctl EPOLL_CTL_MOD"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return; |
| } |
| |
| message.message.asndata = (unsigned char *)peerInfo->asnData; |
| message.message.asnLength = peerInfo->asnLength; |
| message.message.messageType = peerInfo->mtype; |
| memcpy(message.message.enodbName, peerInfo->enodbName, sizeof(peerInfo->enodbName)); |
| num_of_messages.fetch_add(1, std::memory_order_release); |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "send the delayed SETUP/ENDC SETUP to sctp for %s", |
| message.message.enodbName); |
| } |
| if (sendSctpMsg(peerInfo, message, params->sctpMap, &lspan) != 0) { |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Error write to SCTP %s %d", __func__, __LINE__); |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return; |
| } |
| |
| memset(peerInfo->asnData, 0, peerInfo->asnLength); |
| peerInfo->asnLength = 0; |
| peerInfo->mtype = 0; |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| } |
| |
| |
| void handlepoll_error(struct epoll_event &event, |
| ReportingMessages_t &message, |
| RmrMessagesBuffer_t &rmrMessageBuffer, |
| sctp_params_t *params, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| if (event.data.fd != params->rmrListenFd) { |
| auto *peerInfo = (ConnectedCU_t *)event.data.ptr; |
| mdclog_write(MDCLOG_ERR, "epoll error, events %0x on fd %d, RAN NAME : %s", |
| event.events, peerInfo->fileDescriptor, peerInfo->enodbName); |
| |
| rmrMessageBuffer.sendMessage->len = snprintf((char *)rmrMessageBuffer.sendMessage->payload, 256, |
| "%s|Failed SCTP Connection", |
| peerInfo->enodbName); |
| message.message.asndata = rmrMessageBuffer.sendMessage->payload; |
| message.message.asnLength = rmrMessageBuffer.sendMessage->len; |
| |
| memcpy(message.message.enodbName, peerInfo->enodbName, sizeof(peerInfo->enodbName)); |
| message.message.direction = 'N'; |
| if (sendRequestToXapp(message, RIC_SCTP_CONNECTION_FAILURE, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "SCTP_CONNECTION_FAIL message failed to send to xAPP"); |
| } |
| |
| close(peerInfo->fileDescriptor); |
| cleanHashEntry((ConnectedCU_t *) event.data.ptr, params->sctpMap, &lspan); |
| } else { |
| mdclog_write(MDCLOG_ERR, "epoll error, events %0x on RMR FD", event.events); |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| } |
| /** |
| * |
| * @param socket |
| * @return |
| */ |
| int setSocketNoBlocking(int socket) { |
| auto flags = fcntl(socket, F_GETFL, 0); |
| |
| if (flags == -1) { |
| mdclog_mdc_add("func", "fcntl"); |
| mdclog_write(MDCLOG_ERR, "%s, %s", __FUNCTION__, strerror(errno)); |
| mdclog_mdc_clean(); |
| return -1; |
| } |
| |
| flags = (unsigned) flags | (unsigned) O_NONBLOCK; |
| if (fcntl(socket, F_SETFL, flags) == -1) { |
| mdclog_mdc_add("func", "fcntl"); |
| mdclog_write(MDCLOG_ERR, "%s, %s", __FUNCTION__, strerror(errno)); |
| mdclog_mdc_clean(); |
| return -1; |
| } |
| |
| return 0; |
| } |
| |
| /** |
| * |
| * @param val |
| * @param m |
| * @param pSpan |
| */ |
| void cleanHashEntry(ConnectedCU_t *val, Sctp_Map_t *m, otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| // otSpan lspan = 0; |
| #endif |
| char *dummy; |
| auto port = (uint16_t) strtol(val->portNumber, &dummy, 10); |
| char searchBuff[2048]{}; |
| |
| snprintf(searchBuff, sizeof searchBuff, "host:%s:%d", val->hostName, port); |
| m->erase(searchBuff); |
| |
| m->erase(val->enodbName); |
| free(val); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| } |
| |
| /** |
| * |
| * @param fd file discriptor |
| * @param data the asn data to send |
| * @param len length of the data |
| * @param enodbName the enodbName as in the map for printing purpose |
| * @param m map host information |
| * @param mtype message number |
| * @return 0 success, anegative number on fail |
| */ |
| int sendSctpMsg(ConnectedCU_t *peerInfo, ReportingMessages_t &message, Sctp_Map_t *m, otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| auto loglevel = mdclog_level_get(); |
| int fd = peerInfo->fileDescriptor; |
| if (loglevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Send SCTP message for CU %s, %s", |
| message.message.enodbName, __FUNCTION__); |
| } |
| |
| while (true) { |
| if (send(fd,message.message.asndata, message.message.asnLength,MSG_NOSIGNAL) < 0) { |
| if (errno == EINTR) { |
| continue; |
| } |
| mdclog_write(MDCLOG_ERR, "error writing to CU a message, %s ", strerror(errno)); |
| if (!peerInfo->isConnected) { |
| mdclog_write(MDCLOG_ERR, "connection to CU %s is still in progress.", message.message.enodbName); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| cleanHashEntry(peerInfo, m, &lspan); |
| close(fd); |
| char key[MAX_ENODB_NAME_SIZE * 2]; |
| snprintf(key, MAX_ENODB_NAME_SIZE * 2, "msg:%s|%d", message.message.enodbName, |
| message.message.messageType); |
| if (loglevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "remove key = %s from %s at line %d", key, __FUNCTION__, __LINE__); |
| } |
| auto tmp = m->find(key); |
| if (tmp) { |
| free(tmp); |
| } |
| m->erase(key); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| message.message.direction = 'D'; |
| // send report.buffer of size |
| buildJsonMessage(message); |
| |
| if (loglevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, |
| "SCTP message for CU %s sent from %s", |
| message.message.enodbName, |
| __FUNCTION__); |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return 0; |
| } |
| } |
| |
| /** |
| * |
| * @param message |
| * @param rmrMessageBuffer |
| * @param pSpan |
| */ |
| void getRequestMetaData(ReportingMessages_t &message, RmrMessagesBuffer_t &rmrMessageBuffer, otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| // otSpan lspan = 0; |
| #endif |
| rmr_get_meid(rmrMessageBuffer.rcvMessage, (unsigned char *)(message.message.enodbName)); |
| |
| message.message.asndata = rmrMessageBuffer.rcvMessage->payload; |
| message.message.asnLength = rmrMessageBuffer.rcvMessage->len; |
| |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Message from Xapp RAN name = %s message length = %ld", |
| message.message.enodbName, (unsigned long) message.message.asnLength); |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| } |
| |
| |
| /** |
| * |
| * @param metaData all the data strip to structure |
| * @param data the data recived from xAPP |
| * @return 0 success all other values are fault |
| */ |
| int getSetupRequestMetaData(ReportingMessages_t &message, char *data, char *host, uint16_t &port, otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| // otSpan lspan = 0; |
| #endif |
| auto loglevel = mdclog_level_get(); |
| |
| char delimiter[4] {}; |
| memset(delimiter, 0, (size_t)4); |
| delimiter[0] = '|'; |
| char *tmp; |
| |
| char *val = strtok_r(data, delimiter, &tmp); |
| if (val != nullptr) { |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "SCTP ADDRESS parameter from message = %s", val); |
| } |
| memcpy(host, val, tmp - val ); |
| } else { |
| mdclog_write(MDCLOG_ERR, "wrong Host Name for setup request %s", data); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| |
| val = strtok_r(nullptr, delimiter, &tmp); |
| if (val != nullptr) { |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "PORT parameter from message = %s", val); |
| } |
| char *dummy; |
| port = (uint16_t)strtol(val, &dummy, 10); |
| } else { |
| mdclog_write(MDCLOG_ERR, "wrong Port for setup request %s", data); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -2; |
| } |
| |
| val = strtok_r(nullptr, delimiter, &tmp); |
| if (val != nullptr) { |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "RAN NAME parameter from message = %s", val); |
| } |
| memcpy(message.message.enodbName, val, tmp - val); |
| } else { |
| mdclog_write(MDCLOG_ERR, "wrong gNb/Enodeb name for setup request %s", data); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| return -3; |
| } |
| val = strtok_r(nullptr, delimiter, &tmp); |
| if (val != nullptr) { |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "ASN length parameter from message = %s", val); |
| } |
| char *dummy; |
| message.message.asnLength = (uint16_t) strtol(val, &dummy, 10); |
| } else { |
| mdclog_write(MDCLOG_ERR, "wrong ASN length for setup request %s", data); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -4; |
| } |
| |
| message.message.asndata = (unsigned char *)tmp; // tmp is local but point to the location in data |
| |
| if (loglevel >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "Message from Xapp RAN name = %s host address = %s port = %d", |
| message.message.enodbName, host, port); |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| return 0; |
| } |
| |
| /** |
| * |
| * @param events |
| * @param sctpMap |
| * @param numOfMessages |
| * @param rmrMessageBuffer |
| * @param ts |
| * @param pSpan |
| * @return |
| */ |
| int receiveDataFromSctp(struct epoll_event *events, |
| Sctp_Map_t *sctpMap, |
| int &numOfMessages, |
| RmrMessagesBuffer_t &rmrMessageBuffer, |
| struct timespec &ts, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| /* We have data on the fd waiting to be read. Read and display it. |
| * We must read whatever data is available completely, as we are running |
| * in edge-triggered mode and won't get a notification again for the same data. */ |
| ReportingMessages_t message {}; |
| auto done = 0; |
| auto loglevel = mdclog_level_get(); |
| |
| // get the identity of the interface |
| message.peerInfo = (ConnectedCU_t *)events->data.ptr; |
| |
| |
| struct timespec start{0, 0}; |
| struct timespec decodestart{0, 0}; |
| struct timespec end{0, 0}; |
| |
| E2AP_PDU_t *pdu = nullptr; |
| |
| |
| while (true) { |
| if (loglevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Start Read from SCTP %d fd", message.peerInfo->fileDescriptor); |
| clock_gettime(CLOCK_MONOTONIC, &start); |
| } |
| // read the buffer directly to rmr payload |
| message.message.asndata = rmrMessageBuffer.sendMessage->payload; |
| message.message.asnLength = rmrMessageBuffer.sendMessage->len = |
| read(message.peerInfo->fileDescriptor, rmrMessageBuffer.sendMessage->payload, RECEIVE_SCTP_BUFFER_SIZE); |
| |
| if (loglevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Finish Read from SCTP %d fd message length = %ld", |
| message.peerInfo->fileDescriptor, message.message.asnLength); |
| } |
| memcpy(message.message.enodbName, message.peerInfo->enodbName, sizeof(message.peerInfo->enodbName)); |
| message.message.direction = 'U'; |
| message.message.time.tv_nsec = ts.tv_nsec; |
| message.message.time.tv_sec = ts.tv_sec; |
| |
| if (message.message.asnLength < 0) { |
| if (errno == EINTR) { |
| continue; |
| } |
| /* If errno == EAGAIN, that means we have read all |
| data. So goReportingMessages_t back to the main loop. */ |
| if (errno != EAGAIN) { |
| mdclog_write(MDCLOG_ERR, "Read error, %s ", strerror(errno)); |
| done = 1; |
| } else if (loglevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "EAGAIN - descriptor = %d", message.peerInfo->fileDescriptor); |
| } |
| break; |
| } else if (message.message.asnLength == 0) { |
| /* End of file. The remote has closed the connection. */ |
| if (loglevel >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "END of File Closed connection - descriptor = %d", |
| message.peerInfo->fileDescriptor); |
| } |
| done = 1; |
| break; |
| } |
| |
| asn_dec_rval_t rval; |
| if (loglevel >= MDCLOG_DEBUG) { |
| char printBuffer[4096]{}; |
| char *tmp = printBuffer; |
| for (size_t i = 0; i < (size_t)message.message.asnLength; ++i) { |
| snprintf(tmp, 3, "%02x", message.message.asndata[i]); |
| tmp += 2; |
| } |
| printBuffer[message.message.asnLength] = 0; |
| clock_gettime(CLOCK_MONOTONIC, &end); |
| mdclog_write(MDCLOG_DEBUG, "Before Encoding E2AP PDU for : %s, Read time is : %ld seconds, %ld nanoseconds", |
| message.peerInfo->enodbName, end.tv_sec - start.tv_sec, end.tv_nsec - start.tv_nsec); |
| mdclog_write(MDCLOG_DEBUG, "PDU buffer length = %ld, data = : %s", message.message.asnLength, |
| printBuffer); |
| clock_gettime(CLOCK_MONOTONIC, &decodestart); |
| } |
| |
| rval = asn_decode(nullptr, ATS_ALIGNED_BASIC_PER, &asn_DEF_E2AP_PDU, (void **) &pdu, |
| message.message.asndata, message.message.asnLength); |
| if (rval.code != RC_OK) { |
| mdclog_write(MDCLOG_ERR, "Error %d Decoding (unpack) E2AP PDU from RAN : %s", rval.code, |
| message.peerInfo->enodbName); |
| break; |
| } |
| |
| if (loglevel >= MDCLOG_DEBUG) { |
| clock_gettime(CLOCK_MONOTONIC, &end); |
| mdclog_write(MDCLOG_DEBUG, "After Encoding E2AP PDU for : %s, Read time is : %ld seconds, %ld nanoseconds", |
| message.peerInfo->enodbName, end.tv_sec - decodestart.tv_sec, end.tv_nsec - decodestart.tv_nsec); |
| char *printBuffer; |
| size_t size; |
| FILE *stream = open_memstream(&printBuffer, &size); |
| asn_fprint(stream, &asn_DEF_E2AP_PDU, pdu); |
| mdclog_write(MDCLOG_DEBUG, "Encoding E2AP PDU past : %s", printBuffer); |
| clock_gettime(CLOCK_MONOTONIC, &decodestart); |
| } |
| |
| switch (pdu->present) { |
| case E2AP_PDU_PR_initiatingMessage: {//initiating message |
| asnInitiatingRequest(pdu, message, rmrMessageBuffer, &lspan); |
| break; |
| } |
| case E2AP_PDU_PR_successfulOutcome: { //successful outcome |
| asnSuccsesfulMsg(pdu, message, sctpMap, rmrMessageBuffer, &lspan); |
| break; |
| } |
| case E2AP_PDU_PR_unsuccessfulOutcome: { //Unsuccessful Outcome |
| asnUnSuccsesfulMsg(pdu, message, sctpMap, rmrMessageBuffer, &lspan); |
| break; |
| } |
| default: |
| mdclog_write(MDCLOG_ERR, "Unknown index %d in E2AP PDU", pdu->present); |
| break; |
| } |
| if (loglevel >= MDCLOG_DEBUG) { |
| clock_gettime(CLOCK_MONOTONIC, &end); |
| mdclog_write(MDCLOG_DEBUG, |
| "After processing message and sent to rmr for : %s, Read time is : %ld seconds, %ld nanoseconds", |
| message.peerInfo->enodbName, end.tv_sec - decodestart.tv_sec, end.tv_nsec - decodestart.tv_nsec); |
| } |
| numOfMessages++; |
| // remove the break for EAGAIN |
| //break; |
| if (pdu != nullptr) { |
| //TODO need to test ASN_STRUCT_RESET(asn_DEF_E2AP_PDU, pdu); to get better performance |
| //ASN_STRUCT_RESET(asn_DEF_E2AP_PDU, pdu); |
| ASN_STRUCT_FREE(asn_DEF_E2AP_PDU, pdu); |
| pdu = nullptr; |
| } |
| //clock_gettime(CLOCK_MONOTONIC, &start); |
| } |
| // in case of break to avoid memory leak |
| if (pdu != nullptr) { |
| ASN_STRUCT_FREE(asn_DEF_E2AP_PDU, pdu); |
| pdu = nullptr; |
| } |
| |
| if (done) { |
| if (loglevel >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "Closed connection - descriptor = %d", message.peerInfo->fileDescriptor); |
| } |
| message.message.asnLength = rmrMessageBuffer.sendMessage->len = |
| snprintf((char *)rmrMessageBuffer.sendMessage->payload, |
| 256, |
| "%s|CU disconnected unexpectedly", |
| message.peerInfo->enodbName); |
| message.message.asndata = rmrMessageBuffer.sendMessage->payload; |
| |
| if (sendRequestToXapp(message, |
| RIC_SCTP_CONNECTION_FAILURE, |
| rmrMessageBuffer, |
| &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "SCTP_CONNECTION_FAIL message failed to send to xAPP"); |
| } |
| |
| /* Closing descriptor make epoll remove it from the set of descriptors which are monitored. */ |
| close(message.peerInfo->fileDescriptor); |
| cleanHashEntry((ConnectedCU_t *) events->data.ptr, sctpMap, &lspan); |
| } |
| if (loglevel >= MDCLOG_DEBUG) { |
| clock_gettime(CLOCK_MONOTONIC, &end); |
| mdclog_write(MDCLOG_DEBUG, "from receive SCTP to send RMR time is %ld seconds and %ld nanoseconds", |
| end.tv_sec - start.tv_sec, end.tv_nsec - start.tv_nsec); |
| |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| return 0; |
| } |
| |
| static void buildAndsendSetupRequest(ReportingMessages_t &message, |
| E2setupRequestIEs_t *ie, |
| RmrMessagesBuffer_t &rmrMessageBuffer, |
| E2AP_PDU_t *pdu) { |
| auto logLevel = mdclog_level_get(); |
| |
| |
| if (buildRanName(message.peerInfo->enodbName, ie) < 0) { |
| mdclog_write(MDCLOG_ERR, "Bad param in E2setupRequestIEs GlobalE2node_ID.\n"); |
| } else { |
| memcpy(message.message.enodbName, message.peerInfo->enodbName, strlen(message.peerInfo->enodbName)); |
| } |
| // now we can send the data to e2Mgr |
| auto buffer_size = RECEIVE_SCTP_BUFFER_SIZE; |
| |
| auto *rmrMsg = rmr_alloc_msg(rmrMessageBuffer.rmrCtx, buffer_size); |
| // add addrees to message |
| auto j = snprintf((char *)rmrMsg->payload, 256, "%s:%d|", message.peerInfo->sctpParams->myIP.c_str(), message.peerInfo->sctpParams->rmrPort); |
| |
| |
| unsigned char *buffer = &rmrMsg->payload[j]; |
| // encode to xml |
| asn_enc_rval_t er; |
| er = asn_encode_to_buffer(nullptr, ATS_BASIC_XER, &asn_DEF_E2AP_PDU, pdu, buffer, buffer_size - j); |
| if (er.encoded == -1) { |
| mdclog_write(MDCLOG_ERR, "encoding of %s failed, %s", asn_DEF_E2AP_PDU.name, strerror(errno)); |
| } else if (er.encoded > (ssize_t) buffer_size) { |
| mdclog_write(MDCLOG_ERR, "Buffer of size %d is to small for %s", |
| (int) buffer_size, |
| asn_DEF_E2AP_PDU.name); |
| } else { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Buffer of size %d, data = %s", (int) er.encoded, buffer); |
| } |
| // TODO send to RMR |
| message.message.messageType = rmrMsg->mtype = RIC_X2_SETUP_REQ; |
| rmrMsg->state = 0; |
| rmr_bytes2meid(rmrMsg, (unsigned char *) message.message.enodbName, strlen(message.message.enodbName)); |
| |
| static unsigned char tx[32]; |
| snprintf((char *) tx, sizeof tx, "%15ld", transactionCounter++); |
| rmr_bytes2xact(rmrMsg, tx, strlen((const char *) tx)); |
| |
| rmrMsg = rmr_send_msg(rmrMessageBuffer.rmrCtx, rmrMsg); |
| if (rmrMsg == nullptr) { |
| mdclog_write(MDCLOG_ERR, "RMR failed to send returned nullptr"); |
| } else if (rmrMsg->state != 0) { |
| char meid[RMR_MAX_MEID]{}; |
| if (rmrMsg->state == RMR_ERR_RETRY) { |
| usleep(5); |
| rmrMsg->state = 0; |
| mdclog_write(MDCLOG_INFO, "RETRY sending Message %d to Xapp from %s", |
| rmrMsg->mtype, rmr_get_meid(rmrMsg, (unsigned char *) meid)); |
| rmrMsg = rmr_send_msg(rmrMessageBuffer.rmrCtx, rmrMsg); |
| if (rmrMsg == nullptr) { |
| mdclog_write(MDCLOG_ERR, "RMR failed send returned nullptr"); |
| } else if (rmrMsg->state != 0) { |
| mdclog_write(MDCLOG_ERR, |
| "RMR Retry failed %s sending request %d to Xapp from %s", |
| translateRmrErrorMessages(rmrMsg->state).c_str(), |
| rmrMsg->mtype, |
| rmr_get_meid(rmrMsg, (unsigned char *) meid)); |
| } |
| } else { |
| mdclog_write(MDCLOG_ERR, "RMR failed: %s. sending request %d to Xapp from %s", |
| translateRmrErrorMessages(rmrMsg->state).c_str(), |
| rmrMsg->mtype, |
| rmr_get_meid(rmrMsg, (unsigned char *) meid)); |
| } |
| } |
| message.peerInfo->gotSetup = true; |
| buildJsonMessage(message); |
| } |
| |
| } |
| /** |
| * |
| * @param pdu |
| * @param message |
| * @param rmrMessageBuffer |
| * @param pSpan |
| */ |
| void asnInitiatingRequest(E2AP_PDU_t *pdu, |
| ReportingMessages_t &message, |
| RmrMessagesBuffer_t &rmrMessageBuffer, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| |
| auto logLevel = mdclog_level_get(); |
| auto procedureCode = ((InitiatingMessage_t *) pdu->choice.initiatingMessage)->procedureCode; |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Initiating message %ld\n", procedureCode); |
| } |
| switch (procedureCode) { |
| case ProcedureCode_id_E2setup: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got E2setup\n"); |
| } |
| |
| memset(message.peerInfo->enodbName, 0 , MAX_ENODB_NAME_SIZE); |
| for (auto i = 0; i < pdu->choice.initiatingMessage->value.choice.E2setupRequest.protocolIEs.list.count; i++) { |
| auto *ie = pdu->choice.initiatingMessage->value.choice.E2setupRequest.protocolIEs.list.array[i]; |
| if (ie->id == ProtocolIE_ID_id_GlobalE2node_ID) { |
| if (ie->value.present == E2setupRequestIEs__value_PR_GlobalE2node_ID) { |
| buildAndsendSetupRequest(message, ie, rmrMessageBuffer, pdu); |
| break; |
| } |
| } |
| } |
| break; |
| } |
| case ProcedureCode_id_ErrorIndication: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got ErrorIndication %s", message.message.enodbName); |
| } |
| if (sendRequestToXapp(message, RIC_ERROR_INDICATION, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "RIC_ERROR_INDICATION failed to send to xAPP"); |
| } |
| break; |
| } |
| case ProcedureCode_id_Reset: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got Reset %s", message.message.enodbName); |
| } |
| if (sendRequestToXapp(message, RIC_X2_RESET, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "RIC_X2_RESET message failed to send to xAPP"); |
| } |
| break; |
| } |
| case ProcedureCode_id_RICcontrol: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICcontrol %s", message.message.enodbName); |
| } |
| break; |
| } |
| case ProcedureCode_id_RICindication: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICindication %s", message.message.enodbName); |
| } |
| for (auto i = 0; i < pdu->choice.initiatingMessage->value.choice.RICindication.protocolIEs.list.count; i++) { |
| auto messageSent = false; |
| RICindication_IEs_t *ie = pdu->choice.initiatingMessage->value.choice.RICindication.protocolIEs.list.array[i]; |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "ie type (ProtocolIE_ID) = %ld", ie->id); |
| } |
| if (ie->id == ProtocolIE_ID_id_RICrequestID) { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RIC requestId entry, ie type (ProtocolIE_ID) = %ld", ie->id); |
| } |
| if (ie->value.present == RICindication_IEs__value_PR_RICrequestID) { |
| static unsigned char tx[32]; |
| message.message.messageType = rmrMessageBuffer.sendMessage->mtype = RIC_INDICATION; |
| snprintf((char *) tx, sizeof tx, "%15ld", transactionCounter++); |
| rmr_bytes2xact(rmrMessageBuffer.sendMessage, tx, strlen((const char *) tx)); |
| rmr_bytes2meid(rmrMessageBuffer.sendMessage, |
| (unsigned char *)message.message.enodbName, |
| strlen(message.message.enodbName)); |
| rmrMessageBuffer.sendMessage->state = 0; |
| rmrMessageBuffer.sendMessage->sub_id = (int) ie->value.choice.RICrequestID.ricRequestorID; |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "RIC sub id = %d, message type = %d", |
| rmrMessageBuffer.sendMessage->sub_id, |
| rmrMessageBuffer.sendMessage->mtype); |
| } |
| sendRmrMessage(rmrMessageBuffer, message, &lspan); |
| messageSent = true; |
| } else { |
| mdclog_write(MDCLOG_ERR, "RIC request id missing illigal request"); |
| } |
| } |
| if (messageSent) { |
| break; |
| } |
| } |
| break; |
| } |
| case ProcedureCode_id_RICserviceQuery: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICserviceQuery %s", message.message.enodbName); |
| } |
| break; |
| } |
| case ProcedureCode_id_RICserviceUpdate: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICserviceUpdate %s", message.message.enodbName); |
| } |
| if (sendRequestToXapp(message, RIC_SERVICE_UPDATE, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "RIC_SERVICE_UPDATE message failed to send to xAPP"); |
| } |
| break; |
| } |
| case ProcedureCode_id_RICsubscription: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICsubscription %s", message.message.enodbName); |
| } |
| break; |
| } |
| case ProcedureCode_id_RICsubscriptionDelete: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICsubscriptionDelete %s", message.message.enodbName); |
| } |
| break; |
| } |
| default: { |
| mdclog_write(MDCLOG_ERR, "Undefined or not supported message = %ld", procedureCode); |
| message.message.messageType = 0; // no RMR message type yet |
| |
| buildJsonMessage(message); |
| |
| break; |
| } |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| } |
| |
| /** |
| * |
| * @param pdu |
| * @param message |
| * @param sctpMap |
| * @param rmrMessageBuffer |
| * @param pSpan |
| */ |
| void asnSuccsesfulMsg(E2AP_PDU_t *pdu, ReportingMessages_t &message, Sctp_Map_t *sctpMap, |
| RmrMessagesBuffer_t &rmrMessageBuffer, otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| auto procedureCode = pdu->choice.successfulOutcome->procedureCode; |
| auto logLevel = mdclog_level_get(); |
| if (logLevel >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "Successful Outcome %ld", procedureCode); |
| } |
| switch (procedureCode) { |
| case ProcedureCode_id_E2setup: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got E2setup\n"); |
| } |
| break; |
| } |
| case ProcedureCode_id_ErrorIndication: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got ErrorIndication %s", message.message.enodbName); |
| } |
| if (sendRequestToXapp(message, RIC_ERROR_INDICATION, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "RIC_ERROR_INDICATION failed to send to xAPP"); |
| } |
| break; |
| } |
| case ProcedureCode_id_Reset: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got Reset %s", message.message.enodbName); |
| } |
| if (sendRequestToXapp(message, RIC_X2_RESET, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "RIC_X2_RESET message failed to send to xAPP"); |
| } |
| break; |
| } |
| case ProcedureCode_id_RICcontrol: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICcontrol %s", message.message.enodbName); |
| } |
| for (auto i = 0; |
| i < pdu->choice.successfulOutcome->value.choice.RICcontrolAcknowledge.protocolIEs.list.count; i++) { |
| auto messageSent = false; |
| RICcontrolAcknowledge_IEs_t *ie = pdu->choice.successfulOutcome->value.choice.RICcontrolAcknowledge.protocolIEs.list.array[i]; |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "ie type (ProtocolIE_ID) = %ld", ie->id); |
| } |
| if (ie->id == ProtocolIE_ID_id_RICrequestID) { |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RIC requestId entry, ie type (ProtocolIE_ID) = %ld", ie->id); |
| } |
| if (ie->value.present == RICcontrolAcknowledge_IEs__value_PR_RICrequestID) { |
| message.message.messageType = rmrMessageBuffer.sendMessage->mtype = RIC_CONTROL_ACK; |
| rmrMessageBuffer.sendMessage->state = 0; |
| rmrMessageBuffer.sendMessage->sub_id = (int) ie->value.choice.RICrequestID.ricRequestorID; |
| static unsigned char tx[32]; |
| snprintf((char *) tx, sizeof tx, "%15ld", transactionCounter++); |
| rmr_bytes2xact(rmrMessageBuffer.sendMessage, tx, strlen((const char *) tx)); |
| rmr_bytes2meid(rmrMessageBuffer.sendMessage, |
| (unsigned char *)message.message.enodbName, |
| strlen(message.message.enodbName)); |
| |
| sendRmrMessage(rmrMessageBuffer, message, &lspan); |
| messageSent = true; |
| } else { |
| mdclog_write(MDCLOG_ERR, "RIC request id missing illigal request"); |
| } |
| } |
| if (messageSent) { |
| break; |
| } |
| } |
| |
| break; |
| } |
| case ProcedureCode_id_RICindication: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICindication %s", message.message.enodbName); |
| } |
| for (auto i = 0; i < pdu->choice.initiatingMessage->value.choice.RICindication.protocolIEs.list.count; i++) { |
| auto messageSent = false; |
| RICindication_IEs_t *ie = pdu->choice.initiatingMessage->value.choice.RICindication.protocolIEs.list.array[i]; |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "ie type (ProtocolIE_ID) = %ld", ie->id); |
| } |
| if (ie->id == ProtocolIE_ID_id_RICrequestID) { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RIC requestId entry, ie type (ProtocolIE_ID) = %ld", ie->id); |
| } |
| if (ie->value.present == RICindication_IEs__value_PR_RICrequestID) { |
| static unsigned char tx[32]; |
| message.message.messageType = rmrMessageBuffer.sendMessage->mtype = RIC_INDICATION; |
| snprintf((char *) tx, sizeof tx, "%15ld", transactionCounter++); |
| rmr_bytes2xact(rmrMessageBuffer.sendMessage, tx, strlen((const char *) tx)); |
| rmr_bytes2meid(rmrMessageBuffer.sendMessage, |
| (unsigned char *)message.message.enodbName, |
| strlen(message.message.enodbName)); |
| rmrMessageBuffer.sendMessage->state = 0; |
| rmrMessageBuffer.sendMessage->sub_id = (int) ie->value.choice.RICrequestID.ricRequestorID; |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "RIC sub id = %d, message type = %d", |
| rmrMessageBuffer.sendMessage->sub_id, |
| rmrMessageBuffer.sendMessage->mtype); |
| } |
| sendRmrMessage(rmrMessageBuffer, message, &lspan); |
| messageSent = true; |
| } else { |
| mdclog_write(MDCLOG_ERR, "RIC request id missing illigal request"); |
| } |
| } |
| if (messageSent) { |
| break; |
| } |
| } |
| break; |
| } |
| case ProcedureCode_id_RICserviceQuery: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICserviceQuery %s", message.message.enodbName); |
| } |
| break; |
| } |
| case ProcedureCode_id_RICserviceUpdate: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICserviceUpdate %s", message.message.enodbName); |
| } |
| if (sendRequestToXapp(message, RIC_SERVICE_UPDATE, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "RIC_SERVICE_UPDATE message failed to send to xAPP"); |
| } |
| break; |
| } |
| case ProcedureCode_id_RICsubscription: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICsubscription %s", message.message.enodbName); |
| } |
| if (sendRequestToXapp(message, RIC_SUB_RESP, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Subscription successful message failed to send to xAPP"); |
| } |
| break; |
| } |
| case ProcedureCode_id_RICsubscriptionDelete: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICsubscriptionDelete %s", message.message.enodbName); |
| } |
| if (sendRequestToXapp(message, RIC_SUB_DEL_RESP, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Subscription delete successful message failed to send to xAPP"); |
| } |
| break; |
| } |
| default: { |
| mdclog_write(MDCLOG_WARN, "Undefined or not supported message = %ld", procedureCode); |
| message.message.messageType = 0; // no RMR message type yet |
| buildJsonMessage(message); |
| |
| break; |
| } |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| } |
| |
| /** |
| * |
| * @param pdu |
| * @param message |
| * @param sctpMap |
| * @param rmrMessageBuffer |
| * @param pSpan |
| */ |
| void asnUnSuccsesfulMsg(E2AP_PDU_t *pdu, |
| ReportingMessages_t &message, |
| Sctp_Map_t *sctpMap, |
| RmrMessagesBuffer_t &rmrMessageBuffer, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| auto procedureCode = pdu->choice.unsuccessfulOutcome->procedureCode; |
| auto logLevel = mdclog_level_get(); |
| if (logLevel >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "Unsuccessful Outcome %ld", procedureCode); |
| } |
| switch (procedureCode) { |
| case ProcedureCode_id_E2setup: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got E2setup\n"); |
| } |
| break; |
| } |
| case ProcedureCode_id_ErrorIndication: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got ErrorIndication %s", message.message.enodbName); |
| } |
| if (sendRequestToXapp(message, RIC_ERROR_INDICATION, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "RIC_ERROR_INDICATION failed to send to xAPP"); |
| } |
| break; |
| } |
| case ProcedureCode_id_Reset: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got Reset %s", message.message.enodbName); |
| } |
| if (sendRequestToXapp(message, RIC_X2_RESET, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "RIC_X2_RESET message failed to send to xAPP"); |
| } |
| break; |
| } |
| case ProcedureCode_id_RICcontrol: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICcontrol %s", message.message.enodbName); |
| } |
| for (int i = 0; |
| i < pdu->choice.unsuccessfulOutcome->value.choice.RICcontrolFailure.protocolIEs.list.count; i++) { |
| auto messageSent = false; |
| RICcontrolFailure_IEs_t *ie = pdu->choice.unsuccessfulOutcome->value.choice.RICcontrolFailure.protocolIEs.list.array[i]; |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "ie type (ProtocolIE_ID) = %ld", ie->id); |
| } |
| if (ie->id == ProtocolIE_ID_id_RICrequestID) { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RIC requestId entry, ie type (ProtocolIE_ID) = %ld", ie->id); |
| } |
| if (ie->value.present == RICcontrolFailure_IEs__value_PR_RICrequestID) { |
| message.message.messageType = rmrMessageBuffer.sendMessage->mtype = RIC_CONTROL_FAILURE; |
| rmrMessageBuffer.sendMessage->state = 0; |
| rmrMessageBuffer.sendMessage->sub_id = (int) ie->value.choice.RICrequestID.ricRequestorID; |
| static unsigned char tx[32]; |
| snprintf((char *) tx, sizeof tx, "%15ld", transactionCounter++); |
| rmr_bytes2xact(rmrMessageBuffer.sendMessage, tx, strlen((const char *) tx)); |
| rmr_bytes2meid(rmrMessageBuffer.sendMessage, (unsigned char *) message.message.enodbName, |
| strlen(message.message.enodbName)); |
| sendRmrMessage(rmrMessageBuffer, message, &lspan); |
| messageSent = true; |
| } else { |
| mdclog_write(MDCLOG_ERR, "RIC request id missing illigal request"); |
| } |
| } |
| if (messageSent) { |
| break; |
| } |
| } |
| break; |
| } |
| case ProcedureCode_id_RICindication: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICindication %s", message.message.enodbName); |
| } |
| for (auto i = 0; i < pdu->choice.initiatingMessage->value.choice.RICindication.protocolIEs.list.count; i++) { |
| auto messageSent = false; |
| RICindication_IEs_t *ie = pdu->choice.initiatingMessage->value.choice.RICindication.protocolIEs.list.array[i]; |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "ie type (ProtocolIE_ID) = %ld", ie->id); |
| } |
| if (ie->id == ProtocolIE_ID_id_RICrequestID) { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RIC requestId entry, ie type (ProtocolIE_ID) = %ld", ie->id); |
| } |
| if (ie->value.present == RICindication_IEs__value_PR_RICrequestID) { |
| static unsigned char tx[32]; |
| message.message.messageType = rmrMessageBuffer.sendMessage->mtype = RIC_INDICATION; |
| snprintf((char *) tx, sizeof tx, "%15ld", transactionCounter++); |
| rmr_bytes2xact(rmrMessageBuffer.sendMessage, tx, strlen((const char *) tx)); |
| rmr_bytes2meid(rmrMessageBuffer.sendMessage, |
| (unsigned char *)message.message.enodbName, |
| strlen(message.message.enodbName)); |
| rmrMessageBuffer.sendMessage->state = 0; |
| rmrMessageBuffer.sendMessage->sub_id = (int) ie->value.choice.RICrequestID.ricRequestorID; |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "RIC sub id = %d, message type = %d", |
| rmrMessageBuffer.sendMessage->sub_id, |
| rmrMessageBuffer.sendMessage->mtype); |
| } |
| sendRmrMessage(rmrMessageBuffer, message, &lspan); |
| messageSent = true; |
| } else { |
| mdclog_write(MDCLOG_ERR, "RIC request id missing illigal request"); |
| } |
| } |
| if (messageSent) { |
| break; |
| } |
| } |
| break; |
| } |
| case ProcedureCode_id_RICserviceQuery: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICserviceQuery %s", message.message.enodbName); |
| } |
| break; |
| } |
| case ProcedureCode_id_RICserviceUpdate: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICserviceUpdate %s", message.message.enodbName); |
| } |
| if (sendRequestToXapp(message, RIC_SERVICE_UPDATE, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "RIC_SERVICE_UPDATE message failed to send to xAPP"); |
| } |
| break; |
| } |
| case ProcedureCode_id_RICsubscription: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICsubscription %s", message.message.enodbName); |
| } |
| if (sendRequestToXapp(message, RIC_SUB_FAILURE, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Subscription unsuccessful message failed to send to xAPP"); |
| } |
| break; |
| } |
| case ProcedureCode_id_RICsubscriptionDelete: { |
| if (logLevel >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Got RICsubscriptionDelete %s", message.message.enodbName); |
| } |
| if (sendRequestToXapp(message, RIC_SUB_DEL_FAILURE, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Subscription Delete unsuccessful message failed to send to xAPP"); |
| } |
| break; |
| } |
| default: { |
| mdclog_write(MDCLOG_WARN, "Undefined or not supported message = %ld", procedureCode); |
| message.message.messageType = 0; // no RMR message type yet |
| |
| buildJsonMessage(message); |
| |
| break; |
| } |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| } |
| |
| /** |
| * |
| * @param message |
| * @param requestId |
| * @param rmrMmessageBuffer |
| * @param pSpan |
| * @return |
| */ |
| int sendRequestToXapp(ReportingMessages_t &message, |
| int requestId, |
| RmrMessagesBuffer_t &rmrMmessageBuffer, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| rmr_bytes2meid(rmrMmessageBuffer.sendMessage, |
| (unsigned char *)message.message.enodbName, |
| strlen(message.message.enodbName)); |
| message.message.messageType = rmrMmessageBuffer.sendMessage->mtype = requestId; |
| rmrMmessageBuffer.sendMessage->state = 0; |
| static unsigned char tx[32]; |
| snprintf((char *) tx, sizeof tx, "%15ld", transactionCounter++); |
| rmr_bytes2xact(rmrMmessageBuffer.sendMessage, tx, strlen((const char *) tx)); |
| |
| auto rc = sendRmrMessage(rmrMmessageBuffer, message, &lspan); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| return rc; |
| } |
| |
| |
| void getRmrContext(sctp_params_t &pSctpParams, otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| // otSpan lspan = 0; |
| #endif |
| pSctpParams.rmrCtx = nullptr; |
| pSctpParams.rmrCtx = rmr_init(pSctpParams.rmrAddress, RMR_MAX_RCV_BYTES, RMRFL_NONE); |
| if (pSctpParams.rmrCtx == nullptr) { |
| mdclog_write(MDCLOG_ERR, "Failed to initialize RMR"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return; |
| } |
| |
| rmr_set_stimeout(pSctpParams.rmrCtx, 0); // disable retries for any send operation |
| // we need to find that routing table exist and we can run |
| if (mdclog_level_get() >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "We are after RMR INIT wait for RMR_Ready"); |
| } |
| int rmrReady = 0; |
| int count = 0; |
| while (!rmrReady) { |
| if ((rmrReady = rmr_ready(pSctpParams.rmrCtx)) == 0) { |
| sleep(1); |
| } |
| count++; |
| if (count % 60 == 0) { |
| mdclog_write(MDCLOG_INFO, "waiting to RMR ready state for %d seconds", count); |
| } |
| } |
| if (mdclog_level_get() >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "RMR running"); |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| rmr_init_trace(pSctpParams.rmrCtx, 200); |
| // get the RMR fd for the epoll |
| pSctpParams.rmrListenFd = rmr_get_rcvfd(pSctpParams.rmrCtx); |
| struct epoll_event event{}; |
| // add RMR fd to epoll |
| event.events = (EPOLLIN); |
| event.data.fd = pSctpParams.rmrListenFd; |
| // add listening RMR FD to epoll |
| if (epoll_ctl(pSctpParams.epoll_fd, EPOLL_CTL_ADD, pSctpParams.rmrListenFd, &event)) { |
| mdclog_write(MDCLOG_ERR, "Failed to add RMR descriptor to epoll"); |
| close(pSctpParams.rmrListenFd); |
| rmr_close(pSctpParams.rmrCtx); |
| pSctpParams.rmrCtx = nullptr; |
| } |
| } |
| |
| /** |
| * |
| * @param epoll_fd |
| * @param sctpMap |
| * @param rmrMessageBuffer |
| * @param ts |
| * @param pSpan |
| * @return |
| */ |
| int receiveXappMessages(int epoll_fd, |
| Sctp_Map_t *sctpMap, |
| RmrMessagesBuffer_t &rmrMessageBuffer, |
| struct timespec &ts, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| if (rmrMessageBuffer.rcvMessage == nullptr) { |
| //we have error |
| mdclog_write(MDCLOG_ERR, "RMR Allocation message, %s", strerror(errno)); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| return -1; |
| } |
| |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Call to rmr_rcv_msg"); |
| } |
| rmrMessageBuffer.rcvMessage = rmr_rcv_msg(rmrMessageBuffer.rmrCtx, rmrMessageBuffer.rcvMessage); |
| if (rmrMessageBuffer.rcvMessage == nullptr) { |
| mdclog_write(MDCLOG_ERR, "RMR Receving message with null pointer, Realloc rmr mesage buffer"); |
| rmrMessageBuffer.rcvMessage = rmr_alloc_msg(rmrMessageBuffer.rmrCtx, RECEIVE_XAPP_BUFFER_SIZE); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| return -2; |
| } |
| ReportingMessages_t message; |
| message.message.direction = 'D'; |
| message.message.time.tv_nsec = ts.tv_nsec; |
| message.message.time.tv_sec = ts.tv_sec; |
| |
| // get message payload |
| //auto msgData = msg->payload; |
| if (rmrMessageBuffer.rcvMessage->state != 0) { |
| mdclog_write(MDCLOG_ERR, "RMR Receving message with stat = %d", rmrMessageBuffer.rcvMessage->state); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| return -1; |
| } |
| switch (rmrMessageBuffer.rcvMessage->mtype) { |
| case RIC_X2_SETUP_REQ: { |
| if (connectToCUandSetUp(rmrMessageBuffer, message, epoll_fd, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "ERROR in connectToCUandSetUp on RIC_X2_SETUP_REQ"); |
| message.message.messageType = rmrMessageBuffer.sendMessage->mtype = RIC_SCTP_CONNECTION_FAILURE; |
| message.message.direction = 'N'; |
| message.message.asnLength = rmrMessageBuffer.sendMessage->len = |
| snprintf((char *)rmrMessageBuffer.sendMessage->payload, |
| 256, |
| "ERROR in connectToCUandSetUp on RIC_X2_SETUP_REQ"); |
| rmrMessageBuffer.sendMessage->state = 0; |
| message.message.asndata = rmrMessageBuffer.sendMessage->payload; |
| |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "start writing to rmr buffer"); |
| } |
| rmr_bytes2xact(rmrMessageBuffer.sendMessage, rmrMessageBuffer.rcvMessage->xaction, RMR_MAX_XID); |
| rmr_str2meid(rmrMessageBuffer.sendMessage, (unsigned char *)message.message.enodbName); |
| |
| sendRmrMessage(rmrMessageBuffer, message, &lspan); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -3; |
| } |
| break; |
| } |
| case RIC_ENDC_X2_SETUP_REQ: { |
| if (connectToCUandSetUp(rmrMessageBuffer, message, epoll_fd, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "ERROR in connectToCUandSetUp on RIC_ENDC_X2_SETUP_REQ"); |
| message.message.messageType = rmrMessageBuffer.sendMessage->mtype = RIC_SCTP_CONNECTION_FAILURE; |
| message.message.direction = 'N'; |
| message.message.asnLength = rmrMessageBuffer.sendMessage->len = |
| snprintf((char *)rmrMessageBuffer.sendMessage->payload, 256, |
| "ERROR in connectToCUandSetUp on RIC_ENDC_X2_SETUP_REQ"); |
| rmrMessageBuffer.sendMessage->state = 0; |
| message.message.asndata = rmrMessageBuffer.sendMessage->payload; |
| |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "start writing to rmr buffer"); |
| } |
| |
| rmr_bytes2xact(rmrMessageBuffer.sendMessage, rmrMessageBuffer.rcvMessage->xaction, RMR_MAX_XID); |
| rmr_str2meid(rmrMessageBuffer.sendMessage, (unsigned char *) message.message.enodbName); |
| |
| sendRmrMessage(rmrMessageBuffer, message, &lspan); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -3; |
| } |
| break; |
| } |
| case RIC_ENDC_CONF_UPDATE: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_ENDC_CONF_UPDATE"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -4; |
| } |
| break; |
| } |
| case RIC_ENDC_CONF_UPDATE_ACK: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_ENDC_CONF_UPDATE_ACK"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -4; |
| } |
| break; |
| } |
| case RIC_ENDC_CONF_UPDATE_FAILURE: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_ENDC_CONF_UPDATE_FAILURE"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| return -4; |
| } |
| break; |
| } |
| case RIC_ENB_CONF_UPDATE: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_ENDC_CONF_UPDATE"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -4; |
| } |
| break; |
| } |
| case RIC_ENB_CONF_UPDATE_ACK: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_ENB_CONF_UPDATE_ACK"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -4; |
| } |
| break; |
| } |
| case RIC_ENB_CONF_UPDATE_FAILURE: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_ENB_CONF_UPDATE_FAILURE"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -4; |
| } |
| break; |
| } |
| case RIC_RES_STATUS_REQ: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_RES_STATUS_REQ"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -6; |
| } |
| break; |
| } |
| case RIC_SUB_REQ: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_SUB_REQ"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -6; |
| } |
| break; |
| } |
| case RIC_SUB_DEL_REQ: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_SUB_DEL_REQ"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -6; |
| } |
| break; |
| } |
| case RIC_CONTROL_REQ: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_CONTROL_REQ"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -6; |
| } |
| break; |
| } |
| case RIC_SERVICE_QUERY: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_SERVICE_QUERY"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -6; |
| } |
| break; |
| } |
| case RIC_SERVICE_UPDATE_ACK: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_SERVICE_UPDATE_ACK"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -6; |
| } |
| break; |
| } |
| case RIC_SERVICE_UPDATE_FAILURE: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_SERVICE_UPDATE_FAILURE"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -6; |
| } |
| break; |
| } |
| case RIC_X2_RESET: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_X2_RESET"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -6; |
| } |
| break; |
| } |
| case RIC_X2_RESET_RESP: { |
| if (sendDirectionalSctpMsg(rmrMessageBuffer, message, 0, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send RIC_X2_RESET_RESP"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -6; |
| } |
| break; |
| } |
| case RIC_SCTP_CLEAR_ALL: { |
| mdclog_write(MDCLOG_INFO, "RIC_SCTP_CLEAR_ALL"); |
| // loop on all keys and close socket and then erase all map. |
| vector<char *> v; |
| sctpMap->getKeys(v); |
| for (auto const &iter : v) { //}; iter != sctpMap.end(); iter++) { |
| if (!boost::starts_with((string) (iter), "host:") && !boost::starts_with((string) (iter), "msg:")) { |
| auto *peerInfo = (ConnectedCU_t *) sctpMap->find(iter); |
| if (peerInfo == nullptr) { |
| continue; |
| } |
| close(peerInfo->fileDescriptor); |
| memcpy(message.message.enodbName, peerInfo->enodbName, sizeof(peerInfo->enodbName)); |
| message.message.direction = 'D'; |
| message.message.time.tv_nsec = ts.tv_nsec; |
| message.message.time.tv_sec = ts.tv_sec; |
| |
| message.message.asnLength = rmrMessageBuffer.sendMessage->len = |
| snprintf((char *)rmrMessageBuffer.sendMessage->payload, |
| 256, |
| "%s|RIC_SCTP_CLEAR_ALL", |
| peerInfo->enodbName); |
| message.message.asndata = rmrMessageBuffer.sendMessage->payload; |
| mdclog_write(MDCLOG_INFO, "%s", message.message.asndata); |
| if (sendRequestToXapp(message, |
| RIC_SCTP_CONNECTION_FAILURE, rmrMessageBuffer, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "SCTP_CONNECTION_FAIL message failed to send to xAPP"); |
| } |
| free(peerInfo); |
| } |
| } |
| |
| sleep(1); |
| sctpMap->clear(); |
| break; |
| } |
| case E2_TERM_KEEP_ALIVE_REQ: { |
| // send message back |
| rmr_bytes2payload(rmrMessageBuffer.sendMessage, |
| (unsigned char *)rmrMessageBuffer.ka_message, |
| rmrMessageBuffer.ka_message_len); |
| rmrMessageBuffer.sendMessage->mtype = E2_TERM_KEEP_ALIVE_RESP; |
| rmrMessageBuffer.sendMessage->state = 0; |
| static unsigned char tx[32]; |
| auto txLen = snprintf((char *) tx, sizeof tx, "%15ld", transactionCounter++); |
| rmr_bytes2xact(rmrMessageBuffer.sendMessage, tx, txLen); |
| rmrMessageBuffer.sendMessage = rmr_send_msg(rmrMessageBuffer.rmrCtx, rmrMessageBuffer.sendMessage); |
| if (rmrMessageBuffer.sendMessage == nullptr) { |
| rmrMessageBuffer.sendMessage = rmr_alloc_msg(rmrMessageBuffer.rmrCtx, RECEIVE_XAPP_BUFFER_SIZE); |
| mdclog_write(MDCLOG_ERR, "Failed to send E2_TERM_KEEP_ALIVE_RESP RMR message returned NULL"); |
| } else if (rmrMessageBuffer.sendMessage->state != 0) { |
| mdclog_write(MDCLOG_ERR, "Failed to send E2_TERM_KEEP_ALIVE_RESP, on RMR state = %d ( %s)", |
| rmrMessageBuffer.sendMessage->state, translateRmrErrorMessages(rmrMessageBuffer.sendMessage->state).c_str()); |
| } else if (mdclog_level_get() >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "Got Keep Alive Request send : %s", rmrMessageBuffer.ka_message); |
| } |
| |
| break; |
| } |
| default: |
| mdclog_write(MDCLOG_WARN, "Message Type : %d is not seported", rmrMessageBuffer.rcvMessage->mtype); |
| message.message.asndata = rmrMessageBuffer.rcvMessage->payload; |
| message.message.asnLength = rmrMessageBuffer.rcvMessage->len; |
| message.message.time.tv_nsec = ts.tv_nsec; |
| message.message.time.tv_sec = ts.tv_sec; |
| message.message.messageType = rmrMessageBuffer.rcvMessage->mtype; |
| |
| buildJsonMessage(message); |
| |
| |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -7; |
| } |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "EXIT OK from %s", __FUNCTION__); |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return 0; |
| } |
| |
| /** |
| * Send message to the CU that is not expecting for successful or unsuccessful results |
| * @param messageBuffer |
| * @param message |
| * @param failedMsgId |
| * @param sctpMap |
| * @param pSpan |
| * @return |
| */ |
| int sendDirectionalSctpMsg(RmrMessagesBuffer_t &messageBuffer, |
| ReportingMessages_t &message, |
| int failedMsgId, |
| Sctp_Map_t *sctpMap, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| |
| getRequestMetaData(message, messageBuffer, &lspan); |
| if (mdclog_level_get() >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "send message to %s address", message.message.enodbName); |
| } |
| |
| auto rc = sendMessagetoCu(sctpMap, messageBuffer, message, failedMsgId, &lspan); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| return rc; |
| } |
| |
| /** |
| * |
| * @param sctpMap |
| * @param messageBuffer |
| * @param message |
| * @param failedMesgId |
| * @param pSpan |
| * @return |
| */ |
| int sendMessagetoCu(Sctp_Map_t *sctpMap, |
| RmrMessagesBuffer_t &messageBuffer, |
| ReportingMessages_t &message, |
| int failedMesgId, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| auto *peerInfo = (ConnectedCU_t *) sctpMap->find(message.message.enodbName); |
| if (peerInfo == nullptr) { |
| if (failedMesgId != 0) { |
| sendFailedSendingMessagetoXapp(messageBuffer, message, failedMesgId, &lspan); |
| } else { |
| mdclog_write(MDCLOG_ERR, "Failed to send message no CU entry %s", message.message.enodbName); |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| return -1; |
| } |
| |
| // get the FD |
| message.message.messageType = messageBuffer.rcvMessage->mtype; |
| auto rc = sendSctpMsg(peerInfo, message, sctpMap, &lspan); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| return rc; |
| } |
| |
| /** |
| * |
| * @param rmrCtx the rmr context to send and receive |
| * @param msg the msg we got fromxApp |
| * @param metaData data from xApp in ordered struct |
| * @param failedMesgId the return message type error |
| */ |
| void |
| sendFailedSendingMessagetoXapp(RmrMessagesBuffer_t &rmrMessageBuffer, ReportingMessages_t &message, int failedMesgId, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| rmr_mbuf_t *msg = rmrMessageBuffer.sendMessage; |
| msg->len = snprintf((char *) msg->payload, 200, "the gNb/eNode name %s not found", |
| message.message.enodbName); |
| if (mdclog_level_get() >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "%s", msg->payload); |
| } |
| msg->mtype = failedMesgId; |
| msg->state = 0; |
| |
| static unsigned char tx[32]; |
| snprintf((char *) tx, sizeof tx, "%15ld", transactionCounter++); |
| rmr_bytes2xact(msg, tx, strlen((const char *) tx)); |
| |
| sendRmrMessage(rmrMessageBuffer, message, &lspan); |
| #ifdef __TRACING__ |
| lspan->Finish();pLogSink |
| #endif |
| |
| } |
| |
| /** |
| * Send Response back to xApp, message is used only when there was a request from the xApp |
| * |
| * @param enodbName the name of the gNb/eNodeB |
| * @param msgType the value of the message to the xApp |
| * @param requestType The request that was sent by the xAPP |
| * @param rmrCtx the rmr identifier |
| * @param sctpMap hash map holds data on the requestrs |
| * @param buf the buffer to send to xAPP |
| * @param size size of the buffer to send |
| * @return |
| */ |
| /* |
| int sendResponseToXapp(ReportingMessages_t &message, |
| int msgType, |
| int requestType, |
| RmrMessagesBuffer_t &rmrMessageBuffer, |
| Sctp_Map_t *sctpMap, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| char key[MAX_ENODB_NAME_SIZE * 2]; |
| snprintf(key, MAX_ENODB_NAME_SIZE * 2, "msg:%s|%d", message.message.enodbName, requestType); |
| |
| auto xact = sctpMap->find(key); |
| if (xact == nullptr) { |
| mdclog_write(MDCLOG_ERR, "NO Request %s found for this response from CU: %s", key, |
| message.message.enodbName); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| |
| return -1; |
| } |
| sctpMap->erase(key); |
| |
| message.message.messageType = rmrMessageBuffer.sendMessage->mtype = msgType; //SETUP_RESPONSE_MESSAGE_TYPE; |
| rmr_bytes2payload(rmrMessageBuffer.sendMessage, (unsigned char *) message.message.asndata, |
| message.message.asnLength); |
| rmr_bytes2xact(rmrMessageBuffer.sendMessage, (const unsigned char *)xact, strlen((const char *)xact)); |
| rmr_str2meid(rmrMessageBuffer.sendMessage, (unsigned char *) message.message.enodbName); |
| rmrMessageBuffer.sendMessage->state = 0; |
| |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "remove key = %s from %s at line %d", key, __FUNCTION__, __LINE__); |
| } |
| free(xact); |
| |
| auto rc = sendRmrMessage(rmrMessageBuffer, message, &lspan); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return rc; |
| } |
| */ |
| |
| /** |
| * build the SCTP connection to eNodB or gNb |
| * @param rmrMessageBuffer |
| * @param message |
| * @param epoll_fd |
| * @param sctpMap |
| * @param pSpan |
| * @return |
| */ |
| int connectToCUandSetUp(RmrMessagesBuffer_t &rmrMessageBuffer, |
| ReportingMessages_t &message, |
| int epoll_fd, |
| Sctp_Map_t *sctpMap, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| struct sockaddr_in6 servaddr{}; |
| struct addrinfo hints{}, *result; |
| auto msgData = rmrMessageBuffer.rcvMessage->payload; |
| unsigned char meid[RMR_MAX_MEID]{}; |
| char host[256]{}; |
| uint16_t port = 0; |
| |
| message.message.messageType = rmrMessageBuffer.rcvMessage->mtype; |
| rmr_mbuf_t *msg = rmrMessageBuffer.rcvMessage; |
| rmr_get_meid(msg, meid); |
| |
| if (mdclog_level_get() >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "message %d Received for MEID :%s. SETUP/EN-DC Setup Request from xApp, Message = %s", |
| msg->mtype, meid, msgData); |
| } |
| if (getSetupRequestMetaData(message, (char *)msgData, host, port, &lspan) < 0) { |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Error in setup parameters %s, %d", __func__, __LINE__); |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| |
| //// message asndata points to the start of the asndata of the message and not to start of payload |
| // search if the same host:port but not the same enodbname |
| char searchBuff[256]{}; |
| snprintf(searchBuff, sizeof searchBuff, "host:%s:%d", host, port); |
| auto e = (char *)sctpMap->find(searchBuff); |
| if (e != nullptr) { |
| // found one compare if not the same |
| if (strcmp(message.message.enodbName, e) != 0) { |
| mdclog_write(MDCLOG_ERR, |
| "Try to connect CU %s to Host %s but %s already connected", |
| message.message.enodbName, host, e); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| } |
| |
| // check if not connected. if connected send the request and return |
| auto *peerInfo = (ConnectedCU_t *)sctpMap->find(message.message.enodbName); |
| if (peerInfo != nullptr) { |
| if (mdclog_level_get() >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "Device already connected to %s", |
| message.message.enodbName); |
| } |
| message.message.messageType = msg->mtype; |
| auto rc = sendSctpMsg(peerInfo, message, sctpMap, &lspan); |
| if (rc != 0) { |
| mdclog_write(MDCLOG_ERR, "failed write to SCTP %s, %d", __func__, __LINE__); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| |
| char key[MAX_ENODB_NAME_SIZE * 2]; |
| snprintf(key, MAX_ENODB_NAME_SIZE * 2, "msg:%s|%d", message.message.enodbName, msg->mtype); |
| int xaction_len = strlen((const char *) msg->xaction); |
| auto *xaction = (unsigned char *) calloc(1, xaction_len); |
| memcpy(xaction, msg->xaction, xaction_len); |
| sctpMap->setkey(key, xaction); |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "set key = %s from %s at line %d", key, __FUNCTION__, __LINE__); |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return 0; |
| } |
| |
| peerInfo = (ConnectedCU_t *) calloc(1, sizeof(ConnectedCU_t)); |
| memcpy(peerInfo->enodbName, message.message.enodbName, sizeof(message.message.enodbName)); |
| |
| // new connection |
| if ((peerInfo->fileDescriptor = socket(AF_INET6, SOCK_STREAM, IPPROTO_SCTP)) < 0) { |
| mdclog_write(MDCLOG_ERR, "Socket Error, %s %s, %d", strerror(errno), __func__, __LINE__); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| |
| auto optval = 1; |
| if (setsockopt(peerInfo->fileDescriptor, SOL_SOCKET, SO_REUSEPORT, &optval, sizeof optval) != 0) { |
| mdclog_write(MDCLOG_ERR, "setsockopt SO_REUSEPORT Error, %s %s, %d", strerror(errno), __func__, __LINE__); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| optval = 1; |
| if (setsockopt(peerInfo->fileDescriptor, SOL_SOCKET, SO_REUSEADDR, &optval, sizeof optval) != 0) { |
| mdclog_write(MDCLOG_ERR, "setsockopt SO_REUSEADDR Error, %s %s, %d", strerror(errno), __func__, __LINE__); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| servaddr.sin6_family = AF_INET6; |
| |
| struct sockaddr_in6 localAddr {}; |
| localAddr.sin6_family = AF_INET6; |
| localAddr.sin6_addr = in6addr_any; |
| localAddr.sin6_port = htons(SRC_PORT); |
| |
| if (bind(peerInfo->fileDescriptor, (struct sockaddr*)&localAddr , sizeof(struct sockaddr_in6)) < 0) { |
| mdclog_write(MDCLOG_ERR, "bind Socket Error, %s %s, %d", strerror(errno), __func__, __LINE__); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| }//Ends the binding. |
| |
| memset(&hints, 0, sizeof hints); |
| hints.ai_flags = AI_NUMERICHOST; |
| if (getaddrinfo(host, nullptr, &hints, &result) < 0) { |
| close(peerInfo->fileDescriptor); |
| mdclog_write(MDCLOG_ERR, "getaddrinfo error for %s, Error = %s", host, strerror(errno)); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| memcpy(&servaddr, result->ai_addr, sizeof(struct sockaddr_in6)); |
| freeaddrinfo(result); |
| |
| servaddr.sin6_port = htons(port); /* daytime server */ |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Send Connect FD = %d host : %s port %d", |
| peerInfo->fileDescriptor, |
| host, |
| port); |
| } |
| |
| // Add to Epol |
| if (addToEpoll(epoll_fd, peerInfo, (EPOLLOUT | EPOLLIN | EPOLLET), sctpMap, message.message.enodbName, |
| msg->mtype, &lspan) != 0) { |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| |
| char hostBuff[NI_MAXHOST]; |
| char portBuff[NI_MAXHOST]; |
| |
| if (getnameinfo((SA *) &servaddr, sizeof(servaddr), |
| hostBuff, sizeof(hostBuff), |
| portBuff, sizeof(portBuff), |
| (uint) (NI_NUMERICHOST) | (uint) (NI_NUMERICSERV)) != 0) { |
| mdclog_write(MDCLOG_ERR, "getnameinfo() Error, %s %s %d", strerror(errno), __func__, __LINE__); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| |
| if (setSocketNoBlocking(peerInfo->fileDescriptor) != 0) { |
| mdclog_write(MDCLOG_ERR, "setSocketNoBlocking failed to set new connection %s on sctpPort %s", hostBuff, |
| portBuff); |
| close(peerInfo->fileDescriptor); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| |
| memcpy(peerInfo->hostName, hostBuff, strlen(hostBuff)); |
| peerInfo->hostName[strlen(hostBuff)] = 0; |
| memcpy(peerInfo->portNumber, portBuff, strlen(portBuff)); |
| peerInfo->portNumber[strlen(portBuff)] = 0; |
| |
| // map by enoodb/gnb name |
| sctpMap->setkey(message.message.enodbName, peerInfo); |
| //map host and port to enodeb |
| sctpMap->setkey(searchBuff, message.message.enodbName); |
| |
| // save message for the return values |
| char key[MAX_ENODB_NAME_SIZE * 2]; |
| snprintf(key, MAX_ENODB_NAME_SIZE * 2, "msg:%s|%d", message.message.enodbName, msg->mtype); |
| int xaction_len = strlen((const char *) msg->xaction); |
| auto *xaction = (unsigned char *) calloc(1, xaction_len); |
| memcpy(xaction, msg->xaction, xaction_len); |
| sctpMap->setkey(key, xaction); |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "End building peerinfo: %s for CU %s", key, message.message.enodbName); |
| } |
| |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Send connect to FD %d, %s, %d", |
| peerInfo->fileDescriptor, __func__, __LINE__); |
| } |
| if (connect(peerInfo->fileDescriptor, (SA *) &servaddr, sizeof(servaddr)) < 0) { |
| if (errno != EINPROGRESS) { |
| mdclog_write(MDCLOG_ERR, "connect FD %d to host : %s port %d, %s", |
| peerInfo->fileDescriptor, host, port, strerror(errno)); |
| close(peerInfo->fileDescriptor); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, |
| "Connect to FD %d returned with EINPROGRESS : %s", |
| peerInfo->fileDescriptor, strerror(errno)); |
| } |
| // since message.message.asndata is pointing to the asndata in the rmr message payload we copy it like this |
| memcpy(peerInfo->asnData, message.message.asndata, message.message.asnLength); |
| peerInfo->asnLength = message.message.asnLength; |
| peerInfo->mtype = msg->mtype; |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return 0; |
| } |
| |
| if (mdclog_level_get() >= MDCLOG_INFO) { |
| mdclog_write(MDCLOG_INFO, "Connect to FD %d returned OK without EINPROGRESS", peerInfo->fileDescriptor); |
| } |
| |
| peerInfo->isConnected = true; |
| |
| if (modifyToEpoll(epoll_fd, peerInfo, (EPOLLIN | EPOLLET), sctpMap, message.message.enodbName, msg->mtype, |
| &lspan) != 0) { |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Connected to host : %s port %d", host, port); |
| } |
| |
| message.message.messageType = msg->mtype; |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Send SCTP message to FD %d", peerInfo->fileDescriptor); |
| } |
| if (sendSctpMsg(peerInfo, message, sctpMap, &lspan) != 0) { |
| mdclog_write(MDCLOG_ERR, "Error write to SCTP %s %d", __func__, __LINE__); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| memset(peerInfo->asnData, 0, message.message.asnLength); |
| peerInfo->asnLength = 0; |
| peerInfo->mtype = 0; |
| |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "Sent message to SCTP for %s", message.message.enodbName); |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return 0; |
| } |
| |
| /** |
| * |
| * @param epoll_fd |
| * @param peerInfo |
| * @param events |
| * @param sctpMap |
| * @param enodbName |
| * @param msgType |
| * @param pSpan |
| * @return |
| */ |
| int addToEpoll(int epoll_fd, |
| ConnectedCU_t *peerInfo, |
| uint32_t events, |
| Sctp_Map_t *sctpMap, |
| char *enodbName, |
| int msgType, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| // Add to Epol |
| struct epoll_event event{}; |
| event.data.ptr = peerInfo; |
| event.events = events; |
| if (epoll_ctl(epoll_fd, EPOLL_CTL_ADD, peerInfo->fileDescriptor, &event) < 0) { |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "epoll_ctl EPOLL_CTL_ADD (may chack not to quit here), %s, %s %d", |
| strerror(errno), __func__, __LINE__); |
| } |
| close(peerInfo->fileDescriptor); |
| if (enodbName != nullptr) { |
| cleanHashEntry(peerInfo, sctpMap, &lspan); |
| char key[MAX_ENODB_NAME_SIZE * 2]; |
| snprintf(key, MAX_ENODB_NAME_SIZE * 2, "msg:%s|%d", enodbName, msgType); |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "remove key = %s from %s at line %d", key, __FUNCTION__, __LINE__); |
| } |
| auto tmp = sctpMap->find(key); |
| if (tmp) { |
| free(tmp); |
| sctpMap->erase(key); |
| } |
| } else { |
| peerInfo->enodbName[0] = 0; |
| } |
| mdclog_write(MDCLOG_ERR, "epoll_ctl EPOLL_CTL_ADD (may chack not to quit here)"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return 0; |
| } |
| |
| /** |
| * |
| * @param epoll_fd |
| * @param peerInfo |
| * @param events |
| * @param sctpMap |
| * @param enodbName |
| * @param msgType |
| * @param pSpan |
| * @return |
| */ |
| int modifyToEpoll(int epoll_fd, |
| ConnectedCU_t *peerInfo, |
| uint32_t events, |
| Sctp_Map_t *sctpMap, |
| char *enodbName, |
| int msgType, |
| otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| otSpan lspan = 0; |
| #endif |
| // Add to Epol |
| struct epoll_event event{}; |
| event.data.ptr = peerInfo; |
| event.events = events; |
| if (epoll_ctl(epoll_fd, EPOLL_CTL_MOD, peerInfo->fileDescriptor, &event) < 0) { |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "epoll_ctl EPOLL_CTL_MOD (may chack not to quit here), %s, %s %d", |
| strerror(errno), __func__, __LINE__); |
| } |
| close(peerInfo->fileDescriptor); |
| cleanHashEntry(peerInfo, sctpMap, &lspan); |
| char key[MAX_ENODB_NAME_SIZE * 2]; |
| snprintf(key, MAX_ENODB_NAME_SIZE * 2, "msg:%s|%d", enodbName, msgType); |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "remove key = %s from %s at line %d", key, __FUNCTION__, __LINE__); |
| } |
| auto tmp = sctpMap->find(key); |
| if (tmp) { |
| free(tmp); |
| } |
| sctpMap->erase(key); |
| mdclog_write(MDCLOG_ERR, "epoll_ctl EPOLL_CTL_ADD (may chack not to quit here)"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return 0; |
| } |
| |
| |
| int sendRmrMessage(RmrMessagesBuffer_t &rmrMessageBuffer, ReportingMessages_t &message, otSpan *pSpan) { |
| #ifdef __TRACING__ |
| auto lspan = opentracing::Tracer::Global()->StartSpan( |
| __FUNCTION__, { opentracing::ChildOf(&pSpan->get()->context()) }); |
| #else |
| // otSpan lspan = 0; |
| #endif |
| //serialize the span |
| #ifdef __TRACING__ |
| std::unordered_map<std::string, std::string> data; |
| RICCarrierWriter carrier(data); |
| opentracing::Tracer::Global()->Inject((lspan.get())->context(), carrier); |
| nlohmann::json j = data; |
| std::string str = j.dump(); |
| static auto maxTraceLength = 0; |
| |
| maxTraceLength = str.length() > maxTraceLength ? str.length() : maxTraceLength; |
| // serialized context can be put to RMR message using function: |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "max trace length is %d trace data length = %ld data = %s", maxTraceLength, |
| str.length(), str.c_str()); |
| } |
| rmr_set_trace(rmrMessageBuffer.sendMessage, (const unsigned char *) str.c_str(), str.length()); |
| #endif |
| buildJsonMessage(message); |
| |
| rmrMessageBuffer.sendMessage = rmr_send_msg(rmrMessageBuffer.rmrCtx, rmrMessageBuffer.sendMessage); |
| |
| if (rmrMessageBuffer.sendMessage == nullptr) { |
| rmrMessageBuffer.sendMessage = rmr_alloc_msg(rmrMessageBuffer.rmrCtx, RECEIVE_XAPP_BUFFER_SIZE); |
| mdclog_write(MDCLOG_ERR, "RMR failed send message returned with NULL pointer"); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } |
| |
| if (rmrMessageBuffer.sendMessage->state != 0) { |
| char meid[RMR_MAX_MEID]{}; |
| if (rmrMessageBuffer.sendMessage->state == RMR_ERR_RETRY) { |
| usleep(5); |
| rmrMessageBuffer.sendMessage->state = 0; |
| mdclog_write(MDCLOG_INFO, "RETRY sending Message type %d to Xapp from %s", |
| rmrMessageBuffer.sendMessage->mtype, |
| rmr_get_meid(rmrMessageBuffer.sendMessage, (unsigned char *)meid)); |
| rmrMessageBuffer.sendMessage = rmr_send_msg(rmrMessageBuffer.rmrCtx, rmrMessageBuffer.sendMessage); |
| if (rmrMessageBuffer.sendMessage == nullptr) { |
| mdclog_write(MDCLOG_ERR, "RMR failed send message returned with NULL pointer"); |
| rmrMessageBuffer.sendMessage = rmr_alloc_msg(rmrMessageBuffer.rmrCtx, RECEIVE_XAPP_BUFFER_SIZE); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return -1; |
| } else if (rmrMessageBuffer.sendMessage->state != 0) { |
| mdclog_write(MDCLOG_ERR, |
| "Message state %s while sending request %d to Xapp from %s after retry of 10 microseconds", |
| translateRmrErrorMessages(rmrMessageBuffer.sendMessage->state).c_str(), |
| rmrMessageBuffer.sendMessage->mtype, |
| rmr_get_meid(rmrMessageBuffer.sendMessage, (unsigned char *)meid)); |
| auto rc = rmrMessageBuffer.sendMessage->state; |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return rc; |
| } |
| } else { |
| mdclog_write(MDCLOG_ERR, "Message state %s while sending request %d to Xapp from %s", |
| translateRmrErrorMessages(rmrMessageBuffer.sendMessage->state).c_str(), |
| rmrMessageBuffer.sendMessage->mtype, |
| rmr_get_meid(rmrMessageBuffer.sendMessage, (unsigned char *)meid)); |
| #ifdef __TRACING__ |
| lspan->Finish(); |
| #endif |
| return rmrMessageBuffer.sendMessage->state; |
| } |
| } |
| return 0; |
| } |
| |
| void buildJsonMessage(ReportingMessages_t &message) { |
| if (jsonTrace) { |
| message.outLen = sizeof(message.base64Data); |
| base64::encode((const unsigned char *) message.message.asndata, |
| (const int) message.message.asnLength, |
| message.base64Data, |
| message.outLen); |
| if (mdclog_level_get() >= MDCLOG_DEBUG) { |
| mdclog_write(MDCLOG_DEBUG, "asn data length = %d, base64 message length = %d ", |
| (int) message.message.asnLength, |
| (int) message.outLen); |
| } |
| |
| snprintf(message.buffer, sizeof(message.buffer), |
| "{\"header\": {\"ts\": \"%ld.%09ld\"," |
| "\"ranName\": \"%s\"," |
| "\"messageType\": %d," |
| "\"direction\": \"%c\"}," |
| "\"base64Length\": %d," |
| "\"asnBase64\": \"%s\"}", |
| message.message.time.tv_sec, |
| message.message.time.tv_nsec, |
| message.message.enodbName, |
| message.message.messageType, |
| message.message.direction, |
| (int) message.outLen, |
| message.base64Data); |
| static src::logger_mt &lg = my_logger::get(); |
| |
| BOOST_LOG(lg) << message.buffer; |
| } |
| } |
| |
| |
| /** |
| * take RMR error code to string |
| * @param state |
| * @return |
| */ |
| string translateRmrErrorMessages(int state) { |
| string str = {}; |
| switch (state) { |
| case RMR_OK: |
| str = "RMR_OK - state is good"; |
| break; |
| case RMR_ERR_BADARG: |
| str = "RMR_ERR_BADARG - argument passd to function was unusable"; |
| break; |
| case RMR_ERR_NOENDPT: |
| str = "RMR_ERR_NOENDPT - send//call could not find an endpoint based on msg type"; |
| break; |
| case RMR_ERR_EMPTY: |
| str = "RMR_ERR_EMPTY - msg received had no payload; attempt to send an empty message"; |
| break; |
| case RMR_ERR_NOHDR: |
| str = "RMR_ERR_NOHDR - message didn't contain a valid header"; |
| break; |
| case RMR_ERR_SENDFAILED: |
| str = "RMR_ERR_SENDFAILED - send failed; errno has nano reason"; |
| break; |
| case RMR_ERR_CALLFAILED: |
| str = "RMR_ERR_CALLFAILED - unable to send call() message"; |
| break; |
| case RMR_ERR_NOWHOPEN: |
| str = "RMR_ERR_NOWHOPEN - no wormholes are open"; |
| break; |
| case RMR_ERR_WHID: |
| str = "RMR_ERR_WHID - wormhole id was invalid"; |
| break; |
| case RMR_ERR_OVERFLOW: |
| str = "RMR_ERR_OVERFLOW - operation would have busted through a buffer/field size"; |
| break; |
| case RMR_ERR_RETRY: |
| str = "RMR_ERR_RETRY - request (send/call/rts) failed, but caller should retry (EAGAIN for wrappers)"; |
| break; |
| case RMR_ERR_RCVFAILED: |
| str = "RMR_ERR_RCVFAILED - receive failed (hard error)"; |
| break; |
| case RMR_ERR_TIMEOUT: |
| str = "RMR_ERR_TIMEOUT - message processing call timed out"; |
| break; |
| case RMR_ERR_UNSET: |
| str = "RMR_ERR_UNSET - the message hasn't been populated with a transport buffer"; |
| break; |
| case RMR_ERR_TRUNC: |
| str = "RMR_ERR_TRUNC - received message likely truncated"; |
| break; |
| case RMR_ERR_INITFAILED: |
| str = "RMR_ERR_INITFAILED - initialisation of something (probably message) failed"; |
| break; |
| case RMR_ERR_NOTSUPP: |
| str = "RMR_ERR_NOTSUPP - the request is not supported, or RMr was not initialised for the request"; |
| break; |
| default: |
| char buf[128]{}; |
| snprintf(buf, sizeof buf, "UNDOCUMENTED RMR_ERR : %d", state); |
| str = buf; |
| break; |
| } |
| return str; |
| } |
| |
| |