Pid: 3385 Tid: 3385 VMA INFO: ---------------------------------------------------------------------------  Pid: 3385 Tid: 3385 VMA INFO: VMA_VERSION: 7.0.14-0 Release built on Feb 25 2016 18:31:12  Pid: 3385 Tid: 3385 VMA INFO: Cmd Line: ./ping-pong 172.25.24.126 3426 1  Pid: 3385 Tid: 3385 VMA DEBUG: Current Time: Fri Apr 1 14:32:38 2016  Pid: 3385 Tid: 3385 VMA DEBUG: Pid: 3385  Pid: 3385 Tid: 3385 VMA INFO: OFED Version: MLNX_OFED_LINUX-3.2-2.0.0.0:  Pid: 3385 Tid: 3385 VMA DEBUG: System: 2.6.32-573.el6.x86_64  Pid: 3385 Tid: 3385 VMA DEBUG: Architecture: x86_64  Pid: 3385 Tid: 3385 VMA DEBUG: Node: ofed  Pid: 3385 Tid: 3385 VMA DEBUG: ---------------------------------------------------------------------------  Pid: 3385 Tid: 3385 VMA INFO: Log Level DEBUG [VMA_TRACELEVEL]  Pid: 3385 Tid: 3385 VMA INFO: Log Details 2 [VMA_LOG_DETAILS]  Pid: 3385 Tid: 3385 VMA DETAILS: Log Colors Enabled [VMA_LOG_COLORS]  Pid: 3385 Tid: 3385 VMA INFO: Log File vmalog [VMA_LOG_FILE]  Pid: 3385 Tid: 3385 VMA DETAILS: Stats File [VMA_STATS_FILE]  Pid: 3385 Tid: 3385 VMA DETAILS: Stats shared memory directory /tmp/ [VMA_STATS_SHMEM_DIR]  Pid: 3385 Tid: 3385 VMA DETAILS: Stats FD Num (max) 100 [VMA_STATS_FD_NUM]  Pid: 3385 Tid: 3385 VMA DETAILS: Conf File /etc/libvma.conf [VMA_CONFIG_FILE]  Pid: 3385 Tid: 3385 VMA DETAILS: Application ID VMA_DEFAULT_APPLICATION_ID [VMA_APPLICATION_ID]  Pid: 3385 Tid: 3385 VMA DETAILS: Polling CPU idle usage Disabled [VMA_CPU_USAGE_STATS]  Pid: 3385 Tid: 3385 VMA DETAILS: SigIntr Ctrl-C Handle Disabled [VMA_HANDLE_SIGINTR]  Pid: 3385 Tid: 3385 VMA DETAILS: SegFault Backtrace Disabled [VMA_HANDLE_SIGSEGV]  Pid: 3385 Tid: 3385 VMA DETAILS: Ring allocation logic TX 0 (Ring per interface) [VMA_RING_ALLOCATION_LOGIC_TX]  Pid: 3385 Tid: 3385 VMA DETAILS: Ring allocation logic RX 0 (Ring per interface) [VMA_RING_ALLOCATION_LOGIC_RX]  Pid: 3385 Tid: 3385 VMA DETAILS: Ring migration ratio TX 100 [VMA_RING_MIGRATION_RATIO_TX]  Pid: 3385 Tid: 3385 VMA DETAILS: Ring migration ratio RX 100 [VMA_RING_MIGRATION_RATIO_RX]  Pid: 3385 Tid: 3385 VMA DETAILS: Ring limit per interface 0 (no limit) [VMA_RING_LIMIT_PER_INTERFACE]  Pid: 3385 Tid: 3385 VMA DETAILS: TCP max syn rate 0 (no limit) [VMA_TCP_MAX_SYN_RATE]  Pid: 3385 Tid: 3385 VMA DETAILS: Tx Mem Segs TCP 1000000 [VMA_TX_SEGS_TCP]  Pid: 3385 Tid: 3385 VMA DETAILS: Tx Mem Bufs 200000 [VMA_TX_BUFS]  Pid: 3385 Tid: 3385 VMA DETAILS: Tx QP WRE 3000 [VMA_TX_WRE]  Pid: 3385 Tid: 3385 VMA DETAILS: Tx Max QP INLINE 220 [VMA_TX_MAX_INLINE]  Pid: 3385 Tid: 3385 VMA DETAILS: Tx MC Loopback Enabled [VMA_TX_MC_LOOPBACK]  Pid: 3385 Tid: 3385 VMA DETAILS: Tx non-blocked eagains Disabled [VMA_TX_NONBLOCKED_EAGAINS]  Pid: 3385 Tid: 3385 VMA DETAILS: Tx Prefetch Bytes 256 [VMA_TX_PREFETCH_BYTES]  Pid: 3385 Tid: 3385 VMA DETAILS: Rx Mem Bufs 200000 [VMA_RX_BUFS]  Pid: 3385 Tid: 3385 VMA DETAILS: Rx QP WRE 16000 [VMA_RX_WRE]  Pid: 3385 Tid: 3385 VMA DETAILS: Rx QP WRE BATCHING 64 [VMA_RX_WRE_BATCHING]  Pid: 3385 Tid: 3385 VMA DETAILS: Rx Byte Min Limit 65536 [VMA_RX_BYTES_MIN]  Pid: 3385 Tid: 3385 VMA DETAILS: Rx Poll Loops 100000 [VMA_RX_POLL]  Pid: 3385 Tid: 3385 VMA DETAILS: Rx Poll Init Loops 0 [VMA_RX_POLL_INIT]  Pid: 3385 Tid: 3385 VMA DETAILS: Rx UDP Poll OS Ratio 100 [VMA_RX_UDP_POLL_OS_RATIO]  Pid: 3385 Tid: 3385 VMA DETAILS: Rx UDP HW TS Conversion 3 [VMA_RX_UDP_HW_TS_CONVERSION]  Pid: 3385 Tid: 3385 VMA DETAILS: Rx Poll Yield Disabled [VMA_RX_POLL_YIELD]  Pid: 3385 Tid: 3385 VMA DETAILS: Rx Prefetch Bytes 256 [VMA_RX_PREFETCH_BYTES]  Pid: 3385 Tid: 3385 VMA DETAILS: Rx Prefetch Bytes Before Poll 0 [VMA_RX_PREFETCH_BYTES_BEFORE_POLL]  Pid: 3385 Tid: 3385 VMA DETAILS: Rx CQ Drain Rate Disabled [VMA_RX_CQ_DRAIN_RATE_NSEC]  Pid: 3385 Tid: 3385 VMA DETAILS: GRO max streams 32 [VMA_GRO_STREAMS_MAX]  Pid: 3385 Tid: 3385 VMA DETAILS: TCP 3T rules Disabled [VMA_TCP_3T_RULES]  Pid: 3385 Tid: 3385 VMA DETAILS: ETH MC L2 only rules Disabled [VMA_ETH_MC_L2_ONLY_RULES]  Pid: 3385 Tid: 3385 VMA DETAILS: Select Poll (usec) 100000 [VMA_SELECT_POLL]  Pid: 3385 Tid: 3385 VMA DETAILS: Select Poll OS Force Disabled [VMA_SELECT_POLL_OS_FORCE]  Pid: 3385 Tid: 3385 VMA DETAILS: Select Poll OS Ratio 10 [VMA_SELECT_POLL_OS_RATIO]  Pid: 3385 Tid: 3385 VMA DETAILS: Select Skip OS 4 [VMA_SELECT_SKIP_OS]  Pid: 3385 Tid: 3385 VMA DETAILS: CQ Drain Interval (msec) 10 [VMA_PROGRESS_ENGINE_INTERVAL]  Pid: 3385 Tid: 3385 VMA DETAILS: CQ Drain WCE (max) 10000 [VMA_PROGRESS_ENGINE_WCE_MAX]  Pid: 3385 Tid: 3385 VMA DETAILS: CQ Interrupts Moderation Enabled [VMA_CQ_MODERATION_ENABLE]  Pid: 3385 Tid: 3385 VMA DETAILS: CQ Moderation Count 48 [VMA_CQ_MODERATION_COUNT]  Pid: 3385 Tid: 3385 VMA DETAILS: CQ Moderation Period (usec) 50 [VMA_CQ_MODERATION_PERIOD_USEC]  Pid: 3385 Tid: 3385 VMA DETAILS: CQ AIM Max Count 560 [VMA_CQ_AIM_MAX_COUNT]  Pid: 3385 Tid: 3385 VMA DETAILS: CQ AIM Max Period (usec) 250 [VMA_CQ_AIM_MAX_PERIOD_USEC]  Pid: 3385 Tid: 3385 VMA DETAILS: CQ AIM Interval (msec) 250 [VMA_CQ_AIM_INTERVAL_MSEC]  Pid: 3385 Tid: 3385 VMA DETAILS: CQ AIM Interrupts Rate (per sec) 5000 [VMA_CQ_AIM_INTERRUPTS_RATE_PER_SEC]  Pid: 3385 Tid: 3385 VMA DETAILS: CQ Poll Batch (max) 16 [VMA_CQ_POLL_BATCH_MAX]  Pid: 3385 Tid: 3385 VMA DETAILS: CQ Keeps QP Full Enabled [VMA_CQ_KEEP_QP_FULL]  Pid: 3385 Tid: 3385 VMA DETAILS: QP Compensation Level 256 [VMA_QP_COMPENSATION_LEVEL]  Pid: 3385 Tid: 3385 VMA DETAILS: Offloaded Sockets Enabled [VMA_OFFLOADED_SOCKETS]  Pid: 3385 Tid: 3385 VMA DETAILS: Timer Resolution (msec) 10 [VMA_TIMER_RESOLUTION_MSEC]  Pid: 3385 Tid: 3385 VMA DETAILS: TCP Timer Resolution (msec) 100 [VMA_TCP_TIMER_RESOLUTION_MSEC]  Pid: 3385 Tid: 3385 VMA DETAILS: TCP control thread 0 (Disabled) [VMA_TCP_CTL_THREAD]  Pid: 3385 Tid: 3385 VMA DETAILS: TCP timestamp option 0 [VMA_TCP_TIMESTAMP_OPTION]  Pid: 3385 Tid: 3385 VMA DETAILS: Exception handling mode -1(just log debug message) [VMA_EXCEPTION_HANDLING]  Pid: 3385 Tid: 3385 VMA DETAILS: Avoid sys-calls on tcp fd Disabled [VMA_AVOID_SYS_CALLS_ON_TCP_FD]  Pid: 3385 Tid: 3385 VMA DETAILS: Delay after join (msec) 0 [VMA_WAIT_AFTER_JOIN_MSEC]  Pid: 3385 Tid: 3385 VMA DETAILS: Internal Thread Affinity -1 [VMA_INTERNAL_THREAD_AFFINITY]  Pid: 3385 Tid: 3385 VMA DETAILS: Internal Thread Cpuset [VMA_INTERNAL_THREAD_CPUSET]  Pid: 3385 Tid: 3385 VMA DETAILS: Internal Thread Arm CQ Disabled [VMA_INTERNAL_THREAD_ARM_CQ]  Pid: 3385 Tid: 3385 VMA DETAILS: Thread mode Multi spin lock [VMA_THREAD_MODE]  Pid: 3385 Tid: 3385 VMA DETAILS: Buffer batching mode 1 (Batch and reclaim buffers) [VMA_BUFFER_BATCHING_MODE]  Pid: 3385 Tid: 3385 VMA DETAILS: Mem Allocate type 1 (Contig Pages) [VMA_MEM_ALLOC_TYPE]  Pid: 3385 Tid: 3385 VMA DETAILS: Num of UC ARPs 3 [VMA_NEIGH_UC_ARP_QUATA]  Pid: 3385 Tid: 3385 VMA DETAILS: UC ARP delay (msec) 10000 [VMA_NEIGH_UC_ARP_DELAY_MSEC]  Pid: 3385 Tid: 3385 VMA DETAILS: Num of neigh restart retries 1 [VMA_NEIGH_NUM_ERR_RETRIES]  Pid: 3385 Tid: 3385 VMA DETAILS: IPOIB support Enabled [VMA_IPOIB]  Pid: 3385 Tid: 3385 VMA DETAILS: BF (Blue Flame) Enabled [VMA_BF]  Pid: 3385 Tid: 3385 VMA DETAILS: fork() support Enabled [VMA_FORK]  Pid: 3385 Tid: 3385 VMA DETAILS: close on dup2() Enabled [VMA_CLOSE_ON_DUP2]  Pid: 3385 Tid: 3385 VMA DETAILS: MTU 0 (follow actual MTU) [VMA_MTU]  Pid: 3385 Tid: 3385 VMA DETAILS: MSS 0 (follow VMA_MTU) [VMA_MSS]  Pid: 3385 Tid: 3385 VMA DETAILS: TCP CC Algorithm 0 (LWIP) [VMA_TCP_CC_ALGO]  Pid: 3385 Tid: 3385 VMA DETAILS: Suppress IGMP ver. warning Disabled [VMA_SUPPRESS_IGMP_WARNING]  Pid: 3385 Tid: 3385 VMA INFO: ---------------------------------------------------------------------------  Pid: 3385 Tid: 3385 VMA DEBUG: srdr:114:get_orig_funcs() dlsym found (nil) for 'sendmmsg()'  Pid: 3385 Tid: 3385 VMA WARNING: *************************************************************  Pid: 3385 Tid: 3385 VMA WARNING: * VMA is currently configured with high log level *  Pid: 3385 Tid: 3385 VMA WARNING: * Application performance will decrease in this log level! *  Pid: 3385 Tid: 3385 VMA WARNING: * This log level is recommended for debugging purposes only *  Pid: 3385 Tid: 3385 VMA WARNING: *************************************************************  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/module/mlx4_core/parameters/log_num_mgm_entry_size, flags=0, mode=0x2) = 4  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=4)  Pid: 3385 Tid: 3385 VMA DEBUG: sock_redirect_main()  Pid: 3385 Tid: 3385 VMA DEBUG: ibv_fork_init() succeeded, fork() may be used safely!!  Pid: 3385 Tid: 3385 VMA DEBUG: wakeup_pipe[epfd=0]:48:wakeup_pipe() created wakeup pipe [RD=4, WR=5]  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/tmp//vmastat.3385, flags=0x42, mode=0x1f6) = 7  Pid: 3385 Tid: 3385 VMA DEBUG: vma_shmem_stats_open: file '/tmp//vmastat.3385' fd 7 shared memory at 0x7fe28949d000 with 100 max blocks  Pid: 3385 Tid: 3385 VMA DEBUG: evh:59:register_timer_event() timer handler '0x2307830' registered PERIODIC timer for 10 msec (user data: 0)  Pid: 3385 Tid: 3385 VMA DEBUG: evh:314:start_thread() VMA Internal thread affinity not set.  Pid: 3385 Tid: 3385 VMA DEBUG: evh:335:start_thread() Started event handler thread  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3385 VMA DEBUG: nl_wrapper:122:netlink_wrapper() ---> netlink_route_listener CTOR  Pid: 3385 Tid: 3385 VMA DEBUG: nl_wrapper:126:netlink_wrapper() <--- netlink_route_listener CTOR  Pid: 3385 Tid: 3390 VMA DEBUG: evh:251:event_handler_thread() Entering internal thread, id = 140610911995648  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: remove_wakeup_fd()  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/misc/rdma_cm/abi_version, flags=0, mode=0) = 8  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=8)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/infiniband_verbs/abi_version, flags=0, mode=0) = 8  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=8)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/infiniband_verbs/uverbs0/ibdev, flags=0, mode=0) = 9  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=9)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/infiniband_verbs/uverbs0/abi_version, flags=0, mode=0) = 9  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=9)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/infiniband_verbs/uverbs0/device/vendor, flags=0, mode=0x23096f0) = 8  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=8)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/infiniband_verbs/uverbs0/device/device, flags=0, mode=0x2309760) = 8  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=8)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/infiniband/mlx4_0/node_type, flags=0, mode=0) = 8  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=8)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/infiniband/mlx4_0/node_guid, flags=0, mode=0) = 8  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=8)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/dev/infiniband/rdma_cm, flags=0x80002, mode=0x88591e88) = 8  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=8)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/infiniband/mlx4_0/node_guid, flags=0, mode=0) = 8  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=8)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/dev/infiniband/uverbs0, flags=0x2, mode=0) = 8  Pid: 3385 Tid: 3385 VMA DEBUG: ib_ctx_time_converter::get_devices_convertor_status : Checking RX UDP HW time stamp status for all devices [1], ibv_context_list = 0x230f310  Pid: 3385 Tid: 3385 VMA DEBUG: ib_ctx_collection[0x2307b00]:77:map_ib_devices() TS converter status was set to 3  Pid: 3385 Tid: 3385 VMA DEBUG: ib_ctx_collection[0x2307b00]:79:map_ib_devices() Mapping 1 ibv devices  Pid: 3385 Tid: 3385 VMA DEBUG: evh:59:register_timer_event() timer handler '0x230f578' registered ONE SHOT timer for 1000 msec (user data: 0)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3385 VMA DEBUG: evh:59:register_timer_event() timer handler '0x230f578' registered PERIODIC timer for 10000 msec (user data: 0)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: remove_wakeup_fd()  Pid: 3385 Tid: 3385 VMA DEBUG: ib_ctx_handler[0x230f330]:67:ib_ctx_handler() ibv device 'mlx4_0' [0x2309a20] has 2 ports. Vendor Part Id: 4099, FW Ver: 2.36.5000, max_qp_wr=16351, hca_core_clock (per sec)=258000000  Pid: 3385 Tid: 3385 VMA DEBUG: ib_ctx_handler[0x230f330]:136:set_dev_configuration() Setting configuration for the MLX card mlx4_0  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3390 VMA DEBUG: evh:448:priv_prepare_ibverbs_async_event_queue()  Pid: 3385 Tid: 3390 VMA DEBUG: utils:241:set_fd_block_mode() fd[9]: setting to non-blocking mode (0)  Pid: 3385 Tid: 3390 VMA DEBUG: evh:468:priv_prepare_ibverbs_async_event_queue() Emptied 0 Events  Pid: 3385 Tid: 3390 VMA DEBUG: evh:488:priv_register_ibverbs_events() 9 added to event_handler_map_t!  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: remove_wakeup_fd()  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/dev/infiniband/rdma_cm, flags=0x80002, mode=0x88591e88) = 10  Pid: 3385 Tid: 3385 VMA DEBUG: ntm:51:neigh_table_mgr() Creation of neigh_cma_event_channel on fd=10  Pid: 3385 Tid: 3385 VMA DEBUG: evh:59:register_timer_event() timer handler '0x230f678' registered PERIODIC timer for 100000 msec (user data: 0)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: remove_wakeup_fd()  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'lo' (addr=1.0.0.0, flags=10049)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:150:map_net_devices() Blocking offload: Interface ('lo') is not of type AF_INET  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'eth0' (addr=2.0.0.0, flags=11843)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:150:map_net_devices() Blocking offload: Interface ('eth0') is not of type AF_INET  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'eth1' (addr=3.0.0.0, flags=11843)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:150:map_net_devices() Blocking offload: Interface ('eth1') is not of type AF_INET  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'eth2' (addr=4.0.0.0, flags=1002)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:150:map_net_devices() Blocking offload: Interface ('eth2') is not of type AF_INET  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'bond0' (addr=5.0.0.0, flags=11443)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:150:map_net_devices() Blocking offload: Interface ('bond0') is not of type AF_INET  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'eth3' (addr=6.0.0.0, flags=1002)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:150:map_net_devices() Blocking offload: Interface ('eth3') is not of type AF_INET  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'eth4' (addr=7.0.0.0, flags=1002)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:150:map_net_devices() Blocking offload: Interface ('eth4') is not of type AF_INET  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'eth5' (addr=8.0.0.0, flags=1002)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:150:map_net_devices() Blocking offload: Interface ('eth5') is not of type AF_INET  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'bond0.380' (addr=9.0.0.0, flags=11043)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:150:map_net_devices() Blocking offload: Interface ('bond0.380') is not of type AF_INET  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'lo' (addr=127.0.0.1, flags=10049)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/dev/infiniband/rdma_cm, flags=0x80002, mode=0x88591ef8) = 14  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:190:map_net_devices() Blocking offload: No verbs context in cma_id on interfaces ('lo')  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'bond0' (addr=5.6.7.8, flags=11443)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:671:get_vlan_base_name_from_ifname() Failure in ioctl(SIOCGIFVLAN, cmd=GET_VLAN_REALDEV_NAME_CMD) for interface 'bond0' (errno=22 Invalid argument)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/type, flags=0, mode=0x20) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ndv[]:81:configure()  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/mtu, flags=0, mode=0x20) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:671:get_vlan_base_name_from_ifname() Failure in ioctl(SIOCGIFVLAN, cmd=GET_VLAN_REALDEV_NAME_CMD) for interface 'bond0' (errno=22 Invalid argument)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/bonding/mode, flags=0, mode=0x1000) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/bonding/fail_over_mac, flags=0, mode=0x1000) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/bonding/xmit_hash_policy, flags=0, mode=0x1000) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: got bond xmit hash policy = 0  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/bonding/slaves, flags=0, mode=0x100) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/bonding/active_slave, flags=0, mode=0x10) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ndv[bond0]:152:configure() failed to find the active slave, Moving to LAG state  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/address, flags=0, mode=0x100) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:892:get_local_ll_addr() ifname=eth1 un-aliased-ifname=eth1 l2_addr_path=/sys/class/net/eth1/address l2-addr=f4:52:14:38:53:11 (addr-bytes_len=6)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:905:get_local_ll_addr() found ETH UC addressf4:52:14:38:53:11 for interface eth1  Pid: 3385 Tid: 3385 VMA DEBUG: utils:671:get_vlan_base_name_from_ifname() Failure in ioctl(SIOCGIFVLAN, cmd=GET_VLAN_REALDEV_NAME_CMD) for interface 'eth1' (errno=22 Invalid argument)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/device/resource, flags=0, mode=0x400) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/infiniband/mlx4_0/device/resource, flags=0, mode=0x400) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/dev_port, flags=0, mode=0x18) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/address, flags=0, mode=0x100) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:892:get_local_ll_addr() ifname=eth0 un-aliased-ifname=eth0 l2_addr_path=/sys/class/net/eth0/address l2-addr=f4:52:14:38:53:11 (addr-bytes_len=6)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:905:get_local_ll_addr() found ETH UC addressf4:52:14:38:53:11 for interface eth0  Pid: 3385 Tid: 3385 VMA DEBUG: utils:671:get_vlan_base_name_from_ifname() Failure in ioctl(SIOCGIFVLAN, cmd=GET_VLAN_REALDEV_NAME_CMD) for interface 'eth0' (errno=22 Invalid argument)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/device/resource, flags=0, mode=0x400) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/infiniband/mlx4_0/device/resource, flags=0, mode=0x400) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/dev_port, flags=0, mode=0x18) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/address, flags=0, mode=0x100) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:892:get_local_ll_addr() ifname=bond0 un-aliased-ifname=bond0 l2_addr_path=/sys/class/net/bond0/address l2-addr=f4:52:14:38:53:11 (addr-bytes_len=6)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:905:get_local_ll_addr() found ETH UC addressf4:52:14:38:53:11 for interface bond0  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/broadcast, flags=0, mode=0x100) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:892:get_local_ll_addr() ifname=bond0 un-aliased-ifname=bond0 l2_addr_path=/sys/class/net/bond0/broadcast l2-addr=ff:ff:ff:ff:ff:ff (addr-bytes_len=6)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:905:get_local_ll_addr() found ETH BR addressff:ff:ff:ff:ff:ff for interface bond0  Pid: 3385 Tid: 3385 VMA DEBUG: utils:647:get_vlan_id_from_ifname() Failure in ioctl(SIOCGIFVLAN, cmd=GET_VLAN_VID_CMD) for interface 'bond0' (errno=22 Invalid argument)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:647:get_vlan_id_from_ifname() Failure in ioctl(SIOCGIFVLAN, cmd=GET_VLAN_VID_CMD) for interface 'eth1' (errno=22 Invalid argument)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:260:map_net_devices() Offload interface 'bond0': Mapped to ibv device 'mlx4_0' [0x2309a20] on port 1  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'bond0.380' (addr=172.25.24.126, flags=11043)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:680:get_vlan_base_name_from_ifname() found vlan base name 'bond0' for interface 'bond0.380'  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/type, flags=0, mode=0x20) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ndv[]:81:configure()  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0.380/mtu, flags=0, mode=0x20) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:680:get_vlan_base_name_from_ifname() found vlan base name 'bond0' for interface 'bond0.380'  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/bonding/mode, flags=0, mode=0x1000) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/bonding/fail_over_mac, flags=0, mode=0x1000) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/bonding/xmit_hash_policy, flags=0, mode=0x1000) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: got bond xmit hash policy = 0  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/bonding/slaves, flags=0, mode=0x100) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0/bonding/active_slave, flags=0, mode=0x10) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ndv[bond0.380]:152:configure() failed to find the active slave, Moving to LAG state  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/address, flags=0, mode=0x100) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:892:get_local_ll_addr() ifname=eth1 un-aliased-ifname=eth1 l2_addr_path=/sys/class/net/eth1/address l2-addr=f4:52:14:38:53:11 (addr-bytes_len=6)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:905:get_local_ll_addr() found ETH UC addressf4:52:14:38:53:11 for interface eth1  Pid: 3385 Tid: 3385 VMA DEBUG: utils:671:get_vlan_base_name_from_ifname() Failure in ioctl(SIOCGIFVLAN, cmd=GET_VLAN_REALDEV_NAME_CMD) for interface 'eth1' (errno=22 Invalid argument)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/device/resource, flags=0, mode=0x400) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/infiniband/mlx4_0/device/resource, flags=0, mode=0x400) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/dev_port, flags=0, mode=0x18) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/address, flags=0, mode=0x100) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:892:get_local_ll_addr() ifname=eth0 un-aliased-ifname=eth0 l2_addr_path=/sys/class/net/eth0/address l2-addr=f4:52:14:38:53:11 (addr-bytes_len=6)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:905:get_local_ll_addr() found ETH UC addressf4:52:14:38:53:11 for interface eth0  Pid: 3385 Tid: 3385 VMA DEBUG: utils:671:get_vlan_base_name_from_ifname() Failure in ioctl(SIOCGIFVLAN, cmd=GET_VLAN_REALDEV_NAME_CMD) for interface 'eth0' (errno=22 Invalid argument)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/device/resource, flags=0, mode=0x400) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/infiniband/mlx4_0/device/resource, flags=0, mode=0x400) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/dev_port, flags=0, mode=0x18) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0.380/address, flags=0, mode=0x100) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:892:get_local_ll_addr() ifname=bond0.380 un-aliased-ifname=bond0.380 l2_addr_path=/sys/class/net/bond0.380/address l2-addr=f4:52:14:38:53:11 (addr-bytes_len=6)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:905:get_local_ll_addr() found ETH UC addressf4:52:14:38:53:11 for interface bond0.380  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/sys/class/net/bond0.380/broadcast, flags=0, mode=0x100) = 15  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=15)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:892:get_local_ll_addr() ifname=bond0.380 un-aliased-ifname=bond0.380 l2_addr_path=/sys/class/net/bond0.380/broadcast l2-addr=ff:ff:ff:ff:ff:ff (addr-bytes_len=6)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:905:get_local_ll_addr() found ETH BR addressff:ff:ff:ff:ff:ff for interface bond0.380  Pid: 3385 Tid: 3385 VMA DEBUG: utils:654:get_vlan_id_from_ifname() found vlan id '380' for interface 'bond0.380'  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:260:map_net_devices() Offload interface 'bond0.380': Mapped to ibv device 'mlx4_0' [0x2309a20] on port 1  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'lo' (addr=0.0.0.0, flags=10049)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:150:map_net_devices() Blocking offload: Interface ('lo') is not of type AF_INET  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'bond0' (addr=0.0.0.0, flags=11443)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:150:map_net_devices() Blocking offload: Interface ('bond0') is not of type AF_INET  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:143:map_net_devices() Checking if can offload on interface 'bond0.380' (addr=0.0.0.0, flags=11043)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:150:map_net_devices() Blocking offload: Interface ('bond0.380') is not of type AF_INET  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:90:net_device_table_mgr() registering timer for ring draining with 10 msec intervales  Pid: 3385 Tid: 3385 VMA DEBUG: evh:59:register_timer_event() timer handler '0x230f758' registered PERIODIC timer for 10 msec (user data: 0)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:95:net_device_table_mgr() registering timer for cq adaptive moderation with 250 msec intervales  Pid: 3385 Tid: 3385 VMA DEBUG: evh:59:register_timer_event() timer handler '0x230f758' registered PERIODIC timer for 250 msec (user data: 1)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: remove_wakeup_fd()  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: remove_wakeup_fd()  Pid: 3385 Tid: 3385 VMA DEBUG: netlink_socket_mgr:104:netlink_socket_mgr()  Pid: 3385 Tid: 3385 VMA DEBUG: netlink_socket_mgr:125:netlink_socket_mgr() Done  Pid: 3385 Tid: 3385 VMA DEBUG: rrm:53:rule_table_mgr()  Pid: 3385 Tid: 3385 VMA DEBUG: rrv[0x7fe289340018]:76:print_val() lookup table :main  Pid: 3385 Tid: 3385 VMA DEBUG: rrm:61:rule_table_mgr() Done  Pid: 3385 Tid: 3385 VMA DEBUG: netlink_socket_mgr:104:netlink_socket_mgr()  Pid: 3385 Tid: 3385 VMA DEBUG: netlink_socket_mgr:125:netlink_socket_mgr() Done  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:51:route_table_mgr()  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:294:find_route_val() found route val[0x7fe286595018]: scope 253 type 1 index 9  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:407:create_new_entry()  Pid: 3385 Tid: 3385 VMA DEBUG: rrm:144:create_new_entry()  Pid: 3385 Tid: 3385 VMA DEBUG: rrm:157:update_entry() entry [0x23106d0]  Pid: 3385 Tid: 3385 VMA DEBUG: rrm:162:update_entry() rule_entry is not valid-> update value  Pid: 3385 Tid: 3385 VMA DEBUG: rre[Destination IP:172.25.24.126]:34:get_val()  Pid: 3385 Tid: 3385 VMA DEBUG: rrm:185:find_rule_val() found rule val[0x7fe289340018]: lookup table :main  Pid: 3385 Tid: 3385 VMA DEBUG: rrm:148:create_new_entry() new entry 0x23106d0 created successfully  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:264:register_observer() Created new cache_entry Key = Destination IP:172.25.24.126  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:332:update_entry() entry [0x230fd80]  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:335:update_entry() route_entry is not valid-> update value  Pid: 3385 Tid: 3385 VMA DEBUG: rre[Destination IP:172.25.24.126]:34:get_val()  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:294:find_route_val() found route val[0x7fe286595018]: scope 253 type 1 index 9  Pid: 3385 Tid: 3385 VMA DEBUG: rte[Destination IP:172.25.24.126->bond0.380]:74:register_to_net_device() register to net device with src_addr 172.25.24.126  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:339:create_new_entry()  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:312:get_net_device_val() Found ETH: Net Device: bond0.380 for 172.25.24.126  Pid: 3385 Tid: 3385 VMA DEBUG: nde[0x2310c00]:31:net_device_entry()  Pid: 3385 Tid: 3385 VMA DEBUG: evh:59:register_timer_event() timer handler '0x2310ca8' registered PERIODIC timer for 1000 msec (user data: 0)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3385 VMA DEBUG: nde[0x2310c00]:54:net_device_entry() Done  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:264:register_observer() Created new cache_entry Key = 172.25.24.126  Pid: 3385 Tid: 3385 VMA DEBUG: rte[Destination IP:172.25.24.126->bond0.380]:78:register_to_net_device() route_entry [0x230fd80] is registered to an offloaded device  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:411:create_new_entry() new entry 0x230fd80 created successfully  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:407:create_new_entry()  Pid: 3385 Tid: 3385 VMA DEBUG: rrm:144:create_new_entry()  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: remove_wakeup_fd()  Pid: 3385 Tid: 3385 VMA DEBUG: rrm:157:update_entry() entry [0x2310fa0]  Pid: 3385 Tid: 3385 VMA DEBUG: rrm:162:update_entry() rule_entry is not valid-> update value  Pid: 3385 Tid: 3385 VMA DEBUG: rre[Destination IP:5.6.7.8]:34:get_val()  Pid: 3385 Tid: 3385 VMA DEBUG: rrm:185:find_rule_val() found rule val[0x7fe289340018]: lookup table :main  Pid: 3385 Tid: 3385 VMA DEBUG: rrm:148:create_new_entry() new entry 0x2310fa0 created successfully  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:264:register_observer() Created new cache_entry Key = Destination IP:5.6.7.8  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:332:update_entry() entry [0x2307630]  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:335:update_entry() route_entry is not valid-> update value  Pid: 3385 Tid: 3385 VMA DEBUG: rre[Destination IP:5.6.7.8]:34:get_val()  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:294:find_route_val() found route val[0x7fe2865953f0]: scope 253 type 1 index 5  Pid: 3385 Tid: 3385 VMA DEBUG: rte[Destination IP:5.6.7.8->bond0]:74:register_to_net_device() register to net device with src_addr 5.6.7.8  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:339:create_new_entry()  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:312:get_net_device_val() Found ETH: Net Device: bond0 for 5.6.7.8  Pid: 3385 Tid: 3385 VMA DEBUG: nde[0x2311400]:31:net_device_entry()  Pid: 3385 Tid: 3385 VMA DEBUG: evh:59:register_timer_event() timer handler '0x23114a8' registered PERIODIC timer for 1000 msec (user data: 0)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: remove_wakeup_fd()  Pid: 3385 Tid: 3385 VMA DEBUG: nde[0x2311400]:54:net_device_entry() Done  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:264:register_observer() Created new cache_entry Key = 5.6.7.8  Pid: 3385 Tid: 3385 VMA DEBUG: rte[Destination IP:5.6.7.8->bond0]:78:register_to_net_device() route_entry [0x2307630] is registered to an offloaded device  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:411:create_new_entry() new entry 0x2307630 created successfully  Pid: 3385 Tid: 3385 VMA DEBUG: rtv[0x7fe286595018]:83:print_val() scope 253 type 1 index 9  Pid: 3385 Tid: 3385 VMA DEBUG: rtv[0x7fe286595160]:83:print_val() scope 253 type 1 index 5  Pid: 3385 Tid: 3385 VMA DEBUG: rtv[0x7fe2865952a8]:83:print_val() scope 253 type 1 index 9  Pid: 3385 Tid: 3385 VMA DEBUG: rtv[0x7fe2865953f0]:83:print_val() scope 253 type 1 index 5  Pid: 3385 Tid: 3385 VMA DEBUG: rtv[0x7fe286595538]:83:print_val() scope 0 type 1 index 9  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:77:route_table_mgr() Registered to g_p_netlink_handler  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:79:route_table_mgr() Done  Pid: 3385 Tid: 3385 VMA DEBUG: vma_stats_instance_create_bpool_block:539: Added bpool local=0x2311860 shm=0x7fe28949d37c  Pid: 3385 Tid: 3385 VMA DEBUG: ib_ctx_collection[0x2307b00]:122:mem_reg_on_all_devices() addr=0x7fe289800000, length=376000063, pos=0, mr[pos]->lkey=268502784, dev1=0x2309a20, dev2=0x2309a20  Pid: 3385 Tid: 3385 VMA DEBUG: bpool[0x23117b0]:111:buffer_pool() Contiguous pages allocation passed successfully  Pid: 3385 Tid: 3385 VMA DEBUG: vma_stats_instance_create_bpool_block:539: Added bpool local=0x2311c70 shm=0x7fe28949d38c  Pid: 3385 Tid: 3385 VMA DEBUG: ib_ctx_collection[0x2307b00]:122:mem_reg_on_all_devices() addr=0x7fe2a0000000, length=376000063, pos=0, mr[pos]->lkey=268503040, dev1=0x2309a20, dev2=0x2309a20  Pid: 3385 Tid: 3385 VMA DEBUG: bpool[0x2311bc0]:111:buffer_pool() Contiguous pages allocation passed successfully  Pid: 3385 Tid: 3385 VMA DEBUG: evh:59:register_timer_event() timer handler '0x2310b20' registered PERIODIC timer for 100 msec (user data: 0)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: remove_wakeup_fd()  Pid: 3385 Tid: 3385 VMA DEBUG: fdc:56:fd_collection() using open files max limit of 4096 file descriptors  Pid: 3385 Tid: 3385 VMA DEBUG: match:223:__vma_print_conf_file() Configuration File:  Pid: 3385 Tid: 3385 VMA DEBUG: match:176:print_instance_id_str() application-id * *:  Pid: 3385 Tid: 3385 VMA DEBUG: match:196:print_instance_conf() tcp_server's rules:  Pid: 3385 Tid: 3385 VMA DEBUG: match:200:print_instance_conf() tcp_clinet's rules:  Pid: 3385 Tid: 3385 VMA DEBUG: match:204:print_instance_conf() udp receiver rules:  Pid: 3385 Tid: 3385 VMA DEBUG: match:208:print_instance_conf() udp sender rules:  Pid: 3385 Tid: 3385 VMA DEBUG: match:212:print_instance_conf() udp connect rules:  Pid: 3385 Tid: 3385 VMA DEBUG: match:215:print_instance_conf()  Pid: 3385 Tid: 3385 VMA DEBUG: lwip:102:vma_lwip()  Pid: 3385 Tid: 3385 VMA DEBUG: utils:501:get_window_scaling_factor() TCP scaling window factor is set to 7  Pid: 3385 Tid: 3385 VMA DEBUG: lwip:123:vma_lwip() LWIP subsystem initialized  Pid: 3385 Tid: 3385 VMA DEBUG: evh:59:register_timer_event() timer handler '0x2334320' registered PERIODIC timer for 200 msec (user data: 0)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3385 VMA DEBUG: nl_wrapper:161:open_channel() opening netlink channel  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: remove_wakeup_fd()  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: socket_internal(domain=(16), type=SOCK_RAW(3), protocol=0) = 17  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: setsockopt(fd=17, level=1, optname=7)  Pid: 3385 Tid: 3385 VMA DEBUG: EXIT: setsockopt() returned with 0  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: setsockopt(fd=17, level=1, optname=8)  Pid: 3385 Tid: 3385 VMA DEBUG: EXIT: setsockopt() returned with 0  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: bind(fd=17, sa_family=16)  Pid: 3385 Tid: 3385 VMA DEBUG: EXIT: bind() returned with 0  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: getsockname(fd=17)  Pid: 3385 Tid: 3385 VMA DEBUG: EXIT: getsockname() returned with 0  Pid: 3385 Tid: 3385 VMA DEBUG: nl_wrapper:225:open_channel() netlink socket is open  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: setsockopt(fd=17, level=270, optname=1)  Pid: 3385 Tid: 3385 VMA DEBUG: EXIT: setsockopt() returned with 0  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: setsockopt(fd=17, level=270, optname=1)  Pid: 3385 Tid: 3385 VMA DEBUG: EXIT: setsockopt() returned with 0  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: setsockopt(fd=17, level=270, optname=1)  Pid: 3385 Tid: 3385 VMA DEBUG: EXIT: setsockopt() returned with 0  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: setsockopt(fd=17, level=270, optname=1)  Pid: 3385 Tid: 3385 VMA DEBUG: EXIT: setsockopt() returned with 0  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: setsockopt(fd=17, level=270, optname=1)  Pid: 3385 Tid: 3385 VMA DEBUG: EXIT: setsockopt() returned with 0  Pid: 3385 Tid: 3385 VMA DEBUG: evh:179:register_command_event() Register command command_netlink event  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3385 VMA DEBUG: evh:59:register_timer_event() timer handler '0x233f758' registered PERIODIC timer for 10000 msec (user data: 0)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/proc/sys/net/ipv4/igmp_max_memberships, flags=0, mode=0x20) = 18  Pid: 3385 Tid: 3390 VMA DEBUG: evh:634:priv_register_command_events() Adding new channel (fd 17)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=18)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: remove_wakeup_fd()  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: socket_internal(domain=AF_INET(2), type=SOCK_STREAM(1), protocol=0) = 18  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: open(file=/dev/infiniband/rdma_cm, flags=0x80002, mode=0x88591ee8) = 19  Pid: 3385 Tid: 3385 VMA DEBUG: fdc:218:addsocket() On-demand creation of cma event channel on fd=19  Pid: 3385 Tid: 3385 VMA DEBUG: fdc:254:addsocket() TCP rules are either not consistent or instructing to use VMA.transport == USE_VMA  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:179:sockinfo_tcp() tcp socket created  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:183:sockinfo_tcp() new pcb 0x2338d48 pcb state 0  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:191:sockinfo_tcp() TCP PCB FLAGS: 0x0  Pid: 3385 Tid: 3385 VMA DEBUG: si[fd=18]:139:fcntl() going to OS for fcntl cmd=3, arg=0  Pid: 3385 Tid: 3385 VMA DEBUG: si[fd=18]:105:fcntl() cmd=F_SETFL, arg=0x802  Pid: 3385 Tid: 3385 VMA DEBUG: si[fd=18]:92:set_blocking() set socket to non-blocking mode  Pid: 3385 Tid: 3385 VMA DEBUG: si[fd=18]:139:fcntl() going to OS for fcntl cmd=4, arg=0x802  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: setsockopt(fd=18, level=1, optname=2)  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:3053:setsockopt() (SO_REUSEADDR) val: 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:3155:setsockopt() going to OS for setsockopt level 1 optname 2  Pid: 3385 Tid: 3385 VMA DEBUG: EXIT: setsockopt() returned with 0  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: bind(fd=18, AF_INET, addr=172.25.24.126, port=3426)  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:312:get_net_device_val() Found ETH: Net Device: bond0.380 for 172.25.24.126  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:1900:bind() socket bound  Pid: 3385 Tid: 3385 VMA DEBUG: EXIT: bind() returned with 0  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: listen(fd=18, backlog=10)  Pid: 3385 Tid: 3385 VMA DEBUG: match:403:get_family_by_instance_first_matching_rule() MATCHING program name: *, application-id: *  Pid: 3385 Tid: 3385 VMA DEBUG: match:383:get_family_by_first_matching_rule() No matching rule. Using VMA (default)  Pid: 3385 Tid: 3385 VMA DEBUG: match:443:__vma_match_tcp_server() MATCH TCP SERVER (LISTEN): => VMA  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:1941:prepareListen() TRANSPORT: VMA, sock state = 0  Pid: 3385 Tid: 3385 VMA DEBUG: si[fd=18]:857:attach_as_uc_receiver() Attaching to specific local if: 172.25.24.126:3426  Pid: 3385 Tid: 3385 VMA DEBUG: match:403:get_family_by_instance_first_matching_rule() MATCHING program name: *, application-id: *  Pid: 3385 Tid: 3385 VMA DEBUG: match:383:get_family_by_first_matching_rule() No matching rule. Using VMA (default)  Pid: 3385 Tid: 3385 VMA DEBUG: match:443:__vma_match_tcp_server() MATCH TCP SERVER (LISTEN): => VMA  Pid: 3385 Tid: 3385 VMA DEBUG: si[fd=18]:340:attach_receiver() Attaching to dst:172.25.24.126:3426, src:0.0.0.0:0, protocol:TCP, local if:172.25.24.126  Pid: 3385 Tid: 3385 VMA DEBUG: ndv[bond0.380]:455:reserve_ring() Creating new RING for key 0  Pid: 3385 Tid: 3385 VMA DEBUG: ring_simple[0x2340dc0]:167:create_resources() new ring()  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x2359040]:93:configure() Creating QP of transport type 'ETH' on ibv device 'mlx4_0' [0x2309a20] on port 1  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233a120]:225:cq_mgr() Created CQ as Tx with fd[21] and of size 3000 elements (ibv_cq_hndl=0x233a210)  Pid: 3385 Tid: 3385 VMA DEBUG: vma_stats_instance_create_cq_block:479: Added cq local=0x233a538 shm=0x7fe28949d040  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233a4a0]:222:cq_mgr() RX CSUM support = 0  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233a4a0]:225:cq_mgr() Created CQ as Rx with fd[22] and of size 16000 elements (ibv_cq_hndl=0x233a590)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:241:set_fd_block_mode() fd[22]: setting to non-blocking mode (0)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:241:set_fd_block_mode() fd[21]: setting to non-blocking mode (0)  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x2359040]:123:configure() cq tx: 0x233a120 rx: 0x233a4a0  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x2359040]:571:prepare_ibv_qp()  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x2359040]:156:configure() requested max inline = 220 QP, actual max inline = 224, VMA max inline set to 220, max_send_wr=4087, max_recv_wr=16351, max_recv_sge=1  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233a120]:357:add_qp_tx() qp_mgr=0x2359040  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x2359040]:175:configure() Created QP (num=102) with 3000 tx wre and inline=220 and 16000 rx wre and 1 sge  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x2359040]:183:up() QP current state: 1  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x2359040]:268:release_rx_buffers() draining rx cq_mgr 0x233a4a0  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x2359040]:273:release_rx_buffers() draining completed with a total of 0 wce's on rx cq_mgr  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x2359040]:280:release_tx_buffers() draining tx cq_mgr 0x233a120  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x2359040]:552:modify_qp_to_ready_state()  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233a4a0]:300:add_qp_rx() qp_mgr=0x2359040  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233a4a0]:309:add_qp_rx() Trying to push 16000 WRE to allocated qp (0x2359040)  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233a4a0]:334:add_qp_rx() Successfully post_recv qp with 16000 new Rx buffers (planned=16000)  Pid: 3385 Tid: 3385 VMA DEBUG: vma_stats_instance_create_ring_block:419: Added ring local=0x2340f38 shm=0x7fe28949d180  Pid: 3385 Tid: 3385 VMA DEBUG: ring_simple[0x2340dc0]:256:create_resources() new ring() completed  Pid: 3385 Tid: 3385 VMA DEBUG: ring_simple[0x236d120]:167:create_resources() new ring()  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x23853a0]:93:configure() Creating QP of transport type 'ETH' on ibv device 'mlx4_0' [0x2309a20] on port 1  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233ba30]:225:cq_mgr() Created CQ as Tx with fd[23] and of size 3000 elements (ibv_cq_hndl=0x233bb20)  Pid: 3385 Tid: 3385 VMA DEBUG: vma_stats_instance_create_cq_block:479: Added cq local=0x233bd48 shm=0x7fe28949d068  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233bcb0]:222:cq_mgr() RX CSUM support = 0  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233bcb0]:225:cq_mgr() Created CQ as Rx with fd[24] and of size 16000 elements (ibv_cq_hndl=0x233bda0)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:241:set_fd_block_mode() fd[24]: setting to non-blocking mode (0)  Pid: 3385 Tid: 3385 VMA DEBUG: utils:241:set_fd_block_mode() fd[23]: setting to non-blocking mode (0)  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x23853a0]:123:configure() cq tx: 0x233ba30 rx: 0x233bcb0  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x23853a0]:571:prepare_ibv_qp()  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x23853a0]:156:configure() requested max inline = 220 QP, actual max inline = 224, VMA max inline set to 220, max_send_wr=4087, max_recv_wr=16351, max_recv_sge=1  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233ba30]:357:add_qp_tx() qp_mgr=0x23853a0  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x23853a0]:175:configure() Created QP (num=103) with 3000 tx wre and inline=220 and 16000 rx wre and 1 sge  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x23853a0]:183:up() QP current state: 1  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x23853a0]:268:release_rx_buffers() draining rx cq_mgr 0x233bcb0  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x23853a0]:273:release_rx_buffers() draining completed with a total of 0 wce's on rx cq_mgr  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x23853a0]:280:release_tx_buffers() draining tx cq_mgr 0x233ba30  Pid: 3385 Tid: 3385 VMA DEBUG: qpm[0x23853a0]:552:modify_qp_to_ready_state()  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233bcb0]:300:add_qp_rx() qp_mgr=0x23853a0  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233bcb0]:309:add_qp_rx() Trying to push 16000 WRE to allocated qp (0x23853a0)  Pid: 3385 Tid: 3385 VMA DEBUG: cqm[0x233bcb0]:334:add_qp_rx() Successfully post_recv qp with 16000 new Rx buffers (planned=16000)  Pid: 3385 Tid: 3385 VMA DEBUG: vma_stats_instance_create_ring_block:419: Added ring local=0x236d298 shm=0x7fe28949d1c0  Pid: 3385 Tid: 3385 VMA DEBUG: ring_simple[0x236d120]:256:create_resources() new ring() completed  Pid: 3385 Tid: 3385 VMA DEBUG: ndtm[0x230f750]:524:global_ring_wakeup()  Pid: 3385 Tid: 3385 VMA DEBUG: ndv[bond0.380]:486:reserve_ring() Ref usage of RING 0x2339190 for key 0 is 1  Pid: 3385 Tid: 3385 VMA DEBUG: si[fd=18]:670:rx_add_ring_cb()  Pid: 3385 Tid: 3385 VMA DEBUG: ring_simple[0x2340dc0]:270:attach_flow() flow: dst:172.25.24.126:3426, src:0.0.0.0:0, protocol:TCP, local if:172.25.24.126, with sink (0x2338908)  Pid: 3385 Tid: 3385 VMA DEBUG: rfs[0x233c450]:258:create_ibv_flow() ibv_create_flow succeeded with flow dst:172.25.24.126:3426, src:0.0.0.0:0, protocol:TCP  Pid: 3385 Tid: 3385 VMA DEBUG: rfs[0x233c450]:164:add_sink() Added new sink (0x2338908), num of sinks is now: 1  Pid: 3385 Tid: 3385 VMA DEBUG: ring_simple[0x236d120]:270:attach_flow() flow: dst:172.25.24.126:3426, src:0.0.0.0:0, protocol:TCP, local if:172.25.24.126, with sink (0x2338908)  Pid: 3385 Tid: 3385 VMA DEBUG: rfs[0x233c710]:258:create_ibv_flow() ibv_create_flow succeeded with flow dst:172.25.24.126:3426, src:0.0.0.0:0, protocol:TCP  Pid: 3385 Tid: 3385 VMA DEBUG: rfs[0x233c710]:164:add_sink() Added new sink (0x2338908), num of sinks is now: 1  Pid: 3385 Tid: 3385 VMA DEBUG: si[fd=18]:434:attach_receiver() Attached dst:172.25.24.126:3426, src:0.0.0.0:0, protocol:TCP, local if:172.25.24.126 to ring 0x2339190  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2065:listen() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3390 VMA DEBUG: ib_ctx_time_converter[0x230f578]:151:fix_hw_clock_deviation() ibv device 'mlx4_0' [0x2309a20] : fix_hw_clock_deviation parameters status : 1.016411556 since last deviation fix, UPDATE_HW_TIMER_PERIOD_MS = 10000, current_parameters_set = 0x230f588, estimated_hw_time = 262234181, diff_hw_time = 262654707, diff = -420526 ,m_hca_core_clock = 258000000  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth1/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/operstate, flags=0, mode=0x4) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: open(file=/sys/class/net/eth0/bonding_slave/state, flags=0, mode=0x9) = 25  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=25)  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2137:accept_helper() socket accept  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2141:accept_helper() sock state = 1  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:2174:accept_helper() interrupted accept  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=18)  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:330:prepare_to_close()  Pid: 3385 Tid: 3385 VMA DEBUG: si_tcp[fd=18]:435:handle_socket_linger() Going to linger for max time of 0 usec  Pid: 3385 Tid: 3385 VMA DEBUG: evh:122:unregister_timers_event_and_delete() timer handler '0x2338d28'  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3390 VMA DEBUG: si[fd=18]:454:detach_receiver() Unregistering receiver: dst:172.25.24.126:3426, src:0.0.0.0:0, protocol:TCP  Pid: 3385 Tid: 3385 VMA DEBUG: free_libvma_resources: Closing libvma resources  Pid: 3385 Tid: 3390 VMA DEBUG: si[fd=18]:469:detach_receiver() Detaching dst:172.25.24.126:3426, src:0.0.0.0:0, protocol:TCP from ring 0x2339190  Pid: 3385 Tid: 3390 VMA DEBUG: ring_simple[0x2340dc0]:396:detach_flow() flow: dst:172.25.24.126:3426, src:0.0.0.0:0, protocol:TCP, with sink (0x2338908)  Pid: 3385 Tid: 3390 VMA DEBUG: rfs[0x233c450]:172:del_sink() called with sink (0x2338908)  Pid: 3385 Tid: 3390 VMA DEBUG: rfs[0x233c450]:186:del_sink() Removed sink (0x2338908), num of sinks is now: 0  Pid: 3385 Tid: 3390 VMA DEBUG: rfs[0x233c450]:189:del_sink() rfs sinks list is now empty  Pid: 3385 Tid: 3390 VMA DEBUG: rfs[0x233c450]:279:destroy_ibv_flow() ibv_destroy_flow with flow dst:172.25.24.126:3426, src:0.0.0.0:0, protocol:TCP  Pid: 3385 Tid: 3390 VMA DEBUG: ring_simple[0x236d120]:396:detach_flow() flow: dst:172.25.24.126:3426, src:0.0.0.0:0, protocol:TCP, with sink (0x2338908)  Pid: 3385 Tid: 3390 VMA DEBUG: rfs[0x233c710]:172:del_sink() called with sink (0x2338908)  Pid: 3385 Tid: 3390 VMA DEBUG: rfs[0x233c710]:186:del_sink() Removed sink (0x2338908), num of sinks is now: 0  Pid: 3385 Tid: 3390 VMA DEBUG: rfs[0x233c710]:189:del_sink() rfs sinks list is now empty  Pid: 3385 Tid: 3390 VMA DEBUG: rfs[0x233c710]:279:destroy_ibv_flow() ibv_destroy_flow with flow dst:172.25.24.126:3426, src:0.0.0.0:0, protocol:TCP  Pid: 3385 Tid: 3390 VMA DEBUG: si[fd=18]:728:rx_del_ring_cb()  Pid: 3385 Tid: 3390 VMA DEBUG: ndv[bond0.380]:503:release_ring() Deleting RING 0x2339190 for key 0 and removing notification fd from global_table_mgr_epfd (epfd=11)  Pid: 3385 Tid: 3390 VMA DEBUG: ring_simple[0x2340dc0]:97:~ring_simple() delete ring()  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:197:down() QP current state: 3  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:240:modify_qp_to_error_state()  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:297:trigger_completion_for_all_sent_packets() unsignaled count=0, last=(nil)  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:280:release_tx_buffers() draining tx cq_mgr 0x233a120  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:268:release_rx_buffers() draining rx cq_mgr 0x233a4a0  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:270:release_rx_buffers() draining completed on rx cq_mgr (10112 wce)  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:270:release_rx_buffers() draining completed on rx cq_mgr (5888 wce)  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:273:release_rx_buffers() draining completed with a total of 16000 wce's on rx cq_mgr  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233a4a0]:349:del_qp_rx() qp_mgr=0x2359040  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:280:release_tx_buffers() draining tx cq_mgr 0x233a120  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:268:release_rx_buffers() draining rx cq_mgr 0x233a4a0  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:273:release_rx_buffers() draining completed with a total of 0 wce's on rx cq_mgr  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:65:~qp_mgr() calling ibv_destroy_qp(qp=0x233a760)  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233a120]:230:~cq_mgr() destroying CQ as Tx  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233a120]:275:~cq_mgr() done  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233a4a0]:230:~cq_mgr() destroying CQ as Rx  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233a4a0]:282: Packets dropped: 0  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233a4a0]:283: Drained max: 10112  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233a4a0]:284: Buffer disorder: 0.00%  Pid: 3385 Tid: 3390 VMA DEBUG: vma_stats_instance_remove_cq_block:487: Remove cq local=0x233a538  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233a4a0]:275:~cq_mgr() done  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:83:~qp_mgr() Rx buffer poll: 184000 free global buffers available  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x2359040]:84:~qp_mgr() delete done  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=22)  Pid: 3385 Tid: 3390 VMA DEBUG: ring_simple[0x2340dc0]:138:~ring_simple() Tx buffer poll: free count = 256, sender_has = 0, total = 256, good accounting (0)  Pid: 3385 Tid: 3390 VMA DEBUG: ring_simple[0x2340dc0]:141:~ring_simple() Tx WR num: free count = 3000, total = 3000, good accounting (0)  Pid: 3385 Tid: 3390 VMA DEBUG: ring_simple[0x2340dc0]:142:~ring_simple() Rx buffer pool: 256 free global buffers available  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=21)  Pid: 3385 Tid: 3390 VMA DEBUG: vma_stats_instance_remove_ring_block:427: Remove ring local=0x2340f38  Pid: 3385 Tid: 3390 VMA DEBUG: ring_simple[0x2340dc0]:162:~ring_simple() delete ring() completed  Pid: 3385 Tid: 3390 VMA DEBUG: ring_simple[0x236d120]:97:~ring_simple() delete ring()  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:197:down() QP current state: 3  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:240:modify_qp_to_error_state()  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:297:trigger_completion_for_all_sent_packets() unsignaled count=0, last=(nil)  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:280:release_tx_buffers() draining tx cq_mgr 0x233ba30  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:268:release_rx_buffers() draining rx cq_mgr 0x233bcb0  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:270:release_rx_buffers() draining completed on rx cq_mgr (10112 wce)  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:270:release_rx_buffers() draining completed on rx cq_mgr (5888 wce)  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:273:release_rx_buffers() draining completed with a total of 16000 wce's on rx cq_mgr  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233bcb0]:349:del_qp_rx() qp_mgr=0x23853a0  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:280:release_tx_buffers() draining tx cq_mgr 0x233ba30  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:268:release_rx_buffers() draining rx cq_mgr 0x233bcb0  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:273:release_rx_buffers() draining completed with a total of 0 wce's on rx cq_mgr  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:65:~qp_mgr() calling ibv_destroy_qp(qp=0x233bf70)  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233ba30]:230:~cq_mgr() destroying CQ as Tx  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233ba30]:275:~cq_mgr() done  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233bcb0]:230:~cq_mgr() destroying CQ as Rx  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233bcb0]:282: Packets dropped: 0  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233bcb0]:283: Drained max: 10112  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233bcb0]:284: Buffer disorder: 0.00%  Pid: 3385 Tid: 3390 VMA DEBUG: vma_stats_instance_remove_cq_block:487: Remove cq local=0x233bd48  Pid: 3385 Tid: 3390 VMA DEBUG: cqm[0x233bcb0]:275:~cq_mgr() done  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:83:~qp_mgr() Rx buffer poll: 200000 free global buffers available  Pid: 3385 Tid: 3390 VMA DEBUG: qpm[0x23853a0]:84:~qp_mgr() delete done  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=24)  Pid: 3385 Tid: 3390 VMA DEBUG: ring_simple[0x236d120]:138:~ring_simple() Tx buffer poll: free count = 256, sender_has = 0, total = 256, good accounting (0)  Pid: 3385 Tid: 3390 VMA DEBUG: ring_simple[0x236d120]:141:~ring_simple() Tx WR num: free count = 3000, total = 3000, good accounting (0)  Pid: 3385 Tid: 3390 VMA DEBUG: ring_simple[0x236d120]:142:~ring_simple() Rx buffer pool: 256 free global buffers available  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: close(fd=23)  Pid: 3385 Tid: 3390 VMA DEBUG: vma_stats_instance_remove_ring_block:427: Remove ring local=0x236d298  Pid: 3385 Tid: 3390 VMA DEBUG: ring_simple[0x236d120]:162:~ring_simple() delete ring() completed  Pid: 3385 Tid: 3390 VMA DEBUG: cache_subject_observer:278:unregister_observer()  Pid: 3385 Tid: 3390 VMA DEBUG: cache_subject_observer:199:try_to_remove_cache_entry() Cache_entry is not deletable  Pid: 3385 Tid: 3390 VMA DEBUG: si_tcp[fd=18]:270:~sockinfo_tcp() sock closed  Pid: 3385 Tid: 3390 VMA DEBUG: vma_stats_instance_remove_socket_block:323  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: remove_wakeup_fd()  Pid: 3385 Tid: 3385 VMA DEBUG: evh:122:unregister_timers_event_and_delete() timer handler '0x2310ae0'  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: do_wakeup()  Pid: 3385 Tid: 3390 VMA DEBUG: ENTER: remove_wakeup_fd()  Pid: 3385 Tid: 3390 VMA DEBUG: evh:282:event_handler_thread() Ending internal thread  Pid: 3385 Tid: 3385 VMA DEBUG: evh:352:stop_thread() event handler thread stopped  Pid: 3385 Tid: 3385 VMA DEBUG: fdc:170:clear() Removing rdma_cm event_channel  Pid: 3385 Tid: 3385 VMA DEBUG: utils:241:set_fd_block_mode() fd[19]: setting to non-blocking mode (0)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=19)  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:84:~route_table_mgr()  Pid: 3385 Tid: 3385 VMA DEBUG: rte[Destination IP:172.25.24.126->bond0.380]:99:unregister_to_net_device() unregister to net device with src_addr 172.25.24.126  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:278:unregister_observer()  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:194:try_to_remove_cache_entry() Deleting cache_entry  Pid: 3385 Tid: 3385 VMA DEBUG: evh:111:unregister_timer_event() timer handler '0x2310ca8'  Pid: 3385 Tid: 3385 VMA DEBUG: nde[0x2310c00]:67:~net_device_entry() Done  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:278:unregister_observer()  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:194:try_to_remove_cache_entry() Deleting cache_entry Destination IP:172.25.24.126  Pid: 3385 Tid: 3385 VMA DEBUG: rte[Destination IP:5.6.7.8->bond0]:99:unregister_to_net_device() unregister to net device with src_addr 5.6.7.8  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:278:unregister_observer()  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:194:try_to_remove_cache_entry() Deleting cache_entry  Pid: 3385 Tid: 3385 VMA DEBUG: evh:111:unregister_timer_event() timer handler '0x23114a8'  Pid: 3385 Tid: 3385 VMA DEBUG: nde[0x2311400]:67:~net_device_entry() Done  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:278:unregister_observer()  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:194:try_to_remove_cache_entry() Deleting cache_entry Destination IP:5.6.7.8  Pid: 3385 Tid: 3385 VMA DEBUG: rtm:93:~route_table_mgr() Done  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:328:print_tbl() empty  Pid: 3385 Tid: 3385 VMA DEBUG: netlink_socket_mgr:131:~netlink_socket_mgr()  Pid: 3385 Tid: 3385 VMA DEBUG: netlink_socket_mgr:137:~netlink_socket_mgr() Done  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:328:print_tbl() empty  Pid: 3385 Tid: 3385 VMA DEBUG: netlink_socket_mgr:131:~netlink_socket_mgr()  Pid: 3385 Tid: 3385 VMA DEBUG: netlink_socket_mgr:137:~netlink_socket_mgr() Done  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=14)  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:328:print_tbl() empty  Pid: 3385 Tid: 3385 VMA DEBUG: evh:111:unregister_timer_event() timer handler '0x230f678'  Pid: 3385 Tid: 3385 VMA DEBUG: cache_subject_observer:328:print_tbl() empty  Pid: 3385 Tid: 3385 VMA DEBUG: vma_stats_instance_remove_bpool_block:547: Remove bpool local=0x2311c70  Pid: 3385 Tid: 3385 VMA DEBUG: vma_stats_instance_remove_bpool_block:547: Remove bpool local=0x2311860  Pid: 3385 Tid: 3385 VMA DEBUG: nl_wrapper:141:~netlink_wrapper() ---> netlink_route_listener DTOR (LIBNL1)  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=17)  Pid: 3385 Tid: 3385 VMA DEBUG: nl_wrapper:155:~netlink_wrapper() <--- netlink_route_listener DTOR  Pid: 3385 Tid: 3385 VMA DEBUG: evh:111:unregister_timer_event() timer handler '0x230f578'  Pid: 3385 Tid: 3385 VMA DEBUG: evh:111:unregister_timer_event() timer handler '0x2310b20'  Pid: 3385 Tid: 3385 VMA DEBUG: Stopping logger module  Pid: 3385 Tid: 3385 VMA DEBUG: sock_redirect_exit()  Pid: 3385 Tid: 3385 VMA DEBUG: vma_shmem_stats_close: file '/tmp//vmastat.3385' fd 7 shared memory at 0x7fe28949d000 with 100 max blocks  Pid: 3385 Tid: 3385 VMA DEBUG: ENTER: close(fd=7)