Attachment 5004 Details for Bug 30132 – log level 10 excerpt, standard config, firewall off (UCS 3.1-0)

log level 10 excerpt, standard config, firewall off (UCS 3.1-0)

log_excerpt.txt (text/plain), 14.73 KB, created by Arvid Requate on 2013-01-24 13:24:38 CET

(hide)

Description:

Filename:

MIME Type:

Creator: Arvid Requate

Created: 2013-01-24 13:24:38 CET

Size: 14.73 KB

patch

obsolete

>+[2013/01/24 13:06:26,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_winsserver.c:767(initialise_wins)
>+  initialise_wins: add name: S4LISH#1b ttl = 178863 first IP 10.200.6.100 flags = 64
>+[2013/01/24 13:06:26,  3, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_namelistdb.c:263(add_name_to_subnet)
>+  add_name_to_subnet: Added netbios name S4LISH<1b> with first IP 10.200.6.100 ttl=178863 nb_flags=64 to subnet WINS_SERVER_SUBNET
>
>[...]
>
>+[2013/01/24 13:06:50,  0, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_become_lmb.c:397(become_local_master_stage2)
>+  *****
>+  
>+  Samba name server MASTER is now a local master browser for workgroup S4LISH on subnet 10.200.6.100
>+  
>+  *****
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet)
>+  find_workgroup_on_subnet: workgroup search for S4LISH on subnet UNICAST_SUBNET: found.
>+[2013/01/24 13:06:50, 10, pid=19131, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket)
>+  read_udp_v4_socket: ip 10.200.6.100 port 35328 read: 177
>+[2013/01/24 13:06:50,  5, pid=19131, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:835(read_packet)
>+  Received a packet of len 177 from (10.200.6.100) port 138
>+[2013/01/24 13:06:50, 10, pid=19131, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket)
>+  read_udp_v4_socket: ip 10.200.6.100 port 35328 read: 177
>+[2013/01/24 13:06:50,  5, pid=19131, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:835(read_packet)
>+  Received a packet of len 177 from (10.200.6.100) port 138
>+[2013/01/24 13:06:50,  7, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:2024(listen_for_packets)
>+  discarding duplicate packet from 10.200.6.100:138
>+[2013/01/24 13:06:50,  9, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_namelistdb.c:135(find_name_on_subnet)
>+  find_name_on_subnet: on subnet 10.200.6.100 - found name S4LISH<1e> source=2
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:1295(process_dgram)
>+  process_dgram: datagram from MASTER<00> to S4LISH<1e> IP 10.200.6.100 for \MAILSLOT\BROWSE of type 2 len=9
>+[2013/01/24 13:06:50,  8, pid=19131, effective(0, 0), real(0, 0)] ../source3/lib/util.c:1216(is_myname)
>+  is_myname("MASTER") returns 1
>+[2013/01/24 13:06:50,  7, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:1086(process_browse_packet)
>+  process_browse_packet: Discarding datagram from IP 10.200.6.100. Source name MASTER<00> is one of our names !
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet)
>+  find_workgroup_on_subnet: workgroup search for S4LISH on subnet 10.200.6.100: found.
>+[2013/01/24 13:06:50,  8, pid=19131, effective(0, 0), real(0, 0)] ../source3/lib/util.c:1216(is_myname)
>+  is_myname("MASTER") returns 1
>+[2013/01/24 13:06:50,  3, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:170(send_local_master_announcement)
>+  send_local_master_announcement: type 49b0b for name MASTER on subnet 10.200.6.100 for workgroup S4LISH
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:2129(send_mailslot)
>+  send_mailslot: Sending to mailslot \MAILSLOT\BROWSE from MASTER<00> IP 10.200.6.100 to S4LISH<1e> IP 10.200.6.255
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:116(debug_browse_data)
>+  debug_browse_data():
>+    0 char ......MASTER.... hex 0f 03 c0 d4 01 00 4d 41 53 54 45 52 00 00 00 00
>+   10 char ..............U. hex 00 00 00 00 00 00 04 09 0b 9b 04 00 0f 01 55 aa
>+   20 char Univention Corpo hex 55 6e 69 76 65 6e 74 69 6f 6e 20 43 6f 72 70 6f
>+   30 char rate Server.     hex 72 61 74 65 20 53 65 72 76 65 72 00
>+[2013/01/24 13:06:50,  5, pid=19131, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:857(send_udp)
>+  Sending a packet of len 228 to (10.200.6.255) on port 138
>+[2013/01/24 13:06:50,  3, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:189(send_workgroup_announcement)
>+  send_workgroup_announcement: on subnet 10.200.6.100 for workgroup S4LISH
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:2129(send_mailslot)
>+  send_mailslot: Sending to mailslot \MAILSLOT\BROWSE from MASTER<00> IP 10.200.6.100 to ^A^B__MSBROWSE__^B<01> IP 10.200.6.255
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:116(debug_browse_data)
>+  debug_browse_data():
>+    0 char ......S4LISH.... hex 0c 03 c0 d4 01 00 53 34 4c 49 53 48 00 00 00 00
>+   10 char ..............U. hex 00 00 00 00 00 00 04 09 00 10 00 80 0f 01 55 aa
>+   20 char MASTER.          hex 4d 41 53 54 45 52 00
>+[2013/01/24 13:06:50,  5, pid=19131, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:857(send_udp)
>+  Sending a packet of len 207 to (10.200.6.255) on port 138
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:399(announce_myself_to_domain_master_browser)
>+  announce_myself_to_domain_master_browser: I am a local master browser for workgroup S4LISH on subnet 10.200.6.100
>+[2013/01/24 13:06:50,  9, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_namelistdb.c:141(find_name_on_subnet)
>+  find_name_on_subnet: on subnet REMOTE_BROADCAST_SUBNET - name S4LISH<1b> NOT FOUND
>+[2013/01/24 13:06:50,  9, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_namelistdb.c:141(find_name_on_subnet)
>+  find_name_on_subnet: on subnet UNICAST_SUBNET - name S4LISH<1b> NOT FOUND
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:306(initiate_name_query_packet)
>+  initiate_name_query_packet: sending query for name S4LISH<1b> (bcast=No) to IP 10.200.6.100
>+[2013/01/24 13:06:50,  5, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:173(send_netbios_packet)
>+  send_netbios_packet: sending packet to ourselves.
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_responserecordsdb.c:38(add_response_record)
>+  add_response_record: adding response record id:17917 to subnet UNICAST_SUBNET. num_records:1
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet)
>+  find_workgroup_on_subnet: workgroup search for S4LISH on subnet UNICAST_SUBNET: found.
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet)
>+  find_workgroup_on_subnet: workgroup search for S4LISH on subnet UNICAST_SUBNET: found.
>+[2013/01/24 13:06:50, 10, pid=19131, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket)
>+  read_udp_v4_socket: ip 10.200.6.101 port 35328 read: 235
>+[2013/01/24 13:06:50,  5, pid=19131, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:835(read_packet)
>+  Received a packet of len 235 from (10.200.6.101) port 138
>+[2013/01/24 13:06:50, 10, pid=19131, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket)
>+  read_udp_v4_socket: ip 10.200.6.101 port 35328 read: 235
>+[2013/01/24 13:06:50,  5, pid=19131, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:835(read_packet)
>+  Received a packet of len 235 from (10.200.6.101) port 138
>+[2013/01/24 13:06:50,  7, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:2024(listen_for_packets)
>+  discarding duplicate packet from 10.200.6.101:138
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:107(debug_nmb_packet)
>+  nmb packet from 10.200.6.100(137) header: id=17917 opcode=Query(0) response=No
>+      header: flags: bcast=No rec_avail=No rec_des=Yes trunc=No auth=No
>+      header: rcode=0 qdcount=1 ancount=0 nscount=0 arcount=0
>+      question: q_name=S4LISH<1b> q_type=32 q_class=1
>+[2013/01/24 13:06:50,  3, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_winsserver.c:2033(wins_process_name_query_request)
>+  wins_process_name_query: name query for name S4LISH<1b> from IP 10.200.6.100
>+[2013/01/24 13:06:50,  3, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_winsserver.c:2085(wins_process_name_query_request)
>+  wins_process_name_query: name query for name S4LISH<1b> returning first IP 10.200.6.100.
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:969(reply_netbios_packet)
>+  reply_netbios_packet: sending a reply of packet type: wins_query S4LISH<1b> to ip 10.200.6.100 for id 17917
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:107(debug_nmb_packet)
>+  nmb packet from 10.200.6.100(137) header: id=17917 opcode=Query(0) response=Yes
>+      header: flags: bcast=No rec_avail=Yes rec_des=Yes trunc=No auth=Yes
>+      header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0
>+      answers: nmb_name=S4LISH<1b> rr_type=32 rr_class=1 ttl=178839
>+      answers   0 char `....d   hex 60000AC80664
>+[2013/01/24 13:06:50,  5, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:1020(reply_netbios_packet)
>+  reply_netbios_packet: sending packet to ourselves.
>+[2013/01/24 13:06:50,  9, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_namelistdb.c:135(find_name_on_subnet)
>+  find_name_on_subnet: on subnet 10.200.6.100 - found name S4LISH<1d> source=2
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:1295(process_dgram)
>+  process_dgram: datagram from MEMBER<00> to S4LISH<1d> IP 10.200.6.101 for \MAILSLOT\BROWSE of type 1 len=67
>+[2013/01/24 13:06:50,  8, pid=19131, effective(0, 0), real(0, 0)] ../source3/lib/util.c:1216(is_myname)
>+  is_myname("MEMBER") returns 0
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:116(debug_browse_data)
>+  debug_browse_data():
>+    0 char .+....MEMBER.... hex 01 2b 80 a9 03 00 4d 45 4d 42 45 52 00 00 00 00
>+   10 char ........#.....U. hex 00 00 00 00 00 00 04 09 23 9b 00 00 0f 01 55 aa
>+   20 char member univentio hex 6d 65 6d 62 65 72 20 75 6e 69 76 65 6e 74 69 6f
>+   30 char n corporate serv hex 6e 20 63 6f 72 70 6f 72 61 74 65 20 73 65 72 76
>+   40 char er.              hex 65 72 00
>+[2013/01/24 13:06:50,  3, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_incomingdgrams.c:119(process_host_announce)
>+  process_host_announce: from MEMBER<00> IP 10.200.6.101 to S4LISH<1d> for server MEMBER.
>+[2013/01/24 13:06:50,  5, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_incomingdgrams.c:122(process_host_announce)
>+  process_host_announce: ttl=240 server type=00009b23 comment=member univention corporate server
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet)
>+  find_workgroup_on_subnet: workgroup search for S4LISH on subnet 10.200.6.100: found.
>+[2013/01/24 13:06:50,  8, pid=19131, effective(0, 0), real(0, 0)] ../source3/lib/util.c:1216(is_myname)
>+  is_myname("MEMBER") returns 0
>+[2013/01/24 13:06:50,  3, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_serverlistdb.c:134(create_server_on_workgroup)
>+  create_server_on_workgroup: Created server entry MEMBER of type 40009b23 (member univention corporate server) on workgroup S4LISH.
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:107(debug_nmb_packet)
>+  nmb packet from 10.200.6.100(137) header: id=17917 opcode=Query(0) response=Yes
>+      header: flags: bcast=No rec_avail=Yes rec_des=Yes trunc=No auth=Yes
>+      header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0
>+      answers: nmb_name=S4LISH<1b> rr_type=32 rr_class=1 ttl=178839
>+      answers   0 char `....d   hex 60000AC80664
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_responserecordsdb.c:179(find_response_record_on_subnet)
>+  find_response_record: found response record id = 17917 on subnet UNICAST_SUBNET
>+[2013/01/24 13:06:50,  5, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_namequery.c:95(query_name_response)
>+  query_name_response: On subnet UNICAST_SUBNET - positive response from IP 10.200.6.100 for name S4LISH<1b>.  IP of that name is 10.200.6.100
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet)
>+  find_workgroup_on_subnet: workgroup search for S4LISH on subnet UNICAST_SUBNET: found.
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:450(initiate_node_status_packet)
>+  initiate_node_status_packet: sending node status request for name S4LISH<1b> to IP 10.200.6.100
>+[2013/01/24 13:06:50,  5, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:173(send_netbios_packet)
>+  send_netbios_packet: sending packet to ourselves.
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_responserecordsdb.c:38(add_response_record)
>+  add_response_record: adding response record id:17918 to subnet UNICAST_SUBNET. num_records:2
>+[2013/01/24 13:06:50,  4, pid=19131, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:107(debug_nmb_packet)
>+  nmb packet from 10.200.6.100(137) header: id=17918 opcode=Query(0) response=No
>+      header: flags: bcast=No rec_avail=No rec_des=No trunc=No auth=No
>+      header: rcode=0 qdcount=1 ancount=0 nscount=0 arcount=0
>+      question: q_name=S4LISH<1b> q_type=33 q_class=1
>+[2013/01/24 13:06:50,  3, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_incomingrequests.c:323(process_node_status_request)
>+  process_node_status_request: status request for name S4LISH<1b> from IP 10.200.6.100 on subnet UNICAST_SUBNET.
>+[2013/01/24 13:06:50,  9, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_namelistdb.c:141(find_name_on_subnet)
>+  find_name_on_subnet: on subnet UNICAST_SUBNET - name S4LISH<1b> NOT FOUND
>+[2013/01/24 13:06:50,  1, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_incomingrequests.c:328(process_node_status_request)
>+  process_node_status_request: status request for name S4LISH<1b> from IP 10.200.6.100 on subnet UNICAST_SUBNET - name not found.
>
>[...]
>
>+[2013/01/24 13:07:11,  5, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_nodestatus.c:68(node_status_timeout_response)
>+  node_status_timeout_response: failed to get node status from name S4LISH<1b> on subnet UNICAST_SUBNET
>+[2013/01/24 13:07:11,  0, pid=19131, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_browsesync.c:251(domain_master_node_status_fail)
>+  domain_master_node_status_fail:
>+  Doing a node status request to the domain master browser
>+  for workgroup S4LISH at IP 10.200.6.100 failed.
>+  Cannot sync browser lists.
>

Actions: View

Attachments on bug 30132: 5004 | 5009