Changeset a1a101d in mainline for uspace/srv/net/tcp
- Timestamp:
- 2012-08-17T16:58:51Z (13 years ago)
- Branches:
- lfn, master, serial, ticket/834-toolchain-update, topic/msim-upgrade, topic/simplify-dev-export
- Children:
- bc0ccab
- Parents:
- 920d0fc
- Location:
- uspace/srv/net/tcp
- Files:
-
- 9 edited
Legend:
- Unmodified
- Added
- Removed
-
uspace/srv/net/tcp/conn.c
r920d0fc ra1a101d 164 164 static void tcp_conn_free(tcp_conn_t *conn) 165 165 { 166 log_msg(L VL_DEBUG, "%s: tcp_conn_free(%p)", conn->name, conn);166 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_conn_free(%p)", conn->name, conn); 167 167 tcp_tqueue_fini(&conn->retransmit); 168 168 … … 184 184 void tcp_conn_addref(tcp_conn_t *conn) 185 185 { 186 log_msg(L VL_DEBUG2, "%s: tcp_conn_addref(%p)", conn->name, conn);186 log_msg(LOG_DEFAULT, LVL_DEBUG2, "%s: tcp_conn_addref(%p)", conn->name, conn); 187 187 atomic_inc(&conn->refcnt); 188 188 } … … 196 196 void tcp_conn_delref(tcp_conn_t *conn) 197 197 { 198 log_msg(L VL_DEBUG2, "%s: tcp_conn_delref(%p)", conn->name, conn);198 log_msg(LOG_DEFAULT, LVL_DEBUG2, "%s: tcp_conn_delref(%p)", conn->name, conn); 199 199 200 200 if (atomic_predec(&conn->refcnt) == 0) … … 211 211 void tcp_conn_delete(tcp_conn_t *conn) 212 212 { 213 log_msg(L VL_DEBUG, "%s: tcp_conn_delete(%p)", conn->name, conn);213 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_conn_delete(%p)", conn->name, conn); 214 214 215 215 assert(conn->deleted == false); … … 245 245 tcp_cstate_t old_state; 246 246 247 log_msg(L VL_DEBUG, "tcp_conn_state_set(%p)", conn);247 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_conn_state_set(%p)", conn); 248 248 249 249 old_state = conn->cstate; … … 253 253 /* Run user callback function */ 254 254 if (conn->cstate_cb != NULL) { 255 log_msg(L VL_DEBUG, "tcp_conn_state_set() - run user CB");255 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_conn_state_set() - run user CB"); 256 256 conn->cstate_cb(conn, conn->cstate_cb_arg); 257 257 } else { 258 log_msg(L VL_DEBUG, "tcp_conn_state_set() - no user CB");258 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_conn_state_set() - no user CB"); 259 259 } 260 260 … … 293 293 case st_syn_received: 294 294 case st_established: 295 log_msg(L VL_DEBUG, "%s: FIN sent -> Fin-Wait-1", conn->name);295 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: FIN sent -> Fin-Wait-1", conn->name); 296 296 tcp_conn_state_set(conn, st_fin_wait_1); 297 297 break; 298 298 case st_close_wait: 299 log_msg(L VL_DEBUG, "%s: FIN sent -> Last-Ack", conn->name);299 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: FIN sent -> Last-Ack", conn->name); 300 300 tcp_conn_state_set(conn, st_last_ack); 301 301 break; 302 302 default: 303 log_msg(L VL_ERROR, "%s: Connection state %d", conn->name,303 log_msg(LOG_DEFAULT, LVL_ERROR, "%s: Connection state %d", conn->name, 304 304 conn->cstate); 305 305 assert(false); … … 312 312 static bool tcp_socket_match(tcp_sock_t *sock, tcp_sock_t *patt) 313 313 { 314 log_msg(L VL_DEBUG2, "tcp_socket_match(sock=(%x,%u), pat=(%x,%u))",314 log_msg(LOG_DEFAULT, LVL_DEBUG2, "tcp_socket_match(sock=(%x,%u), pat=(%x,%u))", 315 315 sock->addr.ipv4, sock->port, patt->addr.ipv4, patt->port); 316 316 … … 323 323 return false; 324 324 325 log_msg(L VL_DEBUG2, " -> match");325 log_msg(LOG_DEFAULT, LVL_DEBUG2, " -> match"); 326 326 327 327 return true; … … 331 331 static bool tcp_sockpair_match(tcp_sockpair_t *sp, tcp_sockpair_t *pattern) 332 332 { 333 log_msg(L VL_DEBUG2, "tcp_sockpair_match(%p, %p)", sp, pattern);333 log_msg(LOG_DEFAULT, LVL_DEBUG2, "tcp_sockpair_match(%p, %p)", sp, pattern); 334 334 335 335 if (!tcp_socket_match(&sp->local, &pattern->local)) … … 353 353 tcp_conn_t *tcp_conn_find_ref(tcp_sockpair_t *sp) 354 354 { 355 log_msg(L VL_DEBUG, "tcp_conn_find_ref(%p)", sp);355 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_conn_find_ref(%p)", sp); 356 356 357 357 fibril_mutex_lock(&conn_list_lock); … … 360 360 tcp_conn_t *conn = list_get_instance(link, tcp_conn_t, link); 361 361 tcp_sockpair_t *csp = &conn->ident; 362 log_msg(L VL_DEBUG2, "compare with conn (f:(%x,%u), l:(%x,%u))",362 log_msg(LOG_DEFAULT, LVL_DEBUG2, "compare with conn (f:(%x,%u), l:(%x,%u))", 363 363 csp->foreign.addr.ipv4, csp->foreign.port, 364 364 csp->local.addr.ipv4, csp->local.port); … … 380 380 static void tcp_conn_reset(tcp_conn_t *conn) 381 381 { 382 log_msg(L VL_DEBUG, "%s: tcp_conn_reset()", conn->name);382 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_conn_reset()", conn->name); 383 383 tcp_conn_state_set(conn, st_closed); 384 384 conn->reset = true; … … 398 398 { 399 399 /* TODO */ 400 log_msg(L VL_DEBUG, "%s: tcp_reset_signal()", conn->name);400 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_reset_signal()", conn->name); 401 401 } 402 402 … … 422 422 return true; 423 423 case st_closed: 424 log_msg(L VL_WARN, "state=%d", (int) conn->cstate);424 log_msg(LOG_DEFAULT, LVL_WARN, "state=%d", (int) conn->cstate); 425 425 assert(false); 426 426 } … … 436 436 static void tcp_conn_sa_listen(tcp_conn_t *conn, tcp_segment_t *seg) 437 437 { 438 log_msg(L VL_DEBUG, "tcp_conn_sa_listen(%p, %p)", conn, seg);438 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_conn_sa_listen(%p, %p)", conn, seg); 439 439 440 440 if ((seg->ctrl & CTL_RST) != 0) { 441 log_msg(L VL_DEBUG, "Ignoring incoming RST.");441 log_msg(LOG_DEFAULT, LVL_DEBUG, "Ignoring incoming RST."); 442 442 return; 443 443 } 444 444 445 445 if ((seg->ctrl & CTL_ACK) != 0) { 446 log_msg(L VL_DEBUG, "Incoming ACK, send acceptable RST.");446 log_msg(LOG_DEFAULT, LVL_DEBUG, "Incoming ACK, send acceptable RST."); 447 447 tcp_reply_rst(&conn->ident, seg); 448 448 return; … … 450 450 451 451 if ((seg->ctrl & CTL_SYN) == 0) { 452 log_msg(L VL_DEBUG, "SYN not present. Ignoring segment.");453 return; 454 } 455 456 log_msg(L VL_DEBUG, "Got SYN, sending SYN, ACK.");452 log_msg(LOG_DEFAULT, LVL_DEBUG, "SYN not present. Ignoring segment."); 453 return; 454 } 455 456 log_msg(LOG_DEFAULT, LVL_DEBUG, "Got SYN, sending SYN, ACK."); 457 457 458 458 conn->rcv_nxt = seg->seq + 1; … … 460 460 461 461 462 log_msg(L VL_DEBUG, "rcv_nxt=%u", conn->rcv_nxt);462 log_msg(LOG_DEFAULT, LVL_DEBUG, "rcv_nxt=%u", conn->rcv_nxt); 463 463 464 464 if (seg->len > 1) 465 log_msg(L VL_WARN, "SYN combined with data, ignoring data.");465 log_msg(LOG_DEFAULT, LVL_WARN, "SYN combined with data, ignoring data."); 466 466 467 467 /* XXX select ISS */ … … 493 493 static void tcp_conn_sa_syn_sent(tcp_conn_t *conn, tcp_segment_t *seg) 494 494 { 495 log_msg(L VL_DEBUG, "tcp_conn_sa_syn_sent(%p, %p)", conn, seg);495 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_conn_sa_syn_sent(%p, %p)", conn, seg); 496 496 497 497 if ((seg->ctrl & CTL_ACK) != 0) { 498 log_msg(L VL_DEBUG, "snd_una=%u, seg.ack=%u, snd_nxt=%u",498 log_msg(LOG_DEFAULT, LVL_DEBUG, "snd_una=%u, seg.ack=%u, snd_nxt=%u", 499 499 conn->snd_una, seg->ack, conn->snd_nxt); 500 500 if (!seq_no_ack_acceptable(conn, seg->ack)) { 501 501 if ((seg->ctrl & CTL_RST) == 0) { 502 log_msg(L VL_WARN, "ACK not acceptable, send RST");502 log_msg(LOG_DEFAULT, LVL_WARN, "ACK not acceptable, send RST"); 503 503 tcp_reply_rst(&conn->ident, seg); 504 504 } else { 505 log_msg(L VL_WARN, "RST,ACK not acceptable, drop");505 log_msg(LOG_DEFAULT, LVL_WARN, "RST,ACK not acceptable, drop"); 506 506 } 507 507 return; … … 512 512 /* If we get here, we have either an acceptable ACK or no ACK */ 513 513 if ((seg->ctrl & CTL_ACK) != 0) { 514 log_msg(L VL_DEBUG, "%s: Connection reset. -> Closed",514 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: Connection reset. -> Closed", 515 515 conn->name); 516 516 /* Reset connection */ … … 518 518 return; 519 519 } else { 520 log_msg(L VL_DEBUG, "%s: RST without ACK, drop",520 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: RST without ACK, drop", 521 521 conn->name); 522 522 return; … … 527 527 528 528 if ((seg->ctrl & CTL_SYN) == 0) { 529 log_msg(L VL_DEBUG, "No SYN bit, ignoring segment.");529 log_msg(LOG_DEFAULT, LVL_DEBUG, "No SYN bit, ignoring segment."); 530 530 return; 531 531 } … … 544 544 } 545 545 546 log_msg(L VL_DEBUG, "Sent SYN, got SYN.");546 log_msg(LOG_DEFAULT, LVL_DEBUG, "Sent SYN, got SYN."); 547 547 548 548 /* … … 551 551 * will always be accepted as new window setting. 552 552 */ 553 log_msg(L VL_DEBUG, "SND.WND := %" PRIu32 ", SND.WL1 := %" PRIu32 ", "553 log_msg(LOG_DEFAULT, LVL_DEBUG, "SND.WND := %" PRIu32 ", SND.WL1 := %" PRIu32 ", " 554 554 "SND.WL2 = %" PRIu32, seg->wnd, seg->seq, seg->seq); 555 555 conn->snd_wnd = seg->wnd; … … 558 558 559 559 if (seq_no_syn_acked(conn)) { 560 log_msg(L VL_DEBUG, "%s: syn acked -> Established", conn->name);560 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: syn acked -> Established", conn->name); 561 561 tcp_conn_state_set(conn, st_established); 562 562 tcp_tqueue_ctrl_seg(conn, CTL_ACK /* XXX */); 563 563 } else { 564 log_msg(L VL_DEBUG, "%s: syn not acked -> Syn-Received",564 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: syn not acked -> Syn-Received", 565 565 conn->name); 566 566 tcp_conn_state_set(conn, st_syn_received); … … 582 582 tcp_segment_t *pseg; 583 583 584 log_msg(L VL_DEBUG, "tcp_conn_sa_seq(%p, %p)", conn, seg);584 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_conn_sa_seq(%p, %p)", conn, seg); 585 585 586 586 /* Discard unacceptable segments ("old duplicates") */ 587 587 if (!seq_no_segment_acceptable(conn, seg)) { 588 log_msg(L VL_DEBUG, "Replying ACK to unacceptable segment.");588 log_msg(LOG_DEFAULT, LVL_DEBUG, "Replying ACK to unacceptable segment."); 589 589 tcp_tqueue_ctrl_seg(conn, CTL_ACK); 590 590 tcp_segment_delete(seg); … … 682 682 assert(seq_no_in_rcv_wnd(conn, seg->seq)); 683 683 684 log_msg(L VL_WARN, "SYN is in receive window, should send reset. XXX");684 log_msg(LOG_DEFAULT, LVL_WARN, "SYN is in receive window, should send reset. XXX"); 685 685 686 686 /* … … 705 705 if (!seq_no_ack_acceptable(conn, seg->ack)) { 706 706 /* ACK is not acceptable, send RST. */ 707 log_msg(L VL_WARN, "Segment ACK not acceptable, sending RST.");707 log_msg(LOG_DEFAULT, LVL_WARN, "Segment ACK not acceptable, sending RST."); 708 708 tcp_reply_rst(&conn->ident, seg); 709 709 tcp_segment_delete(seg); … … 711 711 } 712 712 713 log_msg(L VL_DEBUG, "%s: SYN ACKed -> Established", conn->name);713 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: SYN ACKed -> Established", conn->name); 714 714 715 715 tcp_conn_state_set(conn, st_established); … … 730 730 static cproc_t tcp_conn_seg_proc_ack_est(tcp_conn_t *conn, tcp_segment_t *seg) 731 731 { 732 log_msg(L VL_DEBUG, "tcp_conn_seg_proc_ack_est(%p, %p)", conn, seg);733 734 log_msg(L VL_DEBUG, "SEG.ACK=%u, SND.UNA=%u, SND.NXT=%u",732 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_conn_seg_proc_ack_est(%p, %p)", conn, seg); 733 734 log_msg(LOG_DEFAULT, LVL_DEBUG, "SEG.ACK=%u, SND.UNA=%u, SND.NXT=%u", 735 735 (unsigned)seg->ack, (unsigned)conn->snd_una, 736 736 (unsigned)conn->snd_nxt); 737 737 738 738 if (!seq_no_ack_acceptable(conn, seg->ack)) { 739 log_msg(L VL_DEBUG, "ACK not acceptable.");739 log_msg(LOG_DEFAULT, LVL_DEBUG, "ACK not acceptable."); 740 740 if (!seq_no_ack_duplicate(conn, seg->ack)) { 741 log_msg(L VL_WARN, "Not acceptable, not duplicate. "741 log_msg(LOG_DEFAULT, LVL_WARN, "Not acceptable, not duplicate. " 742 742 "Send ACK and drop."); 743 743 /* Not acceptable, not duplicate. Send ACK and drop. */ … … 746 746 return cp_done; 747 747 } else { 748 log_msg(L VL_DEBUG, "Ignoring duplicate ACK.");748 log_msg(LOG_DEFAULT, LVL_DEBUG, "Ignoring duplicate ACK."); 749 749 } 750 750 } else { … … 758 758 conn->snd_wl2 = seg->ack; 759 759 760 log_msg(L VL_DEBUG, "Updating send window, SND.WND=%" PRIu32760 log_msg(LOG_DEFAULT, LVL_DEBUG, "Updating send window, SND.WND=%" PRIu32 761 761 ", SND.WL1=%" PRIu32 ", SND.WL2=%" PRIu32, 762 762 conn->snd_wnd, conn->snd_wl1, conn->snd_wl2); … … 785 785 786 786 if (conn->fin_is_acked) { 787 log_msg(L VL_DEBUG, "%s: FIN acked -> Fin-Wait-2", conn->name);787 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: FIN acked -> Fin-Wait-2", conn->name); 788 788 tcp_conn_state_set(conn, st_fin_wait_2); 789 789 } … … 850 850 851 851 if (conn->fin_is_acked) { 852 log_msg(L VL_DEBUG, "%s: FIN acked -> Closed", conn->name);852 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: FIN acked -> Closed", conn->name); 853 853 tcp_conn_remove(conn); 854 854 tcp_conn_state_set(conn, st_closed); … … 881 881 static cproc_t tcp_conn_seg_proc_ack(tcp_conn_t *conn, tcp_segment_t *seg) 882 882 { 883 log_msg(L VL_DEBUG, "%s: tcp_conn_seg_proc_ack(%p, %p)",883 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_conn_seg_proc_ack(%p, %p)", 884 884 conn->name, conn, seg); 885 885 886 886 if ((seg->ctrl & CTL_ACK) == 0) { 887 log_msg(L VL_WARN, "Segment has no ACK. Dropping.");887 log_msg(LOG_DEFAULT, LVL_WARN, "Segment has no ACK. Dropping."); 888 888 tcp_segment_delete(seg); 889 889 return cp_done; … … 940 940 size_t xfer_size; 941 941 942 log_msg(L VL_DEBUG, "%s: tcp_conn_seg_proc_text(%p, %p)",942 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_conn_seg_proc_text(%p, %p)", 943 943 conn->name, conn, seg); 944 944 … … 982 982 fibril_condvar_broadcast(&conn->rcv_buf_cv); 983 983 984 log_msg(L VL_DEBUG, "Received %zu bytes of data.", xfer_size);984 log_msg(LOG_DEFAULT, LVL_DEBUG, "Received %zu bytes of data.", xfer_size); 985 985 986 986 /* Advance RCV.NXT */ … … 998 998 tcp_conn_trim_seg_to_wnd(conn, seg); 999 999 } else { 1000 log_msg(L VL_DEBUG, "%s: Nothing left in segment, dropping "1000 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: Nothing left in segment, dropping " 1001 1001 "(xfer_size=%zu, SEG.LEN=%zu, seg->ctrl=%u)", 1002 1002 conn->name, xfer_size, seg->len, (unsigned)seg->ctrl); … … 1018 1018 static cproc_t tcp_conn_seg_proc_fin(tcp_conn_t *conn, tcp_segment_t *seg) 1019 1019 { 1020 log_msg(L VL_DEBUG, "%s: tcp_conn_seg_proc_fin(%p, %p)",1020 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_conn_seg_proc_fin(%p, %p)", 1021 1021 conn->name, conn, seg); 1022 log_msg(L VL_DEBUG, " seg->len=%zu, seg->ctl=%u", (size_t) seg->len,1022 log_msg(LOG_DEFAULT, LVL_DEBUG, " seg->len=%zu, seg->ctl=%u", (size_t) seg->len, 1023 1023 (unsigned) seg->ctrl); 1024 1024 1025 1025 /* Only process FIN if no text is left in segment. */ 1026 1026 if (tcp_segment_text_size(seg) == 0 && (seg->ctrl & CTL_FIN) != 0) { 1027 log_msg(L VL_DEBUG, " - FIN found in segment.");1027 log_msg(LOG_DEFAULT, LVL_DEBUG, " - FIN found in segment."); 1028 1028 1029 1029 /* Send ACK */ … … 1042 1042 case st_syn_received: 1043 1043 case st_established: 1044 log_msg(L VL_DEBUG, "%s: FIN received -> Close-Wait",1044 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: FIN received -> Close-Wait", 1045 1045 conn->name); 1046 1046 tcp_conn_state_set(conn, st_close_wait); 1047 1047 break; 1048 1048 case st_fin_wait_1: 1049 log_msg(L VL_DEBUG, "%s: FIN received -> Closing",1049 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: FIN received -> Closing", 1050 1050 conn->name); 1051 1051 tcp_conn_state_set(conn, st_closing); 1052 1052 break; 1053 1053 case st_fin_wait_2: 1054 log_msg(L VL_DEBUG, "%s: FIN received -> Time-Wait",1054 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: FIN received -> Time-Wait", 1055 1055 conn->name); 1056 1056 tcp_conn_state_set(conn, st_time_wait); … … 1091 1091 static void tcp_conn_seg_process(tcp_conn_t *conn, tcp_segment_t *seg) 1092 1092 { 1093 log_msg(L VL_DEBUG, "tcp_conn_seg_process(%p, %p)", conn, seg);1093 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_conn_seg_process(%p, %p)", conn, seg); 1094 1094 tcp_segment_dump(seg); 1095 1095 … … 1097 1097 /* XXX Permit valid ACKs, URGs and RSTs */ 1098 1098 /* if (!seq_no_segment_acceptable(conn, seg)) { 1099 log_msg(L VL_WARN, "Segment not acceptable, dropping.");1099 log_msg(LOG_DEFAULT, LVL_WARN, "Segment not acceptable, dropping."); 1100 1100 if ((seg->ctrl & CTL_RST) == 0) { 1101 1101 tcp_tqueue_ctrl_seg(conn, CTL_ACK); … … 1131 1131 */ 1132 1132 if (seg->len > 0) { 1133 log_msg(L VL_DEBUG, "Re-insert segment %p. seg->len=%zu",1133 log_msg(LOG_DEFAULT, LVL_DEBUG, "Re-insert segment %p. seg->len=%zu", 1134 1134 seg, (size_t) seg->len); 1135 1135 tcp_iqueue_insert_seg(&conn->incoming, seg); … … 1146 1146 void tcp_conn_segment_arrived(tcp_conn_t *conn, tcp_segment_t *seg) 1147 1147 { 1148 log_msg(L VL_DEBUG, "%c: tcp_conn_segment_arrived(%p)",1148 log_msg(LOG_DEFAULT, LVL_DEBUG, "%c: tcp_conn_segment_arrived(%p)", 1149 1149 conn->name, seg); 1150 1150 … … 1165 1165 tcp_conn_sa_queue(conn, seg); break; 1166 1166 case st_closed: 1167 log_msg(L VL_DEBUG, "state=%d", (int) conn->cstate);1167 log_msg(LOG_DEFAULT, LVL_DEBUG, "state=%d", (int) conn->cstate); 1168 1168 assert(false); 1169 1169 } … … 1178 1178 tcp_conn_t *conn = (tcp_conn_t *) arg; 1179 1179 1180 log_msg(L VL_DEBUG, "tw_timeout_func(%p)", conn);1180 log_msg(LOG_DEFAULT, LVL_DEBUG, "tw_timeout_func(%p)", conn); 1181 1181 1182 1182 fibril_mutex_lock(&conn->lock); 1183 1183 1184 1184 if (conn->cstate == st_closed) { 1185 log_msg(L VL_DEBUG, "Connection already closed.");1185 log_msg(LOG_DEFAULT, LVL_DEBUG, "Connection already closed."); 1186 1186 fibril_mutex_unlock(&conn->lock); 1187 1187 tcp_conn_delref(conn); … … 1189 1189 } 1190 1190 1191 log_msg(L VL_DEBUG, "%s: TW Timeout -> Closed", conn->name);1191 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: TW Timeout -> Closed", conn->name); 1192 1192 tcp_conn_remove(conn); 1193 1193 tcp_conn_state_set(conn, st_closed); … … 1240 1240 void tcp_unexpected_segment(tcp_sockpair_t *sp, tcp_segment_t *seg) 1241 1241 { 1242 log_msg(L VL_DEBUG, "tcp_unexpected_segment(%p, %p)", sp, seg);1242 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_unexpected_segment(%p, %p)", sp, seg); 1243 1243 1244 1244 if ((seg->ctrl & CTL_RST) == 0) … … 1268 1268 tcp_segment_t *rseg; 1269 1269 1270 log_msg(L VL_DEBUG, "tcp_reply_rst(%p, %p)", sp, seg);1270 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_reply_rst(%p, %p)", sp, seg); 1271 1271 1272 1272 rseg = tcp_segment_make_rst(seg); -
uspace/srv/net/tcp/iqueue.c
r920d0fc ra1a101d 67 67 tcp_iqueue_entry_t *qe; 68 68 link_t *link; 69 log_msg(L VL_DEBUG, "tcp_iqueue_insert_seg()");69 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_iqueue_insert_seg()"); 70 70 71 71 iqe = calloc(1, sizeof(tcp_iqueue_entry_t)); 72 72 if (iqe == NULL) { 73 log_msg(L VL_ERROR, "Failed allocating IQE.");73 log_msg(LOG_DEFAULT, LVL_ERROR, "Failed allocating IQE."); 74 74 return; 75 75 } … … 108 108 link_t *link; 109 109 110 log_msg(L VL_DEBUG, "tcp_get_ready_seg()");110 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_get_ready_seg()"); 111 111 112 112 link = list_first(&iqueue->list); 113 113 if (link == NULL) { 114 log_msg(L VL_DEBUG, "iqueue is empty");114 log_msg(LOG_DEFAULT, LVL_DEBUG, "iqueue is empty"); 115 115 return ENOENT; 116 116 } … … 119 119 120 120 while (!seq_no_segment_acceptable(iqueue->conn, iqe->seg)) { 121 log_msg(L VL_DEBUG, "Skipping unacceptable segment (RCV.NXT=%"121 log_msg(LOG_DEFAULT, LVL_DEBUG, "Skipping unacceptable segment (RCV.NXT=%" 122 122 PRIu32 ", RCV.NXT+RCV.WND=%" PRIu32 ", SEG.SEQ=%" PRIu32 123 123 ", SEG.LEN=%" PRIu32 ")", iqueue->conn->rcv_nxt, … … 130 130 link = list_first(&iqueue->list); 131 131 if (link == NULL) { 132 log_msg(L VL_DEBUG, "iqueue is empty");132 log_msg(LOG_DEFAULT, LVL_DEBUG, "iqueue is empty"); 133 133 return ENOENT; 134 134 } … … 139 139 /* Do not return segments that are not ready for processing */ 140 140 if (!seq_no_segment_ready(iqueue->conn, iqe->seg)) { 141 log_msg(L VL_DEBUG, "Next segment not ready: SEG.SEQ=%u, "141 log_msg(LOG_DEFAULT, LVL_DEBUG, "Next segment not ready: SEG.SEQ=%u, " 142 142 "RCV.NXT=%u, SEG.LEN=%u", iqe->seg->seq, 143 143 iqueue->conn->rcv_nxt, iqe->seg->len); … … 145 145 } 146 146 147 log_msg(L VL_DEBUG, "Returning ready segment %p", iqe->seg);147 log_msg(LOG_DEFAULT, LVL_DEBUG, "Returning ready segment %p", iqe->seg); 148 148 list_remove(&iqe->link); 149 149 *seg = iqe->seg; -
uspace/srv/net/tcp/ncsim.c
r920d0fc ra1a101d 74 74 link_t *link; 75 75 76 log_msg(L VL_DEBUG, "tcp_ncsim_bounce_seg()");76 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_ncsim_bounce_seg()"); 77 77 tcp_rqueue_bounce_seg(sp, seg); 78 78 return; … … 80 80 if (0 /*random() % 4 == 3*/) { 81 81 /* Drop segment */ 82 log_msg(L VL_ERROR, "NCSim dropping segment");82 log_msg(LOG_DEFAULT, LVL_ERROR, "NCSim dropping segment"); 83 83 tcp_segment_delete(seg); 84 84 return; … … 87 87 sqe = calloc(1, sizeof(tcp_squeue_entry_t)); 88 88 if (sqe == NULL) { 89 log_msg(L VL_ERROR, "Failed allocating SQE.");89 log_msg(LOG_DEFAULT, LVL_ERROR, "Failed allocating SQE."); 90 90 return; 91 91 } … … 126 126 int rc; 127 127 128 log_msg(L VL_DEBUG, "tcp_ncsim_fibril()");128 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_ncsim_fibril()"); 129 129 130 130 … … 139 139 sqe = list_get_instance(link, tcp_squeue_entry_t, link); 140 140 141 log_msg(L VL_DEBUG, "NCSim - Sleep");141 log_msg(LOG_DEFAULT, LVL_DEBUG, "NCSim - Sleep"); 142 142 rc = fibril_condvar_wait_timeout(&sim_queue_cv, 143 143 &sim_queue_lock, sqe->delay); … … 147 147 fibril_mutex_unlock(&sim_queue_lock); 148 148 149 log_msg(L VL_DEBUG, "NCSim - End Sleep");149 log_msg(LOG_DEFAULT, LVL_DEBUG, "NCSim - End Sleep"); 150 150 tcp_rqueue_bounce_seg(&sqe->sp, sqe->seg); 151 151 free(sqe); … … 161 161 fid_t fid; 162 162 163 log_msg(L VL_DEBUG, "tcp_ncsim_fibril_start()");163 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_ncsim_fibril_start()"); 164 164 165 165 fid = fibril_create(tcp_ncsim_fibril, NULL); 166 166 if (fid == 0) { 167 log_msg(L VL_ERROR, "Failed creating ncsim fibril.");167 log_msg(LOG_DEFAULT, LVL_ERROR, "Failed creating ncsim fibril."); 168 168 return; 169 169 } -
uspace/srv/net/tcp/rqueue.c
r920d0fc ra1a101d 74 74 tcp_sockpair_t rident; 75 75 76 log_msg(L VL_DEBUG, "tcp_rqueue_bounce_seg()");76 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_rqueue_bounce_seg()"); 77 77 78 78 #ifdef BOUNCE_TRANSCODE … … 81 81 82 82 if (tcp_pdu_encode(sp, seg, &pdu) != EOK) { 83 log_msg(L VL_WARN, "Not enough memory. Segment dropped.");83 log_msg(LOG_DEFAULT, LVL_WARN, "Not enough memory. Segment dropped."); 84 84 return; 85 85 } 86 86 87 87 if (tcp_pdu_decode(pdu, &rident, &dseg) != EOK) { 88 log_msg(L VL_WARN, "Not enough memory. Segment dropped.");88 log_msg(LOG_DEFAULT, LVL_WARN, "Not enough memory. Segment dropped."); 89 89 return; 90 90 } … … 112 112 { 113 113 tcp_rqueue_entry_t *rqe; 114 log_msg(L VL_DEBUG, "tcp_rqueue_insert_seg()");114 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_rqueue_insert_seg()"); 115 115 116 116 tcp_segment_dump(seg); … … 118 118 rqe = calloc(1, sizeof(tcp_rqueue_entry_t)); 119 119 if (rqe == NULL) { 120 log_msg(L VL_ERROR, "Failed allocating RQE.");120 log_msg(LOG_DEFAULT, LVL_ERROR, "Failed allocating RQE."); 121 121 return; 122 122 } … … 134 134 tcp_rqueue_entry_t *rqe; 135 135 136 log_msg(L VL_DEBUG, "tcp_rqueue_fibril()");136 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_rqueue_fibril()"); 137 137 138 138 while (true) { … … 152 152 fid_t fid; 153 153 154 log_msg(L VL_DEBUG, "tcp_rqueue_fibril_start()");154 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_rqueue_fibril_start()"); 155 155 156 156 fid = fibril_create(tcp_rqueue_fibril, NULL); 157 157 if (fid == 0) { 158 log_msg(L VL_ERROR, "Failed creating rqueue fibril.");158 log_msg(LOG_DEFAULT, LVL_ERROR, "Failed creating rqueue fibril."); 159 159 return; 160 160 } -
uspace/srv/net/tcp/segment.c
r920d0fc ra1a101d 248 248 void tcp_segment_dump(tcp_segment_t *seg) 249 249 { 250 log_msg(L VL_DEBUG2, "Segment dump:");251 log_msg(L VL_DEBUG2, " - ctrl = %u", (unsigned)seg->ctrl);252 log_msg(L VL_DEBUG2, " - seq = % " PRIu32, seg->seq);253 log_msg(L VL_DEBUG2, " - ack = % " PRIu32, seg->ack);254 log_msg(L VL_DEBUG2, " - len = % " PRIu32, seg->len);255 log_msg(L VL_DEBUG2, " - wnd = % " PRIu32, seg->wnd);256 log_msg(L VL_DEBUG2, " - up = % " PRIu32, seg->up);250 log_msg(LOG_DEFAULT, LVL_DEBUG2, "Segment dump:"); 251 log_msg(LOG_DEFAULT, LVL_DEBUG2, " - ctrl = %u", (unsigned)seg->ctrl); 252 log_msg(LOG_DEFAULT, LVL_DEBUG2, " - seq = % " PRIu32, seg->seq); 253 log_msg(LOG_DEFAULT, LVL_DEBUG2, " - ack = % " PRIu32, seg->ack); 254 log_msg(LOG_DEFAULT, LVL_DEBUG2, " - len = % " PRIu32, seg->len); 255 log_msg(LOG_DEFAULT, LVL_DEBUG2, " - wnd = % " PRIu32, seg->wnd); 256 log_msg(LOG_DEFAULT, LVL_DEBUG2, " - up = % " PRIu32, seg->up); 257 257 } 258 258 -
uspace/srv/net/tcp/sock.c
r920d0fc ra1a101d 91 91 static void tcp_sock_notify_data(socket_core_t *sock_core) 92 92 { 93 log_msg(L VL_DEBUG, "tcp_sock_notify_data(%d)", sock_core->socket_id);93 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_notify_data(%d)", sock_core->socket_id); 94 94 async_exch_t *exch = async_exchange_begin(sock_core->sess); 95 95 async_msg_5(exch, NET_SOCKET_RECEIVED, (sysarg_t)sock_core->socket_id, … … 100 100 static void tcp_sock_notify_aconn(socket_core_t *lsock_core) 101 101 { 102 log_msg(L VL_DEBUG, "tcp_sock_notify_aconn(%d)", lsock_core->socket_id);102 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_notify_aconn(%d)", lsock_core->socket_id); 103 103 async_exch_t *exch = async_exchange_begin(lsock_core->sess); 104 104 async_msg_5(exch, NET_SOCKET_ACCEPTED, (sysarg_t)lsock_core->socket_id, … … 111 111 tcp_sockdata_t *sock; 112 112 113 log_msg(L VL_DEBUG, "tcp_sock_create()");113 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_create()"); 114 114 *rsock = NULL; 115 115 … … 133 133 static void tcp_sock_uncreate(tcp_sockdata_t *sock) 134 134 { 135 log_msg(L VL_DEBUG, "tcp_sock_uncreate()");135 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_uncreate()"); 136 136 free(sock); 137 137 } … … 142 142 int rc; 143 143 144 log_msg(L VL_DEBUG, "tcp_sock_finish_setup()");144 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_finish_setup()"); 145 145 146 146 sock->recv_fibril = fibril_create(tcp_sock_recv_fibril, sock); … … 171 171 ipc_call_t answer; 172 172 173 log_msg(L VL_DEBUG, "tcp_sock_socket()");173 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_socket()"); 174 174 175 175 rc = tcp_sock_create(client, &sock); … … 208 208 tcp_sockdata_t *socket; 209 209 210 log_msg(L VL_DEBUG, "tcp_sock_bind()");211 log_msg(L VL_DEBUG, " - async_data_write_accept");210 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_bind()"); 211 log_msg(LOG_DEFAULT, LVL_DEBUG, " - async_data_write_accept"); 212 212 rc = async_data_write_accept((void **) &addr, false, 0, 0, 0, &addr_len); 213 213 if (rc != EOK) { … … 216 216 } 217 217 218 log_msg(L VL_DEBUG, " - call socket_bind");218 log_msg(LOG_DEFAULT, LVL_DEBUG, " - call socket_bind"); 219 219 rc = socket_bind(&client->sockets, &gsock, SOCKET_GET_SOCKET_ID(call), 220 220 addr, addr_len, TCP_FREE_PORTS_START, TCP_FREE_PORTS_END, … … 225 225 } 226 226 227 log_msg(L VL_DEBUG, " - call socket_cores_find");227 log_msg(LOG_DEFAULT, LVL_DEBUG, " - call socket_cores_find"); 228 228 sock_core = socket_cores_find(&client->sockets, SOCKET_GET_SOCKET_ID(call)); 229 229 if (sock_core != NULL) { … … 233 233 } 234 234 235 log_msg(L VL_DEBUG, " - success");235 log_msg(LOG_DEFAULT, LVL_DEBUG, " - success"); 236 236 async_answer_0(callid, EOK); 237 237 } … … 250 250 int i; 251 251 252 log_msg(L VL_DEBUG, "tcp_sock_listen()");252 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_listen()"); 253 253 254 254 socket_id = SOCKET_GET_SOCKET_ID(call); … … 284 284 } 285 285 286 log_msg(L VL_DEBUG, " - open connections");286 log_msg(LOG_DEFAULT, LVL_DEBUG, " - open connections"); 287 287 288 288 lsocket.addr.ipv4 = TCP_IPV4_ANY; … … 337 337 tcp_sock_t fsocket; 338 338 339 log_msg(L VL_DEBUG, "tcp_sock_connect()");339 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_connect()"); 340 340 341 341 rc = async_data_write_accept((void **) &addr, false, 0, 0, 0, &addr_len); … … 377 377 fibril_mutex_unlock(&socket->lock); 378 378 async_answer_0(callid, rc); 379 log_msg(L VL_DEBUG, "tcp_sock_connect: Failed to "379 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_connect: Failed to " 380 380 "determine local address."); 381 381 return; … … 383 383 384 384 socket->laddr.ipv4 = loc_addr.ipv4; 385 log_msg(L VL_DEBUG, "Local IP address is %x", socket->laddr.ipv4);385 log_msg(LOG_DEFAULT, LVL_DEBUG, "Local IP address is %x", socket->laddr.ipv4); 386 386 } 387 387 … … 431 431 int rc; 432 432 433 log_msg(L VL_DEBUG, "tcp_sock_accept()");433 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_accept()"); 434 434 435 435 socket_id = SOCKET_GET_SOCKET_ID(call); … … 445 445 fibril_mutex_lock(&socket->lock); 446 446 447 log_msg(L VL_DEBUG, " - verify socket->conn");447 log_msg(LOG_DEFAULT, LVL_DEBUG, " - verify socket->conn"); 448 448 if (socket->conn != NULL) { 449 449 fibril_mutex_unlock(&socket->lock); … … 498 498 499 499 asocket->conn = conn; 500 log_msg(L VL_DEBUG, "tcp_sock_accept():create asocket\n");500 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_accept():create asocket\n"); 501 501 502 502 rc = tcp_sock_finish_setup(asocket, &asock_id); … … 510 510 fibril_add_ready(asocket->recv_fibril); 511 511 512 log_msg(L VL_DEBUG, "tcp_sock_accept(): find acore\n");512 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_accept(): find acore\n"); 513 513 514 514 SOCKET_SET_DATA_FRAGMENT_SIZE(answer, TCP_SOCK_FRAGMENT_SIZE); … … 521 521 522 522 /* Push one fragment notification to client's queue */ 523 log_msg(L VL_DEBUG, "tcp_sock_accept(): notify data\n");523 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_accept(): notify data\n"); 524 524 fibril_mutex_unlock(&socket->lock); 525 525 } … … 539 539 int rc; 540 540 541 log_msg(L VL_DEBUG, "tcp_sock_send()");541 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_send()"); 542 542 socket_id = SOCKET_GET_SOCKET_ID(call); 543 543 fragments = SOCKET_GET_DATA_FRAGMENTS(call); … … 611 611 static void tcp_sock_sendto(tcp_client_t *client, ipc_callid_t callid, ipc_call_t call) 612 612 { 613 log_msg(L VL_DEBUG, "tcp_sock_sendto()");613 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_sendto()"); 614 614 async_answer_0(callid, ENOTSUP); 615 615 } … … 629 629 int rc; 630 630 631 log_msg(L VL_DEBUG, "%p: tcp_sock_recv[from]()", client);631 log_msg(LOG_DEFAULT, LVL_DEBUG, "%p: tcp_sock_recv[from]()", client); 632 632 633 633 socket_id = SOCKET_GET_SOCKET_ID(call); … … 651 651 (void)flags; 652 652 653 log_msg(L VL_DEBUG, "tcp_sock_recvfrom(): lock recv_buffer_lock");653 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_recvfrom(): lock recv_buffer_lock"); 654 654 fibril_mutex_lock(&socket->recv_buffer_lock); 655 655 while (socket->recv_buffer_used == 0 && socket->recv_error == TCP_EOK) { 656 log_msg(L VL_DEBUG, "wait for recv_buffer_cv + recv_buffer_used != 0");656 log_msg(LOG_DEFAULT, LVL_DEBUG, "wait for recv_buffer_cv + recv_buffer_used != 0"); 657 657 fibril_condvar_wait(&socket->recv_buffer_cv, 658 658 &socket->recv_buffer_lock); 659 659 } 660 660 661 log_msg(L VL_DEBUG, "Got data in sock recv_buffer");661 log_msg(LOG_DEFAULT, LVL_DEBUG, "Got data in sock recv_buffer"); 662 662 663 663 data_len = socket->recv_buffer_used; … … 679 679 } 680 680 681 log_msg(L VL_DEBUG, "**** recv result -> %d", rc);681 log_msg(LOG_DEFAULT, LVL_DEBUG, "**** recv result -> %d", rc); 682 682 if (rc != EOK) { 683 683 fibril_mutex_unlock(&socket->recv_buffer_lock); … … 694 694 addr.sin_port = host2uint16_t_be(rsock->port); 695 695 696 log_msg(L VL_DEBUG, "addr read receive");696 log_msg(LOG_DEFAULT, LVL_DEBUG, "addr read receive"); 697 697 if (!async_data_read_receive(&rcallid, &addr_length)) { 698 698 fibril_mutex_unlock(&socket->recv_buffer_lock); … … 705 705 addr_length = sizeof(addr); 706 706 707 log_msg(L VL_DEBUG, "addr read finalize");707 log_msg(LOG_DEFAULT, LVL_DEBUG, "addr read finalize"); 708 708 rc = async_data_read_finalize(rcallid, &addr, addr_length); 709 709 if (rc != EOK) { … … 715 715 } 716 716 717 log_msg(L VL_DEBUG, "data read receive");717 log_msg(LOG_DEFAULT, LVL_DEBUG, "data read receive"); 718 718 if (!async_data_read_receive(&rcallid, &length)) { 719 719 fibril_mutex_unlock(&socket->recv_buffer_lock); … … 726 726 length = data_len; 727 727 728 log_msg(L VL_DEBUG, "data read finalize");728 log_msg(LOG_DEFAULT, LVL_DEBUG, "data read finalize"); 729 729 rc = async_data_read_finalize(rcallid, socket->recv_buffer, length); 730 730 731 731 socket->recv_buffer_used -= length; 732 log_msg(L VL_DEBUG, "tcp_sock_recvfrom: %zu left in buffer",732 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_recvfrom: %zu left in buffer", 733 733 socket->recv_buffer_used); 734 734 if (socket->recv_buffer_used > 0) { … … 758 758 int rc; 759 759 760 log_msg(L VL_DEBUG, "tcp_sock_close()");760 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_close()"); 761 761 socket_id = SOCKET_GET_SOCKET_ID(call); 762 762 … … 798 798 static void tcp_sock_getsockopt(tcp_client_t *client, ipc_callid_t callid, ipc_call_t call) 799 799 { 800 log_msg(L VL_DEBUG, "tcp_sock_getsockopt()");800 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_getsockopt()"); 801 801 async_answer_0(callid, ENOTSUP); 802 802 } … … 804 804 static void tcp_sock_setsockopt(tcp_client_t *client, ipc_callid_t callid, ipc_call_t call) 805 805 { 806 log_msg(L VL_DEBUG, "tcp_sock_setsockopt()");806 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_setsockopt()"); 807 807 async_answer_0(callid, ENOTSUP); 808 808 } … … 815 815 tcp_sockdata_t *socket = lconn->socket; 816 816 817 log_msg(L VL_DEBUG, "tcp_sock_cstate_cb()");817 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_cstate_cb()"); 818 818 fibril_mutex_lock(&socket->lock); 819 819 assert(conn == lconn->conn); … … 828 828 list_append(&lconn->ready_list, &socket->ready); 829 829 830 log_msg(L VL_DEBUG, "tcp_sock_cstate_cb(): notify accept");830 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_cstate_cb(): notify accept"); 831 831 832 832 /* Push one accept notification to client's queue */ … … 842 842 tcp_error_t trc; 843 843 844 log_msg(L VL_DEBUG, "tcp_sock_recv_fibril()");844 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_recv_fibril()"); 845 845 846 846 fibril_mutex_lock(&sock->recv_buffer_lock); 847 847 848 848 while (true) { 849 log_msg(L VL_DEBUG, "call tcp_uc_receive()");849 log_msg(LOG_DEFAULT, LVL_DEBUG, "call tcp_uc_receive()"); 850 850 while (sock->recv_buffer_used != 0 && sock->sock_core != NULL) 851 851 fibril_condvar_wait(&sock->recv_buffer_cv, … … 863 863 } 864 864 865 log_msg(L VL_DEBUG, "got data - broadcast recv_buffer_cv");865 log_msg(LOG_DEFAULT, LVL_DEBUG, "got data - broadcast recv_buffer_cv"); 866 866 867 867 sock->recv_buffer_used = data_len; … … 895 895 break; 896 896 897 log_msg(L VL_DEBUG, "tcp_sock_connection: METHOD=%d\n",897 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_connection: METHOD=%d\n", 898 898 (int)IPC_GET_IMETHOD(call)); 899 899 … … 940 940 941 941 /* Clean up */ 942 log_msg(L VL_DEBUG, "tcp_sock_connection: Clean up");942 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_sock_connection: Clean up"); 943 943 async_hangup(client.sess); 944 944 socket_cores_release(NULL, &client.sockets, &gsock, tcp_free_sock_data); -
uspace/srv/net/tcp/tcp.c
r920d0fc ra1a101d 69 69 size_t pdu_raw_size; 70 70 71 log_msg(L VL_DEBUG, "tcp_inet_ev_recv()");71 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_inet_ev_recv()"); 72 72 73 73 pdu_raw = dgram->data; … … 76 76 /* Split into header and payload. */ 77 77 78 log_msg(L VL_DEBUG, "tcp_inet_ev_recv() - split header/payload");78 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_inet_ev_recv() - split header/payload"); 79 79 80 80 tcp_pdu_t *pdu; … … 84 84 85 85 if (pdu_raw_size < sizeof(tcp_header_t)) { 86 log_msg(L VL_WARN, "pdu_raw_size = %zu < sizeof(tcp_header_t) = %zu",86 log_msg(LOG_DEFAULT, LVL_WARN, "pdu_raw_size = %zu < sizeof(tcp_header_t) = %zu", 87 87 pdu_raw_size, sizeof(tcp_header_t)); 88 88 return EINVAL; … … 96 96 97 97 if (pdu_raw_size < hdr_size) { 98 log_msg(L VL_WARN, "pdu_raw_size = %zu < hdr_size = %zu",98 log_msg(LOG_DEFAULT, LVL_WARN, "pdu_raw_size = %zu < hdr_size = %zu", 99 99 pdu_raw_size, hdr_size); 100 100 return EINVAL; … … 102 102 103 103 if (hdr_size < sizeof(tcp_header_t)) { 104 log_msg(L VL_WARN, "hdr_size = %zu < sizeof(tcp_header_t) = %zu",104 log_msg(LOG_DEFAULT, LVL_WARN, "hdr_size = %zu < sizeof(tcp_header_t) = %zu", 105 105 hdr_size, sizeof(tcp_header_t)); return EINVAL; 106 106 } 107 107 108 log_msg(L VL_DEBUG, "pdu_raw_size=%zu, hdr_size=%zu",108 log_msg(LOG_DEFAULT, LVL_DEBUG, "pdu_raw_size=%zu, hdr_size=%zu", 109 109 pdu_raw_size, hdr_size); 110 110 pdu = tcp_pdu_create(pdu_raw, hdr_size, pdu_raw + hdr_size, 111 111 pdu_raw_size - hdr_size); 112 112 if (pdu == NULL) { 113 log_msg(L VL_WARN, "Failed creating PDU. Dropped.");113 log_msg(LOG_DEFAULT, LVL_WARN, "Failed creating PDU. Dropped."); 114 114 return ENOMEM; 115 115 } … … 117 117 pdu->src_addr.ipv4 = dgram->src.ipv4; 118 118 pdu->dest_addr.ipv4 = dgram->dest.ipv4; 119 log_msg(L VL_DEBUG, "src: 0x%08x, dest: 0x%08x",119 log_msg(LOG_DEFAULT, LVL_DEBUG, "src: 0x%08x, dest: 0x%08x", 120 120 pdu->src_addr.ipv4, pdu->dest_addr.ipv4); 121 121 … … 137 137 pdu_raw = malloc(pdu_raw_size); 138 138 if (pdu_raw == NULL) { 139 log_msg(L VL_ERROR, "Failed to transmit PDU. Out of memory.");139 log_msg(LOG_DEFAULT, LVL_ERROR, "Failed to transmit PDU. Out of memory."); 140 140 return; 141 141 } … … 153 153 rc = inet_send(&dgram, INET_TTL_MAX, 0); 154 154 if (rc != EOK) 155 log_msg(L VL_ERROR, "Failed to transmit PDU.");155 log_msg(LOG_DEFAULT, LVL_ERROR, "Failed to transmit PDU."); 156 156 } 157 157 … … 162 162 tcp_sockpair_t rident; 163 163 164 log_msg(L VL_DEBUG, "tcp_received_pdu()");164 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_received_pdu()"); 165 165 166 166 if (tcp_pdu_decode(pdu, &rident, &dseg) != EOK) { 167 log_msg(L VL_WARN, "Not enough memory. PDU dropped.");167 log_msg(LOG_DEFAULT, LVL_WARN, "Not enough memory. PDU dropped."); 168 168 return; 169 169 } … … 177 177 int rc; 178 178 179 log_msg(L VL_DEBUG, "tcp_init()");179 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_init()"); 180 180 181 181 tcp_rqueue_init(); … … 189 189 rc = inet_init(IP_PROTO_TCP, &tcp_inet_ev_ops); 190 190 if (rc != EOK) { 191 log_msg(L VL_ERROR, "Failed connecting to internet service.");191 log_msg(LOG_DEFAULT, LVL_ERROR, "Failed connecting to internet service."); 192 192 return ENOENT; 193 193 } … … 195 195 rc = tcp_sock_init(); 196 196 if (rc != EOK) { 197 log_msg(L VL_ERROR, "Failed initializing socket service.");197 log_msg(LOG_DEFAULT, LVL_ERROR, "Failed initializing socket service."); 198 198 return ENOENT; 199 199 } -
uspace/srv/net/tcp/tqueue.c
r920d0fc ra1a101d 88 88 tcp_segment_t *seg; 89 89 90 log_msg(L VL_DEBUG, "tcp_tqueue_ctrl_seg(%p, %u)", conn, ctrl);90 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_tqueue_ctrl_seg(%p, %u)", conn, ctrl); 91 91 92 92 seg = tcp_segment_make_ctrl(ctrl); … … 99 99 tcp_tqueue_entry_t *tqe; 100 100 101 log_msg(L VL_DEBUG, "%s: tcp_tqueue_seg(%p, %p)", conn->name, conn,101 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_tqueue_seg(%p, %p)", conn->name, conn, 102 102 seg); 103 103 … … 109 109 rt_seg = tcp_segment_dup(seg); 110 110 if (rt_seg == NULL) { 111 log_msg(L VL_ERROR, "Memory allocation failed.");111 log_msg(LOG_DEFAULT, LVL_ERROR, "Memory allocation failed."); 112 112 /* XXX Handle properly */ 113 113 return; … … 116 116 tqe = calloc(1, sizeof(tcp_tqueue_entry_t)); 117 117 if (tqe == NULL) { 118 log_msg(L VL_ERROR, "Memory allocation failed.");118 log_msg(LOG_DEFAULT, LVL_ERROR, "Memory allocation failed."); 119 119 /* XXX Handle properly */ 120 120 return; … … 165 165 tcp_segment_t *seg; 166 166 167 log_msg(L VL_DEBUG, "%s: tcp_tqueue_new_data()", conn->name);167 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_tqueue_new_data()", conn->name); 168 168 169 169 /* Number of free sequence numbers in send window */ … … 172 172 173 173 xfer_seqlen = min(snd_buf_seqlen, avail_wnd); 174 log_msg(L VL_DEBUG, "%s: snd_buf_seqlen = %zu, SND.WND = %zu, "174 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: snd_buf_seqlen = %zu, SND.WND = %zu, " 175 175 "xfer_seqlen = %zu", conn->name, snd_buf_seqlen, conn->snd_wnd, 176 176 xfer_seqlen); … … 185 185 186 186 if (send_fin) { 187 log_msg(L VL_DEBUG, "%s: Sending out FIN.", conn->name);187 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: Sending out FIN.", conn->name); 188 188 /* We are sending out FIN */ 189 189 ctrl = CTL_FIN; … … 194 194 seg = tcp_segment_make_data(ctrl, conn->snd_buf, data_size); 195 195 if (seg == NULL) { 196 log_msg(L VL_ERROR, "Memory allocation failure.");196 log_msg(LOG_DEFAULT, LVL_ERROR, "Memory allocation failure."); 197 197 return; 198 198 } … … 223 223 link_t *cur, *next; 224 224 225 log_msg(L VL_DEBUG, "%s: tcp_tqueue_ack_received(%p)", conn->name,225 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_tqueue_ack_received(%p)", conn->name, 226 226 conn); 227 227 … … 239 239 240 240 if ((tqe->seg->ctrl & CTL_FIN) != 0) { 241 log_msg(L VL_DEBUG, "Fin has been acked");242 log_msg(L VL_DEBUG, "SND.UNA=%" PRIu32241 log_msg(LOG_DEFAULT, LVL_DEBUG, "Fin has been acked"); 242 log_msg(LOG_DEFAULT, LVL_DEBUG, "SND.UNA=%" PRIu32 243 243 " SEG.SEQ=%" PRIu32 " SEG.LEN=%" PRIu32, 244 244 conn->snd_una, tqe->seg->seq, tqe->seg->len); … … 267 267 void tcp_conn_transmit_segment(tcp_conn_t *conn, tcp_segment_t *seg) 268 268 { 269 log_msg(L VL_DEBUG, "%s: tcp_conn_transmit_segment(%p, %p)",269 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_conn_transmit_segment(%p, %p)", 270 270 conn->name, conn, seg); 271 271 … … 282 282 void tcp_transmit_segment(tcp_sockpair_t *sp, tcp_segment_t *seg) 283 283 { 284 log_msg(L VL_DEBUG, "tcp_transmit_segment(f:(%x,%u),l:(%x,%u), %p)",284 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_transmit_segment(f:(%x,%u),l:(%x,%u), %p)", 285 285 sp->foreign.addr.ipv4, sp->foreign.port, 286 286 sp->local.addr.ipv4, sp->local.port, seg); 287 287 288 log_msg(L VL_DEBUG, "SEG.SEQ=%" PRIu32 ", SEG.WND=%" PRIu32,288 log_msg(LOG_DEFAULT, LVL_DEBUG, "SEG.SEQ=%" PRIu32 ", SEG.WND=%" PRIu32, 289 289 seg->seq, seg->wnd); 290 290 … … 300 300 301 301 if (tcp_pdu_encode(sp, seg, &pdu) != EOK) { 302 log_msg(L VL_WARN, "Not enough memory. Segment dropped.");302 log_msg(LOG_DEFAULT, LVL_WARN, "Not enough memory. Segment dropped."); 303 303 return; 304 304 } … … 315 315 link_t *link; 316 316 317 log_msg(L VL_DEBUG, "### %s: retransmit_timeout_func(%p)", conn->name, conn);317 log_msg(LOG_DEFAULT, LVL_DEBUG, "### %s: retransmit_timeout_func(%p)", conn->name, conn); 318 318 319 319 fibril_mutex_lock(&conn->lock); 320 320 321 321 if (conn->cstate == st_closed) { 322 log_msg(L VL_DEBUG, "Connection already closed.");322 log_msg(LOG_DEFAULT, LVL_DEBUG, "Connection already closed."); 323 323 fibril_mutex_unlock(&conn->lock); 324 324 tcp_conn_delref(conn); … … 328 328 link = list_first(&conn->retransmit.list); 329 329 if (link == NULL) { 330 log_msg(L VL_DEBUG, "Nothing to retransmit");330 log_msg(LOG_DEFAULT, LVL_DEBUG, "Nothing to retransmit"); 331 331 fibril_mutex_unlock(&conn->lock); 332 332 tcp_conn_delref(conn); … … 338 338 rt_seg = tcp_segment_dup(tqe->seg); 339 339 if (rt_seg == NULL) { 340 log_msg(L VL_ERROR, "Memory allocation failed.");340 log_msg(LOG_DEFAULT, LVL_ERROR, "Memory allocation failed."); 341 341 fibril_mutex_unlock(&conn->lock); 342 342 tcp_conn_delref(conn); … … 345 345 } 346 346 347 log_msg(L VL_DEBUG, "### %s: retransmitting segment", conn->name);347 log_msg(LOG_DEFAULT, LVL_DEBUG, "### %s: retransmitting segment", conn->name); 348 348 tcp_conn_transmit_segment(tqe->conn, rt_seg); 349 349 … … 358 358 static void tcp_tqueue_timer_set(tcp_conn_t *conn) 359 359 { 360 log_msg(L VL_DEBUG, "### %s: tcp_tqueue_timer_set()", conn->name);360 log_msg(LOG_DEFAULT, LVL_DEBUG, "### %s: tcp_tqueue_timer_set()", conn->name); 361 361 362 362 /* Clear first to make sure we update refcnt correctly */ … … 371 371 static void tcp_tqueue_timer_clear(tcp_conn_t *conn) 372 372 { 373 log_msg(L VL_DEBUG, "### %s: tcp_tqueue_timer_clear()", conn->name);373 log_msg(LOG_DEFAULT, LVL_DEBUG, "### %s: tcp_tqueue_timer_clear()", conn->name); 374 374 375 375 if (fibril_timer_clear(conn->retransmit.timer) == fts_active) -
uspace/srv/net/tcp/ucall.c
r920d0fc ra1a101d 70 70 tcp_conn_t *nconn; 71 71 72 log_msg(L VL_DEBUG, "tcp_uc_open(%p, %p, %s, %s, %p)",72 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_uc_open(%p, %p, %s, %s, %p)", 73 73 lsock, fsock, acpass == ap_active ? "active" : "passive", 74 74 oflags == tcp_open_nonblock ? "nonblock" : "none", conn); … … 88 88 89 89 /* Wait for connection to be established or reset */ 90 log_msg(L VL_DEBUG, "tcp_uc_open: Wait for connection.");90 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_uc_open: Wait for connection."); 91 91 fibril_mutex_lock(&nconn->lock); 92 92 while (nconn->cstate == st_listen || … … 97 97 98 98 if (nconn->cstate != st_established) { 99 log_msg(L VL_DEBUG, "tcp_uc_open: Connection was reset.");99 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_uc_open: Connection was reset."); 100 100 assert(nconn->cstate == st_closed); 101 101 fibril_mutex_unlock(&nconn->lock); … … 104 104 105 105 fibril_mutex_unlock(&nconn->lock); 106 log_msg(L VL_DEBUG, "tcp_uc_open: Connection was established.");106 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_uc_open: Connection was established."); 107 107 108 108 *conn = nconn; 109 log_msg(L VL_DEBUG, "tcp_uc_open -> %p", nconn);109 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_uc_open -> %p", nconn); 110 110 return TCP_EOK; 111 111 } … … 118 118 size_t xfer_size; 119 119 120 log_msg(L VL_DEBUG, "%s: tcp_uc_send()", conn->name);120 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_uc_send()", conn->name); 121 121 122 122 fibril_mutex_lock(&conn->lock); … … 141 141 buf_free = conn->snd_buf_size - conn->snd_buf_used; 142 142 while (buf_free == 0 && !conn->reset) { 143 log_msg(L VL_DEBUG, "%s: buf_free == 0, waiting.",143 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: buf_free == 0, waiting.", 144 144 conn->name); 145 145 fibril_condvar_wait(&conn->snd_buf_cv, &conn->lock); … … 175 175 size_t xfer_size; 176 176 177 log_msg(L VL_DEBUG, "%s: tcp_uc_receive()", conn->name);177 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_uc_receive()", conn->name); 178 178 179 179 fibril_mutex_lock(&conn->lock); … … 186 186 /* Wait for data to become available */ 187 187 while (conn->rcv_buf_used == 0 && !conn->rcv_buf_fin && !conn->reset) { 188 log_msg(L VL_DEBUG, "tcp_uc_receive() - wait for data");188 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_uc_receive() - wait for data"); 189 189 fibril_condvar_wait(&conn->rcv_buf_cv, &conn->lock); 190 190 } … … 223 223 tcp_tqueue_ctrl_seg(conn, CTL_ACK); 224 224 225 log_msg(L VL_DEBUG, "%s: tcp_uc_receive() - returning %zu bytes",225 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_uc_receive() - returning %zu bytes", 226 226 conn->name, xfer_size); 227 227 … … 234 234 tcp_error_t tcp_uc_close(tcp_conn_t *conn) 235 235 { 236 log_msg(L VL_DEBUG, "%s: tcp_uc_close()", conn->name);236 log_msg(LOG_DEFAULT, LVL_DEBUG, "%s: tcp_uc_close()", conn->name); 237 237 238 238 fibril_mutex_lock(&conn->lock); … … 258 258 void tcp_uc_abort(tcp_conn_t *conn) 259 259 { 260 log_msg(L VL_DEBUG, "tcp_uc_abort()");260 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_uc_abort()"); 261 261 } 262 262 … … 264 264 void tcp_uc_status(tcp_conn_t *conn, tcp_conn_status_t *cstatus) 265 265 { 266 log_msg(L VL_DEBUG, "tcp_uc_status()");266 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_uc_status()"); 267 267 cstatus->cstate = conn->cstate; 268 268 } … … 276 276 void tcp_uc_delete(tcp_conn_t *conn) 277 277 { 278 log_msg(L VL_DEBUG, "tcp_uc_delete()");278 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_uc_delete()"); 279 279 tcp_conn_delete(conn); 280 280 } … … 282 282 void tcp_uc_set_cstate_cb(tcp_conn_t *conn, tcp_cstate_cb_t cb, void *arg) 283 283 { 284 log_msg(L VL_DEBUG, "tcp_uc_set_ctate_cb(%p, %p, %p)",284 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_uc_set_ctate_cb(%p, %p, %p)", 285 285 conn, cb, arg); 286 286 … … 298 298 tcp_conn_t *conn; 299 299 300 log_msg(L VL_DEBUG, "tcp_as_segment_arrived(f:(%x,%u), l:(%x,%u))",300 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_as_segment_arrived(f:(%x,%u), l:(%x,%u))", 301 301 sp->foreign.addr.ipv4, sp->foreign.port, 302 302 sp->local.addr.ipv4, sp->local.port); … … 304 304 conn = tcp_conn_find_ref(sp); 305 305 if (conn == NULL) { 306 log_msg(L VL_WARN, "No connection found.");306 log_msg(LOG_DEFAULT, LVL_WARN, "No connection found."); 307 307 tcp_unexpected_segment(sp, seg); 308 308 return; … … 312 312 313 313 if (conn->cstate == st_closed) { 314 log_msg(L VL_WARN, "Connection is closed.");314 log_msg(LOG_DEFAULT, LVL_WARN, "Connection is closed."); 315 315 tcp_unexpected_segment(sp, seg); 316 316 fibril_mutex_unlock(&conn->lock); … … 339 339 void tcp_to_user(void) 340 340 { 341 log_msg(L VL_DEBUG, "tcp_to_user()");341 log_msg(LOG_DEFAULT, LVL_DEBUG, "tcp_to_user()"); 342 342 } 343 343
Note:
See TracChangeset
for help on using the changeset viewer.