diff -Nuar samba.0/log.nmbd samba.1/log.nmbd --- samba.0/log.nmbd 2013-11-05 16:52:48.804000000 +0100 +++ samba.1/log.nmbd 2013-11-05 16:52:53.188000000 +0100 @@ -2412,3 +2412,267 @@ find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. [2013/11/05 16:52:46, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:49, 6, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/unexpected.c:147(nb_packet_server_listener) + accepted socket 18 +[2013/11/05 16:52:49, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:49, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:49, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:49, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:49, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:276(dump_workgroups) + dump_workgroups() + dump workgroup on subnet 10.200.8.42: netmask= 255.255.255.0: + AR32I8(1) current master browser = UNKNOWN + SLAVE42 40019b3b (slave42 univention corporate server) +[2013/11/05 16:52:49, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket) + read_udp_v4_socket: ip 10.200.8.42 port 5260 read: 50 +[2013/11/05 16:52:49, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:550(parse_nmb) + parse_nmb: packet id = 14374 +[2013/11/05 16:52:49, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:836(read_packet) + Received a packet of len 50 from (10.200.8.42) port 35860 +[2013/11/05 16:52:49, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket) + read_udp_v4_socket: ip 10.200.8.42 port 5260 read: 50 +[2013/11/05 16:52:49, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:550(parse_nmb) + parse_nmb: packet id = 14374 +[2013/11/05 16:52:49, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:836(read_packet) + Received a packet of len 50 from (10.200.8.42) port 35860 +[2013/11/05 16:52:49, 7, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:2024(listen_for_packets) + discarding duplicate packet from 10.200.8.42:35860 +[2013/11/05 16:52:49, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:108(debug_nmb_packet) + nmb packet from 10.200.8.42(35860) header: id=14374 opcode=Query(0) response=No + header: flags: bcast=Yes rec_avail=No rec_des=Yes trunc=No auth=No + header: rcode=0 qdcount=1 ancount=0 nscount=0 arcount=0 + question: q_name=ARW2008R2.QA<1c> q_type=32 q_class=1 +[2013/11/05 16:52:49, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_winsserver.c:524(packet_is_for_wins_server) + packet_is_for_wins_server: failing WINS test #1. +[2013/11/05 16:52:49, 3, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_incomingrequests.c:459(process_name_query_request) + process_name_query_request: Name query from 10.200.8.42 on subnet 10.200.8.42 for name ARW2008R2.QA<1c> +[2013/11/05 16:52:49, 9, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_namelistdb.c:141(find_name_on_subnet) + find_name_on_subnet: on subnet 10.200.8.42 - name ARW2008R2.QA<1c> NOT FOUND +[2013/11/05 16:52:49, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:49, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:50, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:50, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:50, 6, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/unexpected.c:147(nb_packet_server_listener) + accepted socket 18 +[2013/11/05 16:52:50, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:50, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:50, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:50, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:50, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket) + read_udp_v4_socket: ip 10.200.8.42 port 30361 read: 50 +[2013/11/05 16:52:50, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:550(parse_nmb) + parse_nmb: packet id = 22909 +[2013/11/05 16:52:50, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:836(read_packet) + Received a packet of len 50 from (10.200.8.42) port 39286 +[2013/11/05 16:52:50, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket) + read_udp_v4_socket: ip 10.200.8.42 port 30361 read: 50 +[2013/11/05 16:52:50, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:550(parse_nmb) + parse_nmb: packet id = 22909 +[2013/11/05 16:52:50, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:836(read_packet) + Received a packet of len 50 from (10.200.8.42) port 39286 +[2013/11/05 16:52:50, 7, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:2024(listen_for_packets) + discarding duplicate packet from 10.200.8.42:39286 +[2013/11/05 16:52:50, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:108(debug_nmb_packet) + nmb packet from 10.200.8.42(39286) header: id=22909 opcode=Query(0) response=No + header: flags: bcast=Yes rec_avail=No rec_des=Yes trunc=No auth=No + header: rcode=0 qdcount=1 ancount=0 nscount=0 arcount=0 + question: q_name=ARW2008R2.QA<1c> q_type=32 q_class=1 +[2013/11/05 16:52:50, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_winsserver.c:524(packet_is_for_wins_server) + packet_is_for_wins_server: failing WINS test #1. +[2013/11/05 16:52:50, 3, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_incomingrequests.c:459(process_name_query_request) + process_name_query_request: Name query from 10.200.8.42 on subnet 10.200.8.42 for name ARW2008R2.QA<1c> +[2013/11/05 16:52:50, 9, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_namelistdb.c:141(find_name_on_subnet) + find_name_on_subnet: on subnet 10.200.8.42 - name ARW2008R2.QA<1c> NOT FOUND +[2013/11/05 16:52:50, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:50, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:51, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:51, 6, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/unexpected.c:147(nb_packet_server_listener) + accepted socket 18 +[2013/11/05 16:52:51, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:51, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:51, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:51, 6, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/unexpected.c:147(nb_packet_server_listener) + accepted socket 18 +[2013/11/05 16:52:51, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:51, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket) + read_udp_v4_socket: ip 10.200.8.42 port 45245 read: 50 +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:550(parse_nmb) + parse_nmb: packet id = 18994 +[2013/11/05 16:52:51, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:836(read_packet) + Received a packet of len 50 from (10.200.8.42) port 48560 +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket) + read_udp_v4_socket: ip 10.200.8.42 port 45245 read: 50 +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:550(parse_nmb) + parse_nmb: packet id = 18994 +[2013/11/05 16:52:51, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:836(read_packet) + Received a packet of len 50 from (10.200.8.42) port 48560 +[2013/11/05 16:52:51, 7, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:2024(listen_for_packets) + discarding duplicate packet from 10.200.8.42:48560 +[2013/11/05 16:52:51, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:108(debug_nmb_packet) + nmb packet from 10.200.8.42(48560) header: id=18994 opcode=Query(0) response=No + header: flags: bcast=Yes rec_avail=No rec_des=Yes trunc=No auth=No + header: rcode=0 qdcount=1 ancount=0 nscount=0 arcount=0 + question: q_name=ARW2008R2.QA<1c> q_type=32 q_class=1 +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_winsserver.c:524(packet_is_for_wins_server) + packet_is_for_wins_server: failing WINS test #1. +[2013/11/05 16:52:51, 3, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_incomingrequests.c:459(process_name_query_request) + process_name_query_request: Name query from 10.200.8.42 on subnet 10.200.8.42 for name ARW2008R2.QA<1c> +[2013/11/05 16:52:51, 9, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_namelistdb.c:141(find_name_on_subnet) + find_name_on_subnet: on subnet 10.200.8.42 - name ARW2008R2.QA<1c> NOT FOUND +[2013/11/05 16:52:51, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket) + read_udp_v4_socket: ip 10.200.8.40 port 35328 read: 237 +[2013/11/05 16:52:51, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:836(read_packet) + Received a packet of len 237 from (10.200.8.40) port 138 +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket) + read_udp_v4_socket: ip 10.200.8.40 port 35328 read: 237 +[2013/11/05 16:52:51, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:836(read_packet) + Received a packet of len 237 from (10.200.8.40) port 138 +[2013/11/05 16:52:51, 7, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:2024(listen_for_packets) + discarding duplicate packet from 10.200.8.40:138 +[2013/11/05 16:52:51, 9, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_namelistdb.c:135(find_name_on_subnet) + find_name_on_subnet: on subnet 10.200.8.42 - found name AR32I8<1e> source=2 +[2013/11/05 16:52:51, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:1295(process_dgram) + process_dgram: datagram from MASTER40<00> to AR32I8<1e> IP 10.200.8.40 for \MAILSLOT\BROWSE of type 15 len=69 +[2013/11/05 16:52:51, 8, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util.c:1191(is_myname) + is_myname("MASTER40") returns 0 +[2013/11/05 16:52:51, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:116(debug_browse_data) + debug_browse_data(): + 0 char ......MASTER40.. hex 0f 80 80 fc 0a 00 4d 41 53 54 45 52 34 30 00 00 + 10 char ........+.....U. hex 00 00 00 00 00 00 04 09 2b 9b 0c 00 0f 01 55 aa + 20 char master40 univent hex 6d 61 73 74 65 72 34 30 20 75 6e 69 76 65 6e 74 + 30 char ion corporate se hex 69 6f 6e 20 63 6f 72 70 6f 72 61 74 65 20 73 65 + 40 char rver. hex 72 76 65 72 00 +[2013/11/05 16:52:51, 3, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_incomingdgrams.c:276(process_local_master_announce) + process_local_master_announce: from MASTER40<00> IP 10.200.8.40 to AR32I8<1e> for server MASTER40. +[2013/11/05 16:52:51, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_incomingdgrams.c:279(process_local_master_announce) + process_local_master_announce: ttl=720 server type=000c9b2b comment=master40 univention corporate server +[2013/11/05 16:52:51, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:51, 8, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util.c:1191(is_myname) + is_myname("MASTER40") returns 0 +[2013/11/05 16:52:51, 3, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_serverlistdb.c:134(create_server_on_workgroup) + create_server_on_workgroup: Created server entry MASTER40 of type 400c9b2b (master40 univention corporate server) on workgroup AR32I8. +[2013/11/05 16:52:51, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_become_lmb.c:578(set_workgroup_local_master_browser_name) + set_workgroup_local_master_browser_name: setting local master name to 'MASTER40' for workgroup AR32I8. +[2013/11/05 16:52:51, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket) + read_udp_v4_socket: ip 10.200.8.40 port 35328 read: 209 +[2013/11/05 16:52:51, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:836(read_packet) + Received a packet of len 209 from (10.200.8.40) port 138 +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket) + read_udp_v4_socket: ip 10.200.8.40 port 35328 read: 209 +[2013/11/05 16:52:51, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:836(read_packet) + Received a packet of len 209 from (10.200.8.40) port 138 +[2013/11/05 16:52:51, 7, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:2024(listen_for_packets) + discarding duplicate packet from 10.200.8.40:138 +[2013/11/05 16:52:51, 9, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_namelistdb.c:141(find_name_on_subnet) + find_name_on_subnet: on subnet 10.200.8.42 - name __MSBROWSE__<01> NOT FOUND +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/unexpected.c:327(nb_packet_dispatch) + client expects packet 0, got 1 +[2013/11/05 16:52:51, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:1230(process_dgram) + process_dgram: ignoring dgram packet sent to name __MSBROWSE__<01> from 10.200.8.40 +[2013/11/05 16:52:51, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:51, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:52, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:52, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:52, 6, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/unexpected.c:147(nb_packet_server_listener) + accepted socket 18 +[2013/11/05 16:52:52, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:52, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:52, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:52, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:52, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket) + read_udp_v4_socket: ip 10.200.8.42 port 50371 read: 50 +[2013/11/05 16:52:52, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:550(parse_nmb) + parse_nmb: packet id = 22719 +[2013/11/05 16:52:52, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:836(read_packet) + Received a packet of len 50 from (10.200.8.42) port 50116 +[2013/11/05 16:52:52, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:99(read_udp_v4_socket) + read_udp_v4_socket: ip 10.200.8.42 port 50371 read: 50 +[2013/11/05 16:52:52, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:550(parse_nmb) + parse_nmb: packet id = 22719 +[2013/11/05 16:52:52, 5, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:836(read_packet) + Received a packet of len 50 from (10.200.8.42) port 50116 +[2013/11/05 16:52:52, 7, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_packets.c:2024(listen_for_packets) + discarding duplicate packet from 10.200.8.42:50116 +[2013/11/05 16:52:52, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:108(debug_nmb_packet) + nmb packet from 10.200.8.42(50116) header: id=22719 opcode=Query(0) response=No + header: flags: bcast=Yes rec_avail=No rec_des=Yes trunc=No auth=No + header: rcode=0 qdcount=1 ancount=0 nscount=0 arcount=0 + question: q_name=ARW2008R2.QA<1c> q_type=32 q_class=1 +[2013/11/05 16:52:52, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_winsserver.c:524(packet_is_for_wins_server) + packet_is_for_wins_server: failing WINS test #1. +[2013/11/05 16:52:52, 3, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_incomingrequests.c:459(process_name_query_request) + process_name_query_request: Name query from 10.200.8.42 on subnet 10.200.8.42 for name ARW2008R2.QA<1c> +[2013/11/05 16:52:52, 9, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_namelistdb.c:141(find_name_on_subnet) + find_name_on_subnet: on subnet 10.200.8.42 - name ARW2008R2.QA<1c> NOT FOUND +[2013/11/05 16:52:52, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:52, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:53, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:53, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:53, 6, pid=7216, effective(0, 0), real(0, 0)] ../source3/libsmb/unexpected.c:147(nb_packet_server_listener) + accepted socket 18 +[2013/11/05 16:52:53, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:53, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:53, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:53, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. +[2013/11/05 16:52:53, 4, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:165(find_workgroup_on_subnet) + find_workgroup_on_subnet: workgroup search for AR32I8 on subnet 10.200.8.42: found. +[2013/11/05 16:52:53, 10, pid=7216, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_sendannounce.c:376(announce_myself_to_domain_master_browser) + announce_myself_to_domain_master_browser: no unicast subnet, ignoring. diff -Nuar samba.0/log.wb-ARW2008R2 samba.1/log.wb-ARW2008R2 --- samba.0/log.wb-ARW2008R2 2013-11-05 16:52:48.804000000 +0100 +++ samba.1/log.wb-ARW2008R2 2013-11-05 16:52:53.188000000 +0100 @@ -1163,3 +1163,1603 @@ ldb: 10 [2013/11/05 16:52:38.693771, 0, pid=7237, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler) Got sig[15] terminate (is_parent=0) +[2013/11/05 16:52:48.822553, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:340(messaging_deregister) + Deregistering messaging pointer for type 33 - private_data=(nil) +[2013/11/05 16:52:48.822638, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:340(messaging_deregister) + Deregistering messaging pointer for type 13 - private_data=(nil) +[2013/11/05 16:52:48.822681, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:340(messaging_deregister) + Deregistering messaging pointer for type 1028 - private_data=(nil) +[2013/11/05 16:52:48.822723, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:340(messaging_deregister) + Deregistering messaging pointer for type 1027 - private_data=(nil) +[2013/11/05 16:52:48.822779, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:340(messaging_deregister) + Deregistering messaging pointer for type 1029 - private_data=(nil) +[2013/11/05 16:52:48.822820, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:340(messaging_deregister) + Deregistering messaging pointer for type 1280 - private_data=(nil) +[2013/11/05 16:52:48.822860, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:340(messaging_deregister) + Deregistering messaging pointer for type 1033 - private_data=(nil) +[2013/11/05 16:52:48.822900, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:340(messaging_deregister) + Deregistering messaging pointer for type 1 - private_data=(nil) +[2013/11/05 16:52:48.823138, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) + Registering messaging pointer for type 1028 - private_data=(nil) +[2013/11/05 16:52:48.823200, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) + Registering messaging pointer for type 1027 - private_data=(nil) +[2013/11/05 16:52:48.823241, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) + Registering messaging pointer for type 1280 - private_data=(nil) +[2013/11/05 16:52:48.823282, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) + Registering messaging pointer for type 1 - private_data=(nil) +[2013/11/05 16:52:48.823322, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) + Registering messaging pointer for type 1034 - private_data=(nil) +[2013/11/05 16:52:48.823362, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:308(messaging_register) + Overriding messaging pointer for type 1034 - private_data=(nil) +[2013/11/05 16:52:48.823462, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:499(set_domain_online_request) + set_domain_online_request: called for domain ARW2008R2 +[2013/11/05 16:52:48.823526, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:534(set_domain_online_request) + set_domain_online_request: domain ARW2008R2 was globally offline. +[2013/11/05 16:52:48.823570, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:499(set_domain_online_request) + set_domain_online_request: called for domain AR32I8 +[2013/11/05 16:52:48.823608, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:509(set_domain_online_request) + set_domain_online_request: Internal domains are always online +[2013/11/05 16:52:48.823690, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) + Need to read 56 extra bytes +[2013/11/05 16:52:48.823736, 4, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1333(child_handler) + child daemon request 59 +[2013/11/05 16:52:48.823776, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) + child_process_request: request fn NDRCMD +[2013/11/05 16:52:48.823820, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) + winbindd_dual_ndrcmd: Running command WBINT_LOOKUPNAME (ARW2008R2) +[2013/11/05 16:52:48.824029, 1, pid=7317, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) + wbint_LookupName: struct wbint_LookupName + in: struct wbint_LookupName + domain : * + domain : 'ARW2008R2' + name : * + name : 'ADMINISTRATOR' + flags : 0x00000000 (0) +[2013/11/05 16:52:48.824226, 3, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1796(connection_ok) + connection_ok: Connection to (null) for domain ARW2008R2 is not connected +[2013/11/05 16:52:48.824406, 5, pid=7317, effective(0, 0), real(0, 0), class=tdb] ../source3/lib/gencache.c:67(gencache_init) + Opening cache file at /var/cache/samba/gencache.tdb +[2013/11/05 16:52:48.824588, 5, pid=7317, effective(0, 0), real(0, 0), class=tdb] ../source3/lib/gencache.c:110(gencache_init) + Opening cache file at /var/run/samba/gencache_notrans.tdb +[2013/11/05 16:52:48.824721, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:211(saf_fetch) + saf_fetch: Returning "WIN-125IN6TLA89" for "ARW2008R2" domain +[2013/11/05 16:52:48.824803, 9, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/conncache.c:150(check_negative_conn_cache) + check_negative_conn_cache returning result 0 for domain ARW2008R2 server WIN-125IN6TLA89 +[2013/11/05 16:52:48.824847, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1571(cm_open_connection) + cm_open_connection: saf_servername is 'WIN-125IN6TLA89' for domain ARW2008R2 +[2013/11/05 16:52:48.824917, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1613(cm_open_connection) + cm_open_connection: dcname is 'WIN-125IN6TLA89' for domain ARW2008R2 +[2013/11/05 16:52:48.824967, 9, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/conncache.c:150(check_negative_conn_cache) + check_negative_conn_cache returning result 0 for domain ARW2008R2 server WIN-125IN6TLA89 +[2013/11/05 16:52:48.825080, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/sitename_cache.c:102(sitename_fetch) + sitename_fetch: No stored sitename for +[2013/11/05 16:52:48.825147, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up WIN-125IN6TLA89#20 (sitename (null)) +[2013/11/05 16:52:48.825214, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:165(namecache_fetch) + name WIN-125IN6TLA89#20 found. +[2013/11/05 16:52:48.825405, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:1110(remove_duplicate_addrs2) + remove_duplicate_addrs2: looking for duplicate address/port pairs +[2013/11/05 16:52:48.825586, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:585(open_socket_out_send) + Connecting to 10.200.8.135 at port 445 +[2013/11/05 16:52:48.826881, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:816(cm_prepare_connection) + cm_prepare_connection: connecting to DC WIN-125IN6TLA89 for domain ARW2008R2 +[2013/11/05 16:52:48.829253, 3, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:594(cm_get_ipc_userpass) + cm_get_ipc_userpass: Retrieved auth-user from secrets.tdb [AR32I8\Administrator] +[2013/11/05 16:52:48.829341, 5, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:952(cm_prepare_connection) + connecting to WIN-125IN6TLA89 from SLAVE42 with username [AR32I8]\[Administrator] +[2013/11/05 16:52:48.829434, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/cliconnect.c:1781(cli_session_setup_spnego_send) + Doing spnego session setup (blob length=120) +[2013/11/05 16:52:48.829708, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/cliconnect.c:1808(cli_session_setup_spnego_send) + got OID=1.3.6.1.4.1.311.2.2.30 + got OID=1.2.840.48018.1.2.2 + got OID=1.2.840.113554.1.2.2 + got OID=1.2.840.113554.1.2.2.3 + got OID=1.3.6.1.4.1.311.2.2.10 +[2013/11/05 16:52:48.829818, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/cliconnect.c:1818(cli_session_setup_spnego_send) + got principal=not_defined_in_RFC4178@please_ignore +[2013/11/05 16:52:48.830948, 1, pid=7317, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) + negotiate: struct NEGOTIATE_MESSAGE + Signature : 'NTLMSSP' + MessageType : NtLmNegotiate (1) + NegotiateFlags : 0x60088215 (1611170325) + 1: NTLMSSP_NEGOTIATE_UNICODE + 0: NTLMSSP_NEGOTIATE_OEM + 1: NTLMSSP_REQUEST_TARGET + 1: NTLMSSP_NEGOTIATE_SIGN + 0: NTLMSSP_NEGOTIATE_SEAL + 0: NTLMSSP_NEGOTIATE_DATAGRAM + 0: NTLMSSP_NEGOTIATE_LM_KEY + 0: NTLMSSP_NEGOTIATE_NETWARE + 1: NTLMSSP_NEGOTIATE_NTLM + 0: NTLMSSP_NEGOTIATE_NT_ONLY + 0: NTLMSSP_ANONYMOUS + 0: NTLMSSP_NEGOTIATE_OEM_DOMAIN_SUPPLIED + 0: NTLMSSP_NEGOTIATE_OEM_WORKSTATION_SUPPLIED + 0: NTLMSSP_NEGOTIATE_THIS_IS_LOCAL_CALL + 1: NTLMSSP_NEGOTIATE_ALWAYS_SIGN + 0: NTLMSSP_TARGET_TYPE_DOMAIN + 0: NTLMSSP_TARGET_TYPE_SERVER + 0: NTLMSSP_TARGET_TYPE_SHARE + 1: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY + 0: NTLMSSP_NEGOTIATE_IDENTIFY + 0: NTLMSSP_REQUEST_NON_NT_SESSION_KEY + 0: NTLMSSP_NEGOTIATE_TARGET_INFO + 0: NTLMSSP_NEGOTIATE_VERSION + 1: NTLMSSP_NEGOTIATE_128 + 1: NTLMSSP_NEGOTIATE_KEY_EXCH + 0: NTLMSSP_NEGOTIATE_56 + DomainNameLen : 0x0006 (6) + DomainNameMaxLen : 0x0006 (6) + DomainName : * + DomainName : 'AR32I8' + WorkstationLen : 0x0007 (7) + WorkstationMaxLen : 0x0007 (7) + Workstation : * + Workstation : 'SLAVE42' +[2013/11/05 16:52:48.831843, 10, pid=7317, effective(0, 0), real(0, 0)] ../libcli/smb/smb_signing.c:271(smb_signing_sign_pdu) + smb_signing_sign_pdu: sent SMB signature of +[2013/11/05 16:52:48.831950, 10, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/util.c:512(dump_data) + [0000] 42 53 52 53 50 59 4C 20 BSRSPYL +[2013/11/05 16:52:48.833292, 1, pid=7317, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) + challenge: struct CHALLENGE_MESSAGE + Signature : 'NTLMSSP' + MessageType : NtLmChallenge (0x2) + TargetNameLen : 0x0012 (18) + TargetNameMaxLen : 0x0012 (18) + TargetName : * + TargetName : 'ARW2008R2' + NegotiateFlags : 0x62898215 (1653178901) + 1: NTLMSSP_NEGOTIATE_UNICODE + 0: NTLMSSP_NEGOTIATE_OEM + 1: NTLMSSP_REQUEST_TARGET + 1: NTLMSSP_NEGOTIATE_SIGN + 0: NTLMSSP_NEGOTIATE_SEAL + 0: NTLMSSP_NEGOTIATE_DATAGRAM + 0: NTLMSSP_NEGOTIATE_LM_KEY + 0: NTLMSSP_NEGOTIATE_NETWARE + 1: NTLMSSP_NEGOTIATE_NTLM + 0: NTLMSSP_NEGOTIATE_NT_ONLY + 0: NTLMSSP_ANONYMOUS + 0: NTLMSSP_NEGOTIATE_OEM_DOMAIN_SUPPLIED + 0: NTLMSSP_NEGOTIATE_OEM_WORKSTATION_SUPPLIED + 0: NTLMSSP_NEGOTIATE_THIS_IS_LOCAL_CALL + 1: NTLMSSP_NEGOTIATE_ALWAYS_SIGN + 1: NTLMSSP_TARGET_TYPE_DOMAIN + 0: NTLMSSP_TARGET_TYPE_SERVER + 0: NTLMSSP_TARGET_TYPE_SHARE + 1: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY + 0: NTLMSSP_NEGOTIATE_IDENTIFY + 0: NTLMSSP_REQUEST_NON_NT_SESSION_KEY + 1: NTLMSSP_NEGOTIATE_TARGET_INFO + 1: NTLMSSP_NEGOTIATE_VERSION + 1: NTLMSSP_NEGOTIATE_128 + 1: NTLMSSP_NEGOTIATE_KEY_EXCH + 0: NTLMSSP_NEGOTIATE_56 + ServerChallenge : 362a3566d47f2cf3 + Reserved : 0000000000000000 + TargetInfoLen : 0x00bc (188) + TargetNameInfoMaxLen : 0x00bc (188) + TargetInfo : * + TargetInfo: struct AV_PAIR_LIST + count : 0x00000007 (7) + pair: ARRAY(7) + pair: struct AV_PAIR + AvId : MsvAvNbDomainName (0x2) + AvLen : 0x0012 (18) + Value : union ntlmssp_AvValue(case 0x2) + AvNbDomainName : 'ARW2008R2' + pair: struct AV_PAIR + AvId : MsvAvNbComputerName (0x1) + AvLen : 0x001e (30) + Value : union ntlmssp_AvValue(case 0x1) + AvNbComputerName : 'WIN-125IN6TLA89' + pair: struct AV_PAIR + AvId : MsvAvDnsDomainName (0x4) + AvLen : 0x0018 (24) + Value : union ntlmssp_AvValue(case 0x4) + AvDnsDomainName : 'arw2008r2.qa' + pair: struct AV_PAIR + AvId : MsvAvDnsComputerName (0x3) + AvLen : 0x0038 (56) + Value : union ntlmssp_AvValue(case 0x3) + AvDnsComputerName : 'WIN-125IN6TLA89.arw2008r2.qa' + pair: struct AV_PAIR + AvId : MsvAvDnsTreeName (0x5) + AvLen : 0x0018 (24) + Value : union ntlmssp_AvValue(case 0x5) + AvDnsTreeName : 'arw2008r2.qa' + pair: struct AV_PAIR + AvId : MsvAvTimestamp (0x7) + AvLen : 0x0008 (8) + Value : union ntlmssp_AvValue(case 0x7) + AvTimestamp : Di Nov 5 16:53:04 2013 CET + pair: struct AV_PAIR + AvId : MsvAvEOL (0x0) + AvLen : 0x0000 (0) + Value : union ntlmssp_AvValue(case 0x0) + Version: struct ntlmssp_VERSION + ProductMajorVersion : NTLMSSP_WINDOWS_MAJOR_VERSION_6 (0x6) + ProductMinorVersion : NTLMSSP_WINDOWS_MINOR_VERSION_1 (0x1) + ProductBuild : 0x1db1 (7601) + Reserved : 000000 + NTLMRevisionCurrent : NTLMSSP_REVISION_W2K3 (0xF) +[2013/11/05 16:52:48.834843, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/ntlmssp.c:455(ntlmssp3_client_challenge) + Got challenge flags: +[2013/11/05 16:52:48.834897, 3, pid=7317, effective(0, 0), real(0, 0)] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags) + Got NTLMSSP neg_flags=0x62898215 + NTLMSSP_NEGOTIATE_UNICODE + NTLMSSP_REQUEST_TARGET + NTLMSSP_NEGOTIATE_SIGN + NTLMSSP_NEGOTIATE_NTLM + NTLMSSP_NEGOTIATE_ALWAYS_SIGN + NTLMSSP_NEGOTIATE_NTLM2 + NTLMSSP_NEGOTIATE_TARGET_INFO + NTLMSSP_NEGOTIATE_VERSION + NTLMSSP_NEGOTIATE_128 + NTLMSSP_NEGOTIATE_KEY_EXCH +[2013/11/05 16:52:48.835137, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/ntlmssp.c:477(ntlmssp3_client_challenge) + NTLMSSP: Set final flags: +[2013/11/05 16:52:48.835178, 3, pid=7317, effective(0, 0), real(0, 0)] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags) + Got NTLMSSP neg_flags=0x60088215 + NTLMSSP_NEGOTIATE_UNICODE + NTLMSSP_REQUEST_TARGET + NTLMSSP_NEGOTIATE_SIGN + NTLMSSP_NEGOTIATE_NTLM + NTLMSSP_NEGOTIATE_ALWAYS_SIGN + NTLMSSP_NEGOTIATE_NTLM2 + NTLMSSP_NEGOTIATE_128 + NTLMSSP_NEGOTIATE_KEY_EXCH +[2013/11/05 16:52:48.835508, 1, pid=7317, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) + authenticate: struct AUTHENTICATE_MESSAGE + Signature : 'NTLMSSP' + MessageType : NtLmAuthenticate (3) + LmChallengeResponseLen : 0x0018 (24) + LmChallengeResponseMaxLen: 0x0018 (24) + LmChallengeResponse : * + LmChallengeResponse : union ntlmssp_LM_RESPONSE(case 24) + v1: struct LM_RESPONSE + Response : 586710214d925aeeed1afbea5036f3235f15602eb8dd3e19 + NtChallengeResponseLen : 0x00e8 (232) + NtChallengeResponseMaxLen: 0x00e8 (232) + NtChallengeResponse : * + NtChallengeResponse : union ntlmssp_NTLM_RESPONSE(case 232) + v2: struct NTLMv2_RESPONSE + Response : e7645eeb786488fb9a3c38761e488fc2 + Challenge: struct NTLMv2_CLIENT_CHALLENGE + RespType : 0x01 (1) + HiRespType : 0x01 (1) + Reserved1 : 0x0000 (0) + Reserved2 : 0x00000000 (0) + TimeStamp : Di Nov 5 16:52:48 2013 CET + ChallengeFromClient : 490962de2f40cf1f + Reserved3 : 0x00000000 (0) + AvPairs: struct AV_PAIR_LIST + count : 0x00000007 (7) + pair: ARRAY(7) + pair: struct AV_PAIR + AvId : MsvAvNbDomainName (0x2) + AvLen : 0x0012 (18) + Value : union ntlmssp_AvValue(case 0x2) + AvNbDomainName : 'ARW2008R2' + pair: struct AV_PAIR + AvId : MsvAvNbComputerName (0x1) + AvLen : 0x001e (30) + Value : union ntlmssp_AvValue(case 0x1) + AvNbComputerName : 'WIN-125IN6TLA89' + pair: struct AV_PAIR + AvId : MsvAvDnsDomainName (0x4) + AvLen : 0x0018 (24) + Value : union ntlmssp_AvValue(case 0x4) + AvDnsDomainName : 'arw2008r2.qa' + pair: struct AV_PAIR + AvId : MsvAvDnsComputerName (0x3) + AvLen : 0x0038 (56) + Value : union ntlmssp_AvValue(case 0x3) + AvDnsComputerName : 'WIN-125IN6TLA89.arw2008r2.qa' + pair: struct AV_PAIR + AvId : MsvAvDnsTreeName (0x5) + AvLen : 0x0018 (24) + Value : union ntlmssp_AvValue(case 0x5) + AvDnsTreeName : 'arw2008r2.qa' + pair: struct AV_PAIR + AvId : MsvAvTimestamp (0x7) + AvLen : 0x0008 (8) + Value : union ntlmssp_AvValue(case 0x7) + AvTimestamp : Di Nov 5 16:53:04 2013 CET + pair: struct AV_PAIR + AvId : MsvAvEOL (0x0) + AvLen : 0x0000 (0) + Value : union ntlmssp_AvValue(case 0x0) + DomainNameLen : 0x000c (12) + DomainNameMaxLen : 0x000c (12) + DomainName : * + DomainName : 'AR32I8' + UserNameLen : 0x001a (26) + UserNameMaxLen : 0x001a (26) + UserName : * + UserName : 'Administrator' + WorkstationLen : 0x000e (14) + WorkstationMaxLen : 0x000e (14) + Workstation : * + Workstation : 'SLAVE42' + EncryptedRandomSessionKeyLen: 0x0010 (16) + EncryptedRandomSessionKeyMaxLen: 0x0010 (16) + EncryptedRandomSessionKey: * + EncryptedRandomSessionKey: DATA_BLOB length=16 + [0000] F8 6F 0B DC D7 B6 4A 3B AC 72 3C 6E BA A8 77 0C .o....J; .r9... +[2013/11/05 16:52:48.951739, 10, pid=7317, effective(0, 0), real(0, 0)] ../libcli/smb/smb_signing.c:158(smb_signing_md5) + smb_signing_md5: sequence number 17 +[2013/11/05 16:52:48.951955, 10, pid=7317, effective(0, 0), real(0, 0)] ../libcli/smb/smb_signing.c:330(smb_signing_check_pdu) + smb_signing_check_pdu: seq 17: got good SMB signature of +[2013/11/05 16:52:48.952063, 10, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/util.c:512(dump_data) + [0000] 33 75 6C 44 6E 63 90 39 3ulDnc.9 +[2013/11/05 16:52:48.952305, 5, pid=7317, effective(0, 0), real(0, 0), class=rpc_cli] ../source3/rpc_client/cli_pipe.c:100(rpc_read_send) + rpc_read_send: data_to_read: 32 +[2013/11/05 16:52:48.952459, 1, pid=7317, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) + r: struct ncacn_packet + rpc_vers : 0x05 (5) + rpc_vers_minor : 0x00 (0) + ptype : DCERPC_PKT_RESPONSE (2) + pfc_flags : 0x03 (3) + drep: ARRAY(4) + [0] : 0x10 (16) + [1] : 0x00 (0) + [2] : 0x00 (0) + [3] : 0x00 (0) + frag_length : 0x0030 (48) + auth_length : 0x0000 (0) + call_id : 0x00000004 (4) + u : union dcerpc_payload(case 2) + response: struct dcerpc_response + alloc_hint : 0x00000018 (24) + context_id : 0x0000 (0) + cancel_count : 0x00 (0) + _pad : DATA_BLOB length=1 + [0000] 00 . + stub_and_verifier : DATA_BLOB length=24 + [0000] 00 00 00 00 09 84 1D 47 E5 7A 7F 4F B9 8D 1D 1A .......G .z.O.... + [0010] 7C 70 AA 05 00 00 00 00 |p...... +[2013/11/05 16:52:48.953567, 10, pid=7317, effective(0, 0), real(0, 0), class=rpc_cli] ../source3/rpc_client/cli_pipe.c:440(cli_pipe_validate_current_pdu) + Got pdu len 48, data_len 24, ss_len 0 +[2013/11/05 16:52:48.953665, 10, pid=7317, effective(0, 0), real(0, 0), class=rpc_cli] ../source3/rpc_client/cli_pipe.c:885(rpc_api_pipe_got_pdu) + rpc_api_pipe: got frag len of 48 at offset 0: NT_STATUS_OK +[2013/11/05 16:52:48.953847, 10, pid=7317, effective(0, 0), real(0, 0), class=rpc_cli] ../source3/rpc_client/cli_pipe.c:940(rpc_api_pipe_got_pdu) + rpc_api_pipe: host WIN-125IN6TLA89 returned 24 bytes. +[2013/11/05 16:52:48.954008, 1, pid=7317, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) + lsa_OpenPolicy2: struct lsa_OpenPolicy2 + out: struct lsa_OpenPolicy2 + handle : * + handle: struct policy_handle + handle_type : 0x00000000 (0) + uuid : 471d8409-7ae5-4f7f-b98d-1d1a7c70aa05 + result : NT_STATUS_OK +[2013/11/05 16:52:48.954540, 1, pid=7317, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) + lsa_QueryInfoPolicy2: struct lsa_QueryInfoPolicy2 + in: struct lsa_QueryInfoPolicy2 + handle : * + handle: struct policy_handle + handle_type : 0x00000000 (0) + uuid : 471d8409-7ae5-4f7f-b98d-1d1a7c70aa05 + level : LSA_POLICY_INFO_DNS (12) +[2013/11/05 16:52:48.955391, 1, pid=7317, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) + &r: struct ncacn_packet + rpc_vers : 0x05 (5) + rpc_vers_minor : 0x00 (0) + ptype : DCERPC_PKT_REQUEST (0) + pfc_flags : 0x03 (3) + drep: ARRAY(4) + [0] : 0x10 (16) + [1] : 0x00 (0) + [2] : 0x00 (0) + [3] : 0x00 (0) + frag_length : 0x0018 (24) + auth_length : 0x0000 (0) + call_id : 0x00000005 (5) + u : union dcerpc_payload(case 0) + request: struct dcerpc_request + alloc_hint : 0x00000016 (22) + context_id : 0x0000 (0) + opnum : 0x002e (46) + object : union dcerpc_object(case 0) + empty: struct dcerpc_empty + _pad : DATA_BLOB length=0 + stub_and_verifier : DATA_BLOB length=0 +[2013/11/05 16:52:48.956498, 5, pid=7317, effective(0, 0), real(0, 0), class=rpc_cli] ../source3/rpc_client/cli_pipe.c:741(rpc_api_pipe_send) + rpc_api_pipe: host WIN-125IN6TLA89 +[2013/11/05 16:52:48.956642, 10, pid=7317, effective(0, 0), real(0, 0)] ../libcli/smb/smb1cli_trans.c:334(smb1cli_trans_format) + num_setup=2, max_setup=0, param_total=0, this_param=0, max_param=0, data_total=46, this_data=46, max_data=4280, param_offset=84, param_pad=2, param_disp=0, data_offset=84, data_pad=0, data_disp=0 +[2013/11/05 16:52:48.956771, 10, pid=7317, effective(0, 0), real(0, 0)] ../libcli/smb/smb_signing.c:158(smb_signing_md5) + smb_signing_md5: sequence number 18 +[2013/11/05 16:52:48.956874, 10, pid=7317, effective(0, 0), real(0, 0)] ../libcli/smb/smb_signing.c:271(smb_signing_sign_pdu) + smb_signing_sign_pdu: sent SMB signature of +[2013/11/05 16:52:48.956967, 10, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/util.c:512(dump_data) + [0000] 92 12 B0 B6 A6 61 57 0E .....aW. +[2013/11/05 16:52:48.958417, 10, pid=7317, effective(0, 0), real(0, 0)] ../libcli/smb/smb_signing.c:158(smb_signing_md5) + smb_signing_md5: sequence number 19 +[2013/11/05 16:52:48.958625, 10, pid=7317, effective(0, 0), real(0, 0)] ../libcli/smb/smb_signing.c:330(smb_signing_check_pdu) + smb_signing_check_pdu: seq 19: got good SMB signature of +[2013/11/05 16:52:48.958725, 10, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/util.c:512(dump_data) + [0000] 7D 44 98 31 7C 65 A2 86 }D.1|e.. +[2013/11/05 16:52:48.959012, 5, pid=7317, effective(0, 0), real(0, 0), class=rpc_cli] ../source3/rpc_client/cli_pipe.c:100(rpc_read_send) + rpc_read_send: data_to_read: 196 +[2013/11/05 16:52:48.959325, 1, pid=7317, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) + r: struct ncacn_packet + rpc_vers : 0x05 (5) + rpc_vers_minor : 0x00 (0) + ptype : DCERPC_PKT_RESPONSE (2) + pfc_flags : 0x03 (3) + drep: ARRAY(4) + [0] : 0x10 (16) + [1] : 0x00 (0) + [2] : 0x00 (0) + [3] : 0x00 (0) + frag_length : 0x00d4 (212) + auth_length : 0x0000 (0) + call_id : 0x00000005 (5) + u : union dcerpc_payload(case 2) + response: struct dcerpc_response + alloc_hint : 0x000000bc (188) + context_id : 0x0000 (0) + cancel_count : 0x00 (0) + _pad : DATA_BLOB length=1 + [0000] 00 . + stub_and_verifier : DATA_BLOB length=188 + [0000] 00 00 02 00 0C 00 00 00 12 00 14 00 04 00 02 00 ........ ........ + [0010] 18 00 1A 00 08 00 02 00 18 00 1A 00 0C 00 02 00 ........ ........ + [0020] 0E 18 71 40 64 B1 A9 42 B1 32 33 E9 C1 2F A1 BD ..q@d..B .23../.. + [0030] 10 00 02 00 0A 00 00 00 00 00 00 00 09 00 00 00 ........ ........ + [0040] 41 00 52 00 57 00 32 00 30 00 30 00 38 00 52 00 A.R.W.2. 0.0.8.R. + [0050] 32 00 00 00 0D 00 00 00 00 00 00 00 0C 00 00 00 2....... ........ + [0060] 61 00 72 00 77 00 32 00 30 00 30 00 38 00 72 00 a.r.w.2. 0.0.8.r. + [0070] 32 00 2E 00 71 00 61 00 0D 00 00 00 00 00 00 00 2...q.a. ........ + [0080] 0C 00 00 00 61 00 72 00 77 00 32 00 30 00 30 00 ....a.r. w.2.0.0. + [0090] 38 00 72 00 32 00 2E 00 71 00 61 00 04 00 00 00 8.r.2... q.a..... + [00A0] 01 04 00 00 00 00 00 05 15 00 00 00 58 3F 6D B2 ........ ....X?m. + [00B0] 38 1F 4B 87 FA 51 AD 36 00 00 00 00 8.K..Q.6 .... +[2013/11/05 16:52:48.961450, 10, pid=7317, effective(0, 0), real(0, 0), class=rpc_cli] ../source3/rpc_client/cli_pipe.c:440(cli_pipe_validate_current_pdu) + Got pdu len 212, data_len 188, ss_len 0 +[2013/11/05 16:52:48.961554, 10, pid=7317, effective(0, 0), real(0, 0), class=rpc_cli] ../source3/rpc_client/cli_pipe.c:885(rpc_api_pipe_got_pdu) + rpc_api_pipe: got frag len of 212 at offset 0: NT_STATUS_OK +[2013/11/05 16:52:48.961653, 10, pid=7317, effective(0, 0), real(0, 0), class=rpc_cli] ../source3/rpc_client/cli_pipe.c:940(rpc_api_pipe_got_pdu) + rpc_api_pipe: host WIN-125IN6TLA89 returned 188 bytes. +[2013/11/05 16:52:48.961966, 1, pid=7317, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) + lsa_QueryInfoPolicy2: struct lsa_QueryInfoPolicy2 + out: struct lsa_QueryInfoPolicy2 + info : * + info : * + info : union lsa_PolicyInformation(case 12) + dns: struct lsa_DnsDomainInfo + name: struct lsa_StringLarge + length : 0x0012 (18) + size : 0x0014 (20) + string : * + string : 'ARW2008R2' + dns_domain: struct lsa_StringLarge + length : 0x0018 (24) + size : 0x001a (26) + string : * + string : 'arw2008r2.qa' + dns_forest: struct lsa_StringLarge + length : 0x0018 (24) + size : 0x001a (26) + string : * + string : 'arw2008r2.qa' + domain_guid : 4071180e-b164-42a9-b132-33e9c12fa1bd + sid : * + sid : S-1-5-21-2993504088-2269847352-917328378 + result : NT_STATUS_OK +[2013/11/05 16:52:48.963358, 5, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:2169(set_dc_type_and_flags_connect) + set_dc_type_and_flags_connect: domain ARW2008R2 is in native mode. +[2013/11/05 16:52:48.963463, 5, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:2172(set_dc_type_and_flags_connect) + set_dc_type_and_flags_connect: domain ARW2008R2 is running active directory. +[2013/11/05 16:52:48.963607, 10, pid=7317, effective(0, 0), real(0, 0)] ../libcli/smb/smb_signing.c:158(smb_signing_md5) + smb_signing_md5: sequence number 20 +[2013/11/05 16:52:48.963709, 10, pid=7317, effective(0, 0), real(0, 0)] ../libcli/smb/smb_signing.c:271(smb_signing_sign_pdu) + smb_signing_sign_pdu: sent SMB signature of +[2013/11/05 16:52:48.963802, 10, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/util.c:512(dump_data) + [0000] B1 DE 01 AB 38 33 E4 38 ....83.8 +[2013/11/05 16:52:48.965183, 10, pid=7317, effective(0, 0), real(0, 0)] ../libcli/smb/smb_signing.c:158(smb_signing_md5) + smb_signing_md5: sequence number 21 +[2013/11/05 16:52:48.965347, 10, pid=7317, effective(0, 0), real(0, 0)] ../libcli/smb/smb_signing.c:330(smb_signing_check_pdu) + smb_signing_check_pdu: seq 21: got good SMB signature of +[2013/11/05 16:52:48.965447, 10, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/util.c:512(dump_data) + [0000] 70 2F 72 E3 16 A1 F2 51 p/r....Q +[2013/11/05 16:52:48.965673, 5, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:167(get_cache) + get_cache: Setting ADS methods for domain ARW2008R2 +[2013/11/05 16:52:48.965891, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:420(wcache_fetch_seqnum) + wcache_fetch_seqnum: ARW2008R2 not found +[2013/11/05 16:52:48.966003, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:4683(wcache_tdc_fetch_domain) + wcache_tdc_fetch_domain: Searching for domain ARW2008R2 +[2013/11/05 16:52:48.966160, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:4698(wcache_tdc_fetch_domain) + wcache_tdc_fetch_domain: Found domain ARW2008R2 +[2013/11/05 16:52:48.966271, 3, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_ads.c:1355(sequence_number) + ads: fetch sequence_number for ARW2008R2 +[2013/11/05 16:52:48.966374, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:4683(wcache_tdc_fetch_domain) + wcache_tdc_fetch_domain: Searching for domain ARW2008R2 +[2013/11/05 16:52:48.966497, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:4698(wcache_tdc_fetch_domain) + wcache_tdc_fetch_domain: Found domain ARW2008R2 +[2013/11/05 16:52:48.966600, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_ads.c:206(ads_cached_connection) + ads_cached_connection +[2013/11/05 16:52:48.966814, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:77(smb_register_passdb) + Attempting to register passdb backend smbpasswd +[2013/11/05 16:52:48.966966, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:90(smb_register_passdb) + Successfully added passdb backend 'smbpasswd' +[2013/11/05 16:52:48.967169, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:77(smb_register_passdb) + Attempting to register passdb backend tdbsam +[2013/11/05 16:52:48.967309, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:90(smb_register_passdb) + Successfully added passdb backend 'tdbsam' +[2013/11/05 16:52:48.967434, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:77(smb_register_passdb) + Attempting to register passdb backend wbc_sam +[2013/11/05 16:52:48.967534, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:90(smb_register_passdb) + Successfully added passdb backend 'wbc_sam' +[2013/11/05 16:52:48.967630, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:77(smb_register_passdb) + Attempting to register passdb backend samba_dsdb +[2013/11/05 16:52:48.967733, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:90(smb_register_passdb) + Successfully added passdb backend 'samba_dsdb' +[2013/11/05 16:52:48.967825, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:77(smb_register_passdb) + Attempting to register passdb backend samba4 +[2013/11/05 16:52:48.968021, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:90(smb_register_passdb) + Successfully added passdb backend 'samba4' +[2013/11/05 16:52:48.968121, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:77(smb_register_passdb) + Attempting to register passdb backend ldapsam +[2013/11/05 16:52:48.968224, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:90(smb_register_passdb) + Successfully added passdb backend 'ldapsam' +[2013/11/05 16:52:48.968322, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:77(smb_register_passdb) + Attempting to register passdb backend NDS_ldapsam +[2013/11/05 16:52:48.968420, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:90(smb_register_passdb) + Successfully added passdb backend 'NDS_ldapsam' +[2013/11/05 16:52:48.968516, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:77(smb_register_passdb) + Attempting to register passdb backend IPA_ldapsam +[2013/11/05 16:52:48.968615, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:90(smb_register_passdb) + Successfully added passdb backend 'IPA_ldapsam' +[2013/11/05 16:52:48.968759, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:153(make_pdb_method_name) + Attempting to find a passdb backend to match ldapsam:"ldap://slave42.ar32i8.qa:7389" (ldapsam) +[2013/11/05 16:52:48.968867, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:174(make_pdb_method_name) + Found pdb backend ldapsam +[2013/11/05 16:52:48.969523, 2, pid=7317, effective(0, 0), real(0, 0)] ../source3/passdb/pdb_ldap_util.c:280(smbldap_search_domain_info) + smbldap_search_domain_info: Searching for:[(&(objectClass=sambaDomain)(sambaDomainName=AR32I8))] +[2013/11/05 16:52:48.969780, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/smbldap.c:1249(smbldap_search_ext) + smbldap_search_ext: base => [dc=ar32i8,dc=qa], filter => [(&(objectClass=sambaDomain)(sambaDomainName=AR32I8))], scope => [2] +[2013/11/05 16:52:48.969998, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/smbldap.c:1114(smbldap_close) + The connection to the LDAP server was closed +[2013/11/05 16:52:48.970102, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/smbldap.c:595(smb_ldap_setup_conn) + smb_ldap_setup_connection: ldap://slave42.ar32i8.qa:7389 +[2013/11/05 16:52:48.986130, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/smbldap.c:579(smbldap_start_tls) + StartTLS issued: using a TLS connection +[2013/11/05 16:52:48.986242, 2, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/smbldap.c:794(smbldap_open_connection) + smbldap_open_connection: connection opened +[2013/11/05 16:52:48.986284, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/smbldap.c:954(smbldap_connect_system) + ldap_connect_system: Binding to ldap server ldap://slave42.ar32i8.qa:7389 as "cn=slave42,cn=dc,cn=computers,dc=ar32i8,dc=qa" +[2013/11/05 16:52:48.996099, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/smbldap.c:1013(smbldap_connect_system) + ldap_connect_system: successful connection to the LDAP server + ldap_connect_system: LDAP server does support paged results +[2013/11/05 16:52:48.996210, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/smbldap.c:1092(smbldap_open) + The LDAP server is successfully connected +[2013/11/05 16:52:48.996966, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/smbldap.c:90(smbldap_talloc_single_attribute) + attribute sambaAlgorithmicRidBase does not exist +[2013/11/05 16:52:48.997052, 5, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:185(make_pdb_method_name) + pdb backend ldapsam:"ldap://slave42.ar32i8.qa:7389" has a valid init +[2013/11/05 16:52:48.997209, 10, pid=7317, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_ldap.c:6091(ldapsam_get_trusteddom_pw) + ldapsam_get_trusteddom_pw called for domain ARW2008R2 +[2013/11/05 16:52:48.997291, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/smbldap.c:1249(smbldap_search_ext) + smbldap_search_ext: base => [sambaDomainName=ARW2008R2,sambaDomainName=AR32I8,cn=samba,dc=ar32i8,dc=qa], filter => [(&(objectClass=sambaTrustedDomainPassword)(sambaDomainName=ARW2008R2))], scope => [2] +[2013/11/05 16:52:48.998039, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/sitename_cache.c:105(sitename_fetch) + sitename_fetch: Returning sitename for arw2008r2.qa: "Default-First-Site-Name" +[2013/11/05 16:52:48.998118, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery_dc.c:77(ads_dc_name) + ads_dc_name: domain=ARW2008R2 +[2013/11/05 16:52:48.998187, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/sitename_cache.c:105(sitename_fetch) + sitename_fetch: Returning sitename for arw2008r2.qa: "Default-First-Site-Name" +[2013/11/05 16:52:48.998227, 6, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/ldap.c:422(ads_find_dc) + ads_find_dc: (cldap) looking for realm 'arw2008r2.qa' +[2013/11/05 16:52:48.998279, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3289(get_sorted_dc_list) + get_sorted_dc_list: attempting lookup for name arw2008r2.qa (sitename Default-First-Site-Name) +[2013/11/05 16:52:48.998364, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:208(saf_fetch) + saf_fetch: failed to find server for "arw2008r2.qa" domain +[2013/11/05 16:52:48.998419, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3102(get_dc_list) + get_dc_list: preferred server list: ", *" +[2013/11/05 16:52:48.998463, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up arw2008r2.qa#1c (sitename Default-First-Site-Name) +[2013/11/05 16:52:48.998514, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:160(namecache_fetch) + no entry for arw2008r2.qa#1C found. +[2013/11/05 16:52:48.998578, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2452(resolve_ads) + resolve_ads: Attempting to resolve DCs for arw2008r2.qa using DNS +[2013/11/05 16:52:49.017094, 3, pid=7317, effective(0, 0), real(0, 0)] ../lib/addns/dnsquery.c:346(dns_send_req) + dns_send_req: Failed to resolve _ldap._tcp.Default-First-Site-Name._sites.dc._msdcs.arw2008r2.qa (Erfolg) +[2013/11/05 16:52:49.017272, 3, pid=7317, effective(0, 0), real(0, 0)] ../lib/addns/dnsquery.c:423(ads_dns_lookup_srv) + ads_dns_lookup_srv: Failed to send DNS query (NT_STATUS_UNSUCCESSFUL) +[2013/11/05 16:52:49.034483, 3, pid=7317, effective(0, 0), real(0, 0)] ../lib/addns/dnsquery.c:346(dns_send_req) + dns_send_req: Failed to resolve _ldap._tcp.dc._msdcs.arw2008r2.qa (Erfolg) +[2013/11/05 16:52:49.034734, 3, pid=7317, effective(0, 0), real(0, 0)] ../lib/addns/dnsquery.c:423(ads_dns_lookup_srv) + ads_dns_lookup_srv: Failed to send DNS query (NT_STATUS_UNSUCCESSFUL) +[2013/11/05 16:52:49.034894, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3123(get_dc_list) + Adding 0 DC's from auto lookup +[2013/11/05 16:52:49.035036, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3134(get_dc_list) + get_dc_list: no servers found +[2013/11/05 16:52:49.035138, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3301(get_sorted_dc_list) + get_sorted_dc_list: no server for name arw2008r2.qa available in site Default-First-Site-Name, fallback to all servers +[2013/11/05 16:52:49.035313, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:208(saf_fetch) + saf_fetch: failed to find server for "arw2008r2.qa" domain +[2013/11/05 16:52:49.035422, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3102(get_dc_list) + get_dc_list: preferred server list: ", *" +[2013/11/05 16:52:49.035522, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up arw2008r2.qa#1c (sitename (null)) +[2013/11/05 16:52:49.035645, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:160(namecache_fetch) + no entry for arw2008r2.qa#1C found. +[2013/11/05 16:52:49.035763, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2452(resolve_ads) + resolve_ads: Attempting to resolve DCs for arw2008r2.qa using DNS +[2013/11/05 16:52:49.053727, 3, pid=7317, effective(0, 0), real(0, 0)] ../lib/addns/dnsquery.c:346(dns_send_req) + dns_send_req: Failed to resolve _ldap._tcp.dc._msdcs.arw2008r2.qa (Erfolg) +[2013/11/05 16:52:49.054139, 3, pid=7317, effective(0, 0), real(0, 0)] ../lib/addns/dnsquery.c:423(ads_dns_lookup_srv) + ads_dns_lookup_srv: Failed to send DNS query (NT_STATUS_UNSUCCESSFUL) +[2013/11/05 16:52:49.054264, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3123(get_dc_list) + Adding 0 DC's from auto lookup +[2013/11/05 16:52:49.054364, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3134(get_dc_list) + get_dc_list: no servers found +[2013/11/05 16:52:49.054514, 6, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/ldap.c:422(ads_find_dc) + ads_find_dc: (cldap) looking for domain 'arw2008r2.qa' +[2013/11/05 16:52:49.054615, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3289(get_sorted_dc_list) + get_sorted_dc_list: attempting lookup for name arw2008r2.qa (sitename Default-First-Site-Name) +[2013/11/05 16:52:49.054792, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:208(saf_fetch) + saf_fetch: failed to find server for "arw2008r2.qa" domain +[2013/11/05 16:52:49.054904, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3102(get_dc_list) + get_dc_list: preferred server list: ", *" +[2013/11/05 16:52:49.055042, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up arw2008r2.qa#1c (sitename Default-First-Site-Name) +[2013/11/05 16:52:49.055252, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:160(namecache_fetch) + no entry for arw2008r2.qa#1C found. +[2013/11/05 16:52:49.055359, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2266(resolve_lmhosts) + resolve_lmhosts: Attempting lmhosts lookup for name arw2008r2.qa<0x1c> +[2013/11/05 16:52:49.055604, 3, pid=7317, effective(0, 0), real(0, 0)] ../libcli/nbt/lmhosts.c:185(resolve_lmhosts_file_as_sockaddr) + resolve_lmhosts: Attempting lmhosts lookup for name arw2008r2.qa<0x1c> +[2013/11/05 16:52:49.055818, 4, pid=7317, effective(0, 0), real(0, 0)] ../libcli/nbt/lmhosts.c:41(startlmhosts) + startlmhosts: Can't open lmhosts file /etc/samba/lmhosts. Error was Datei oder Verzeichnis nicht gefunden +[2013/11/05 16:52:49.056346, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2068(resolve_wins_send) + resolve_wins: WINS server resolution selected and no WINS servers listed. +[2013/11/05 16:52:49.056597, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2315(resolve_hosts) + resolve_hosts: not appropriate for name type <0x1c> +[2013/11/05 16:52:49.056752, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:1806(name_resolve_bcast_send) + name_resolve_bcast: Attempting broadcast lookup for name arw2008r2.qa<0x1c> +[2013/11/05 16:52:49.057499, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:499(open_socket_in) + bind succeeded on port 0 +[2013/11/05 16:52:49.057679, 5, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) + Socket options: + SO_KEEPALIVE = 0 + SO_REUSEADDR = 1 + SO_BROADCAST = 1 + Could not test socket option TCP_NODELAY. + Could not test socket option TCP_KEEPCNT. + Could not test socket option TCP_KEEPIDLE. + Could not test socket option TCP_KEEPINTVL. + IPTOS_LOWDELAY = 0 + IPTOS_THROUGHPUT = 0 + SO_REUSEPORT = 1 + SO_SNDBUF = 212992 + SO_RCVBUF = 212992 + SO_SNDLOWAT = 1 + SO_RCVLOWAT = 1 + SO_SNDTIMEO = 0 + SO_RCVTIMEO = 0 + Could not test socket option TCP_QUICKACK. + Could not test socket option TCP_DEFER_ACCEPT. +[2013/11/05 16:52:50.061304, 2, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/tevent_debug.c:63(samba_tevent_debug) + samba_tevent: EPOLL_CTL_DEL EBADF for fde[0x7f491fec7470] mpx_fde[(nil)] fd[25] - disabling +[2013/11/05 16:52:50.062239, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3123(get_dc_list) + Adding 0 DC's from auto lookup +[2013/11/05 16:52:50.062361, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3134(get_dc_list) + get_dc_list: no servers found +[2013/11/05 16:52:50.062463, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3301(get_sorted_dc_list) + get_sorted_dc_list: no server for name arw2008r2.qa available in site Default-First-Site-Name, fallback to all servers +[2013/11/05 16:52:50.062648, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:208(saf_fetch) + saf_fetch: failed to find server for "arw2008r2.qa" domain +[2013/11/05 16:52:50.062758, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3102(get_dc_list) + get_dc_list: preferred server list: ", *" +[2013/11/05 16:52:50.062859, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up arw2008r2.qa#1c (sitename (null)) +[2013/11/05 16:52:50.063031, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:160(namecache_fetch) + no entry for arw2008r2.qa#1C found. +[2013/11/05 16:52:50.063141, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2266(resolve_lmhosts) + resolve_lmhosts: Attempting lmhosts lookup for name arw2008r2.qa<0x1c> +[2013/11/05 16:52:50.063244, 3, pid=7317, effective(0, 0), real(0, 0)] ../libcli/nbt/lmhosts.c:185(resolve_lmhosts_file_as_sockaddr) + resolve_lmhosts: Attempting lmhosts lookup for name arw2008r2.qa<0x1c> +[2013/11/05 16:52:50.063419, 4, pid=7317, effective(0, 0), real(0, 0)] ../libcli/nbt/lmhosts.c:41(startlmhosts) + startlmhosts: Can't open lmhosts file /etc/samba/lmhosts. Error was Datei oder Verzeichnis nicht gefunden +[2013/11/05 16:52:50.063580, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2068(resolve_wins_send) + resolve_wins: WINS server resolution selected and no WINS servers listed. +[2013/11/05 16:52:50.063733, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2315(resolve_hosts) + resolve_hosts: not appropriate for name type <0x1c> +[2013/11/05 16:52:50.063856, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:1806(name_resolve_bcast_send) + name_resolve_bcast: Attempting broadcast lookup for name arw2008r2.qa<0x1c> +[2013/11/05 16:52:50.064332, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:499(open_socket_in) + bind succeeded on port 0 +[2013/11/05 16:52:50.064503, 5, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) + Socket options: + SO_KEEPALIVE = 0 + SO_REUSEADDR = 1 + SO_BROADCAST = 1 + Could not test socket option TCP_NODELAY. + Could not test socket option TCP_KEEPCNT. + Could not test socket option TCP_KEEPIDLE. + Could not test socket option TCP_KEEPINTVL. + IPTOS_LOWDELAY = 0 + IPTOS_THROUGHPUT = 0 + SO_REUSEPORT = 1 + SO_SNDBUF = 212992 + SO_RCVBUF = 212992 + SO_SNDLOWAT = 1 + SO_RCVLOWAT = 1 + SO_SNDTIMEO = 0 + SO_RCVTIMEO = 0 + Could not test socket option TCP_QUICKACK. + Could not test socket option TCP_DEFER_ACCEPT. +[2013/11/05 16:52:51.067813, 2, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/tevent_debug.c:63(samba_tevent_debug) + samba_tevent: EPOLL_CTL_DEL EBADF for fde[0x7f491fec73c0] mpx_fde[(nil)] fd[25] - disabling +[2013/11/05 16:52:51.068339, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3123(get_dc_list) + Adding 0 DC's from auto lookup +[2013/11/05 16:52:51.068453, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3134(get_dc_list) + get_dc_list: no servers found +[2013/11/05 16:52:51.068690, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3289(get_sorted_dc_list) + get_sorted_dc_list: attempting lookup for name ARW2008R2 (sitename NULL) +[2013/11/05 16:52:51.068896, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:211(saf_fetch) + saf_fetch: Returning "WIN-125IN6TLA89" for "ARW2008R2" domain +[2013/11/05 16:52:51.069009, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3102(get_dc_list) + get_dc_list: preferred server list: "WIN-125IN6TLA89, *" +[2013/11/05 16:52:51.069114, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up ARW2008R2#1c (sitename (null)) +[2013/11/05 16:52:51.069244, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:165(namecache_fetch) + name ARW2008R2#1C found. +[2013/11/05 16:52:51.069502, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:1110(remove_duplicate_addrs2) + remove_duplicate_addrs2: looking for duplicate address/port pairs +[2013/11/05 16:52:51.069608, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3123(get_dc_list) + Adding 1 DC's from auto lookup +[2013/11/05 16:52:51.069797, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/sitename_cache.c:102(sitename_fetch) + sitename_fetch: No stored sitename for +[2013/11/05 16:52:51.069902, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up WIN-125IN6TLA89#20 (sitename (null)) +[2013/11/05 16:52:51.070032, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:165(namecache_fetch) + name WIN-125IN6TLA89#20 found. +[2013/11/05 16:52:51.070201, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:1110(remove_duplicate_addrs2) + remove_duplicate_addrs2: looking for duplicate address/port pairs +[2013/11/05 16:52:51.070360, 9, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/conncache.c:150(check_negative_conn_cache) + check_negative_conn_cache returning result 0 for domain ARW2008R2 server 10.200.8.135 +[2013/11/05 16:52:51.070493, 9, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/conncache.c:150(check_negative_conn_cache) + check_negative_conn_cache returning result 0 for domain ARW2008R2 server 10.200.8.135 +[2013/11/05 16:52:51.070592, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:1110(remove_duplicate_addrs2) + remove_duplicate_addrs2: looking for duplicate address/port pairs +[2013/11/05 16:52:51.070701, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3239(get_dc_list) + get_dc_list: returning 1 ip addresses in an ordered list +[2013/11/05 16:52:51.070796, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3240(get_dc_list) + get_dc_list: 10.200.8.135:0 +[2013/11/05 16:52:51.071040, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:916(name_status_find) + name_status_find: looking up ARW2008R2#1c at 10.200.8.135 +[2013/11/05 16:52:51.071200, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:299(namecache_status_fetch) + namecache_status_fetch: no entry for NBT/ARW2008R2#1C.20.10.200.8.135 found. +[2013/11/05 16:52:51.071526, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:499(open_socket_in) + bind succeeded on port 0 +[2013/11/05 16:52:51.075102, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:550(parse_nmb) + parse_nmb: packet id = 3604 +[2013/11/05 16:52:51.075452, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:108(debug_nmb_packet) + nmb packet from 10.200.8.135(35072) header: id=3604 opcode=Query(0) response=Yes + header: flags: bcast=No rec_avail=No rec_des=No trunc=No auth=Yes + header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0 + answers: nmb_name=ARW2008R2<1c> rr_type=33 rr_class=1 ttl=0 + answers 0 char .WIN-125IN6TLA89 hex 0557494E2D313235494E36544C413839 + answers 10 char ...ARW2008R2 hex 00040041525732303038523220202020 + answers 20 char ...ARW2008R2 hex 20200084004152573230303852322020 + answers 30 char ...WIN-125IN hex 202020201C840057494E2D313235494E + answers 40 char 6TLA89 ..ARW2008 hex 36544C41383920040041525732303038 + answers 50 char R2 ...RT... hex 52322020202020201B04005254008BD7 + answers 60 char ................ hex 16000000000000000000000000000000 + answers 70 char ................ hex 00000000000000000000000000000000 + answers 80 char ......... hex 000000000000000000 +[2013/11/05 16:52:51.077093, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:278(parse_node_status) + WIN-125IN6TLA89#00: flags = 0x04 +[2013/11/05 16:52:51.077215, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:278(parse_node_status) + ARW2008R2#00: flags = 0x84 +[2013/11/05 16:52:51.077313, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:278(parse_node_status) + ARW2008R2#1c: flags = 0x84 +[2013/11/05 16:52:51.077409, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:278(parse_node_status) + WIN-125IN6TLA89#20: flags = 0x04 +[2013/11/05 16:52:51.077505, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:278(parse_node_status) + ARW2008R2#1b: flags = 0x04 +[2013/11/05 16:52:51.077684, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:962(name_status_find) + name_status_find: name found, name WIN-125IN6TLA89 ip address is 10.200.8.135 +[2013/11/05 16:52:51.077884, 9, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/conncache.c:150(check_negative_conn_cache) + check_negative_conn_cache returning result 0 for domain ARW2008R2 server WIN-125IN6TLA89 +[2013/11/05 16:52:51.078013, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery_dc.c:209(rpc_dc_name) + rpc_dc_name: Returning DC WIN-125IN6TLA89 (10.200.8.135) for domain ARW2008R2 +[2013/11/05 16:52:51.078133, 6, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/ldap.c:402(ads_find_dc) + ads_find_dc: (ldap) looking for realm 'arw2008r2.qa' +[2013/11/05 16:52:51.078292, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/sitename_cache.c:105(sitename_fetch) + sitename_fetch: Returning sitename for arw2008r2.qa: "Default-First-Site-Name" +[2013/11/05 16:52:51.078400, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery_dc.c:77(ads_dc_name) + ads_dc_name: domain=ARW2008R2 +[2013/11/05 16:52:51.078528, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/sitename_cache.c:105(sitename_fetch) + sitename_fetch: Returning sitename for arw2008r2.qa: "Default-First-Site-Name" +[2013/11/05 16:52:51.078624, 6, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/ldap.c:422(ads_find_dc) + ads_find_dc: (cldap) looking for realm 'arw2008r2.qa' +[2013/11/05 16:52:51.078765, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3289(get_sorted_dc_list) + get_sorted_dc_list: attempting lookup for name arw2008r2.qa (sitename Default-First-Site-Name) +[2013/11/05 16:52:51.078909, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:208(saf_fetch) + saf_fetch: failed to find server for "arw2008r2.qa" domain +[2013/11/05 16:52:51.079089, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3102(get_dc_list) + get_dc_list: preferred server list: ", *" +[2013/11/05 16:52:51.079193, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up arw2008r2.qa#1c (sitename Default-First-Site-Name) +[2013/11/05 16:52:51.079315, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:160(namecache_fetch) + no entry for arw2008r2.qa#1C found. +[2013/11/05 16:52:51.079433, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2452(resolve_ads) + resolve_ads: Attempting to resolve DCs for arw2008r2.qa using DNS +[2013/11/05 16:52:51.101247, 3, pid=7317, effective(0, 0), real(0, 0)] ../lib/addns/dnsquery.c:346(dns_send_req) + dns_send_req: Failed to resolve _ldap._tcp.Default-First-Site-Name._sites.dc._msdcs.arw2008r2.qa (Erfolg) +[2013/11/05 16:52:51.101567, 3, pid=7317, effective(0, 0), real(0, 0)] ../lib/addns/dnsquery.c:423(ads_dns_lookup_srv) + ads_dns_lookup_srv: Failed to send DNS query (NT_STATUS_UNSUCCESSFUL) +[2013/11/05 16:52:51.119456, 3, pid=7317, effective(0, 0), real(0, 0)] ../lib/addns/dnsquery.c:346(dns_send_req) + dns_send_req: Failed to resolve _ldap._tcp.dc._msdcs.arw2008r2.qa (Erfolg) +[2013/11/05 16:52:51.119724, 3, pid=7317, effective(0, 0), real(0, 0)] ../lib/addns/dnsquery.c:423(ads_dns_lookup_srv) + ads_dns_lookup_srv: Failed to send DNS query (NT_STATUS_UNSUCCESSFUL) +[2013/11/05 16:52:51.119843, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3123(get_dc_list) + Adding 0 DC's from auto lookup +[2013/11/05 16:52:51.120170, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3134(get_dc_list) + get_dc_list: no servers found +[2013/11/05 16:52:51.120273, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3301(get_sorted_dc_list) + get_sorted_dc_list: no server for name arw2008r2.qa available in site Default-First-Site-Name, fallback to all servers +[2013/11/05 16:52:51.120461, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:208(saf_fetch) + saf_fetch: failed to find server for "arw2008r2.qa" domain +[2013/11/05 16:52:51.120572, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3102(get_dc_list) + get_dc_list: preferred server list: ", *" +[2013/11/05 16:52:51.120672, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up arw2008r2.qa#1c (sitename (null)) +[2013/11/05 16:52:51.120797, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:160(namecache_fetch) + no entry for arw2008r2.qa#1C found. +[2013/11/05 16:52:51.120917, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2452(resolve_ads) + resolve_ads: Attempting to resolve DCs for arw2008r2.qa using DNS +[2013/11/05 16:52:51.138378, 3, pid=7317, effective(0, 0), real(0, 0)] ../lib/addns/dnsquery.c:346(dns_send_req) + dns_send_req: Failed to resolve _ldap._tcp.dc._msdcs.arw2008r2.qa (Erfolg) +[2013/11/05 16:52:51.138585, 3, pid=7317, effective(0, 0), real(0, 0)] ../lib/addns/dnsquery.c:423(ads_dns_lookup_srv) + ads_dns_lookup_srv: Failed to send DNS query (NT_STATUS_UNSUCCESSFUL) +[2013/11/05 16:52:51.138677, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3123(get_dc_list) + Adding 0 DC's from auto lookup +[2013/11/05 16:52:51.138756, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3134(get_dc_list) + get_dc_list: no servers found +[2013/11/05 16:52:51.139233, 6, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/ldap.c:422(ads_find_dc) + ads_find_dc: (cldap) looking for domain 'arw2008r2.qa' +[2013/11/05 16:52:51.139340, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3289(get_sorted_dc_list) + get_sorted_dc_list: attempting lookup for name arw2008r2.qa (sitename Default-First-Site-Name) +[2013/11/05 16:52:51.139501, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:208(saf_fetch) + saf_fetch: failed to find server for "arw2008r2.qa" domain +[2013/11/05 16:52:51.139608, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3102(get_dc_list) + get_dc_list: preferred server list: ", *" +[2013/11/05 16:52:51.139706, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up arw2008r2.qa#1c (sitename Default-First-Site-Name) +[2013/11/05 16:52:51.139828, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:160(namecache_fetch) + no entry for arw2008r2.qa#1C found. +[2013/11/05 16:52:51.140070, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2266(resolve_lmhosts) + resolve_lmhosts: Attempting lmhosts lookup for name arw2008r2.qa<0x1c> +[2013/11/05 16:52:51.140177, 3, pid=7317, effective(0, 0), real(0, 0)] ../libcli/nbt/lmhosts.c:185(resolve_lmhosts_file_as_sockaddr) + resolve_lmhosts: Attempting lmhosts lookup for name arw2008r2.qa<0x1c> +[2013/11/05 16:52:51.140292, 4, pid=7317, effective(0, 0), real(0, 0)] ../libcli/nbt/lmhosts.c:41(startlmhosts) + startlmhosts: Can't open lmhosts file /etc/samba/lmhosts. Error was Datei oder Verzeichnis nicht gefunden +[2013/11/05 16:52:51.140446, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2068(resolve_wins_send) + resolve_wins: WINS server resolution selected and no WINS servers listed. +[2013/11/05 16:52:51.140571, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2315(resolve_hosts) + resolve_hosts: not appropriate for name type <0x1c> +[2013/11/05 16:52:51.140688, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:1806(name_resolve_bcast_send) + name_resolve_bcast: Attempting broadcast lookup for name arw2008r2.qa<0x1c> +[2013/11/05 16:52:51.140985, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:499(open_socket_in) + bind succeeded on port 0 +[2013/11/05 16:52:51.141107, 5, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) + Socket options: + SO_KEEPALIVE = 0 + SO_REUSEADDR = 1 + SO_BROADCAST = 1 + Could not test socket option TCP_NODELAY. + Could not test socket option TCP_KEEPCNT. + Could not test socket option TCP_KEEPIDLE. + Could not test socket option TCP_KEEPINTVL. + IPTOS_LOWDELAY = 0 + IPTOS_THROUGHPUT = 0 + SO_REUSEPORT = 1 + SO_SNDBUF = 212992 + SO_RCVBUF = 212992 + SO_SNDLOWAT = 1 + SO_RCVLOWAT = 1 + SO_SNDTIMEO = 0 + SO_RCVTIMEO = 0 + Could not test socket option TCP_QUICKACK. + Could not test socket option TCP_DEFER_ACCEPT. +[2013/11/05 16:52:52.144316, 2, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/tevent_debug.c:63(samba_tevent_debug) + samba_tevent: EPOLL_CTL_DEL EBADF for fde[0x7f491fec7550] mpx_fde[(nil)] fd[25] - disabling +[2013/11/05 16:52:52.144710, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3123(get_dc_list) + Adding 0 DC's from auto lookup +[2013/11/05 16:52:52.144847, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3134(get_dc_list) + get_dc_list: no servers found +[2013/11/05 16:52:52.144948, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3301(get_sorted_dc_list) + get_sorted_dc_list: no server for name arw2008r2.qa available in site Default-First-Site-Name, fallback to all servers +[2013/11/05 16:52:52.145130, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:208(saf_fetch) + saf_fetch: failed to find server for "arw2008r2.qa" domain +[2013/11/05 16:52:52.145286, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3102(get_dc_list) + get_dc_list: preferred server list: ", *" +[2013/11/05 16:52:52.145392, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up arw2008r2.qa#1c (sitename (null)) +[2013/11/05 16:52:52.145516, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:160(namecache_fetch) + no entry for arw2008r2.qa#1C found. +[2013/11/05 16:52:52.145622, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2266(resolve_lmhosts) + resolve_lmhosts: Attempting lmhosts lookup for name arw2008r2.qa<0x1c> +[2013/11/05 16:52:52.145721, 3, pid=7317, effective(0, 0), real(0, 0)] ../libcli/nbt/lmhosts.c:185(resolve_lmhosts_file_as_sockaddr) + resolve_lmhosts: Attempting lmhosts lookup for name arw2008r2.qa<0x1c> +[2013/11/05 16:52:52.145833, 4, pid=7317, effective(0, 0), real(0, 0)] ../libcli/nbt/lmhosts.c:41(startlmhosts) + startlmhosts: Can't open lmhosts file /etc/samba/lmhosts. Error was Datei oder Verzeichnis nicht gefunden +[2013/11/05 16:52:52.145985, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2068(resolve_wins_send) + resolve_wins: WINS server resolution selected and no WINS servers listed. +[2013/11/05 16:52:52.146102, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2315(resolve_hosts) + resolve_hosts: not appropriate for name type <0x1c> +[2013/11/05 16:52:52.146219, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:1806(name_resolve_bcast_send) + name_resolve_bcast: Attempting broadcast lookup for name arw2008r2.qa<0x1c> +[2013/11/05 16:52:52.146530, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:499(open_socket_in) + bind succeeded on port 0 +[2013/11/05 16:52:52.146650, 5, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) + Socket options: + SO_KEEPALIVE = 0 + SO_REUSEADDR = 1 + SO_BROADCAST = 1 + Could not test socket option TCP_NODELAY. + Could not test socket option TCP_KEEPCNT. + Could not test socket option TCP_KEEPIDLE. + Could not test socket option TCP_KEEPINTVL. + IPTOS_LOWDELAY = 0 + IPTOS_THROUGHPUT = 0 + SO_REUSEPORT = 1 + SO_SNDBUF = 212992 + SO_RCVBUF = 212992 + SO_SNDLOWAT = 1 + SO_RCVLOWAT = 1 + SO_SNDTIMEO = 0 + SO_RCVTIMEO = 0 + Could not test socket option TCP_QUICKACK. + Could not test socket option TCP_DEFER_ACCEPT. +[2013/11/05 16:52:53.149408, 2, pid=7317, effective(0, 0), real(0, 0)] ../lib/util/tevent_debug.c:63(samba_tevent_debug) + samba_tevent: EPOLL_CTL_DEL EBADF for fde[0x7f491fec7430] mpx_fde[(nil)] fd[25] - disabling +[2013/11/05 16:52:53.149801, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3123(get_dc_list) + Adding 0 DC's from auto lookup +[2013/11/05 16:52:53.149936, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3134(get_dc_list) + get_dc_list: no servers found +[2013/11/05 16:52:53.150050, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3289(get_sorted_dc_list) + get_sorted_dc_list: attempting lookup for name ARW2008R2 (sitename NULL) +[2013/11/05 16:52:53.150268, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:211(saf_fetch) + saf_fetch: Returning "WIN-125IN6TLA89" for "ARW2008R2" domain +[2013/11/05 16:52:53.150379, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3102(get_dc_list) + get_dc_list: preferred server list: "WIN-125IN6TLA89, *" +[2013/11/05 16:52:53.150484, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up ARW2008R2#1c (sitename (null)) +[2013/11/05 16:52:53.150615, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:165(namecache_fetch) + name ARW2008R2#1C found. +[2013/11/05 16:52:53.150873, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:1110(remove_duplicate_addrs2) + remove_duplicate_addrs2: looking for duplicate address/port pairs +[2013/11/05 16:52:53.151061, 8, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3123(get_dc_list) + Adding 1 DC's from auto lookup +[2013/11/05 16:52:53.151201, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/sitename_cache.c:102(sitename_fetch) + sitename_fetch: No stored sitename for +[2013/11/05 16:52:53.151303, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up WIN-125IN6TLA89#20 (sitename (null)) +[2013/11/05 16:52:53.151432, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:165(namecache_fetch) + name WIN-125IN6TLA89#20 found. +[2013/11/05 16:52:53.151598, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:1110(remove_duplicate_addrs2) + remove_duplicate_addrs2: looking for duplicate address/port pairs +[2013/11/05 16:52:53.151737, 9, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/conncache.c:150(check_negative_conn_cache) + check_negative_conn_cache returning result 0 for domain ARW2008R2 server 10.200.8.135 +[2013/11/05 16:52:53.151866, 9, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/conncache.c:150(check_negative_conn_cache) + check_negative_conn_cache returning result 0 for domain ARW2008R2 server 10.200.8.135 +[2013/11/05 16:52:53.152563, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:1110(remove_duplicate_addrs2) + remove_duplicate_addrs2: looking for duplicate address/port pairs +[2013/11/05 16:52:53.152670, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3239(get_dc_list) + get_dc_list: returning 1 ip addresses in an ordered list +[2013/11/05 16:52:53.152767, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:3240(get_dc_list) + get_dc_list: 10.200.8.135:0 +[2013/11/05 16:52:53.152879, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:916(name_status_find) + name_status_find: looking up ARW2008R2#1c at 10.200.8.135 +[2013/11/05 16:52:53.153008, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:299(namecache_status_fetch) + namecache_status_fetch: no entry for NBT/ARW2008R2#1C.20.10.200.8.135 found. +[2013/11/05 16:52:53.153312, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:499(open_socket_in) + bind succeeded on port 0 +[2013/11/05 16:52:53.155441, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:550(parse_nmb) + parse_nmb: packet id = 27720 +[2013/11/05 16:52:53.155696, 4, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/nmblib.c:108(debug_nmb_packet) + nmb packet from 10.200.8.135(35072) header: id=27720 opcode=Query(0) response=Yes + header: flags: bcast=No rec_avail=No rec_des=No trunc=No auth=Yes + header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0 + answers: nmb_name=ARW2008R2<1c> rr_type=33 rr_class=1 ttl=0 + answers 0 char .WIN-125IN6TLA89 hex 0557494E2D313235494E36544C413839 + answers 10 char ...ARW2008R2 hex 00040041525732303038523220202020 + answers 20 char ...ARW2008R2 hex 20200084004152573230303852322020 + answers 30 char ...WIN-125IN hex 202020201C840057494E2D313235494E + answers 40 char 6TLA89 ..ARW2008 hex 36544C41383920040041525732303038 + answers 50 char R2 ...RT... hex 52322020202020201B04005254008BD7 + answers 60 char ................ hex 16000000000000000000000000000000 + answers 70 char ................ hex 00000000000000000000000000000000 + answers 80 char ......... hex 000000000000000000 +[2013/11/05 16:52:53.157032, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:278(parse_node_status) + WIN-125IN6TLA89#00: flags = 0x04 +[2013/11/05 16:52:53.157150, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:278(parse_node_status) + ARW2008R2#00: flags = 0x84 +[2013/11/05 16:52:53.157249, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:278(parse_node_status) + ARW2008R2#1c: flags = 0x84 +[2013/11/05 16:52:53.157379, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:278(parse_node_status) + WIN-125IN6TLA89#20: flags = 0x04 +[2013/11/05 16:52:53.157478, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:278(parse_node_status) + ARW2008R2#1b: flags = 0x04 +[2013/11/05 16:52:53.157595, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:962(name_status_find) + name_status_find: name found, name WIN-125IN6TLA89 ip address is 10.200.8.135 +[2013/11/05 16:52:53.157740, 9, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/conncache.c:150(check_negative_conn_cache) + check_negative_conn_cache returning result 0 for domain ARW2008R2 server WIN-125IN6TLA89 +[2013/11/05 16:52:53.157848, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery_dc.c:209(rpc_dc_name) + rpc_dc_name: Returning DC WIN-125IN6TLA89 (10.200.8.135) for domain ARW2008R2 +[2013/11/05 16:52:53.158467, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/sitename_cache.c:102(sitename_fetch) + sitename_fetch: No stored sitename for +[2013/11/05 16:52:53.158597, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:2598(internal_resolve_name) + internal_resolve_name: looking up WIN-125IN6TLA89#20 (sitename (null)) +[2013/11/05 16:52:53.158735, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namecache.c:165(namecache_fetch) + name WIN-125IN6TLA89#20 found. +[2013/11/05 16:52:53.158949, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libsmb/namequery.c:1110(remove_duplicate_addrs2) + remove_duplicate_addrs2: looking for duplicate address/port pairs +[2013/11/05 16:52:53.159094, 5, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/ldap.c:270(ads_try_connect) + ads_try_connect: sending CLDAP request to 10.200.8.135 (realm: arw2008r2.qa) +[2013/11/05 16:52:53.162284, 1, pid=7317, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) + &response->data.nt5_ex: struct NETLOGON_SAM_LOGON_RESPONSE_EX + command : LOGON_SAM_LOGON_RESPONSE_EX (23) + sbz : 0x0000 (0) + server_type : 0x000033fd (13309) + 1: NBT_SERVER_PDC + 1: NBT_SERVER_GC + 1: NBT_SERVER_LDAP + 1: NBT_SERVER_DS + 1: NBT_SERVER_KDC + 1: NBT_SERVER_TIMESERV + 1: NBT_SERVER_CLOSEST + 1: NBT_SERVER_WRITABLE + 1: NBT_SERVER_GOOD_TIMESERV + 0: NBT_SERVER_NDNC + 0: NBT_SERVER_SELECT_SECRET_DOMAIN_6 + 1: NBT_SERVER_FULL_SECRET_DOMAIN_6 + 1: NBT_SERVER_ADS_WEB_SERVICE + 0: NBT_SERVER_HAS_DNS_NAME + 0: NBT_SERVER_IS_DEFAULT_NC + 0: NBT_SERVER_FOREST_ROOT + domain_uuid : 4071180e-b164-42a9-b132-33e9c12fa1bd + forest : 'arw2008r2.qa' + dns_domain : 'arw2008r2.qa' + pdc_dns_name : 'WIN-125IN6TLA89.arw2008r2.qa' + domain_name : 'ARW2008R2' + pdc_name : 'WIN-125IN6TLA89' + user_name : '' + server_site : 'Default-First-Site-Name' + client_site : 'Default-First-Site-Name' + sockaddr_size : 0x00 (0) + sockaddr: struct nbt_sockaddr + sockaddr_family : 0x00000000 (0) + pdc_ip : (null) + remaining : DATA_BLOB length=0 + next_closest_site : NULL + nt_version : 0x00000005 (5) + 1: NETLOGON_NT_VERSION_1 + 0: NETLOGON_NT_VERSION_5 + 1: NETLOGON_NT_VERSION_5EX + 0: NETLOGON_NT_VERSION_5EX_WITH_IP + 0: NETLOGON_NT_VERSION_WITH_CLOSEST_SITE + 0: NETLOGON_NT_VERSION_AVOID_NT4EMUL + 0: NETLOGON_NT_VERSION_PDC + 0: NETLOGON_NT_VERSION_IP + 0: NETLOGON_NT_VERSION_LOCAL + 0: NETLOGON_NT_VERSION_GC + lmnt_token : 0xffff (65535) + lm20_token : 0xffff (65535) +[2013/11/05 16:52:53.164967, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/sitename_cache.c:70(sitename_store) + sitename_store: realm = [ARW2008R2], sitename = [Default-First-Site-Name], expire = [2085923199] +[2013/11/05 16:52:53.165126, 10, pid=7317, effective(0, 0), real(0, 0), class=tdb] ../source3/lib/gencache.c:275(gencache_set_data_blob) + Did not store value for AD_SITENAME/DOMAIN/ARW2008R2, we already got it +[2013/11/05 16:52:53.165232, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/sitename_cache.c:70(sitename_store) + sitename_store: realm = [arw2008r2.qa], sitename = [Default-First-Site-Name], expire = [2085923199] +[2013/11/05 16:52:53.165357, 10, pid=7317, effective(0, 0), real(0, 0), class=tdb] ../source3/lib/gencache.c:275(gencache_set_data_blob) + Did not store value for AD_SITENAME/DOMAIN/ARW2008R2.QA, we already got it +[2013/11/05 16:52:53.165496, 3, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/ldap.c:680(ads_connect) + Successfully contacted LDAP server 10.200.8.135 +[2013/11/05 16:52:53.165647, 10, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/ldap.c:70(ldap_open_with_timeout) + Opening connection to LDAP server 'WIN-125IN6TLA89.arw2008r2.qa:389', timeout 15 seconds +[2013/11/05 16:52:53.185610, 2, pid=7317, effective(0, 0), real(0, 0)] ../source3/libads/ldap.c:116(ldap_open_with_timeout) + Could not open connection to LDAP server WIN-125IN6TLA89.arw2008r2.qa:389: Erfolg +[2013/11/05 16:52:53.185767, 1, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_ads.c:122(ads_cached_connection_connect) + ads_connect for domain ARW2008R2 failed: Operations error +[2013/11/05 16:52:53.185852, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:571(refresh_sequence_number) + refresh_sequence_number: failed with NT_STATUS_UNSUCCESSFUL +[2013/11/05 16:52:53.185981, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:496(wcache_store_seqnum) + wcache_store_seqnum: success [ARW2008R2][4294967295 @ 1383666773] +[2013/11/05 16:52:53.186022, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) + refresh_sequence_number: ARW2008R2 seq number is now -1 +[2013/11/05 16:52:53.186103, 1, pid=7317, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) + wbint_LookupName: struct wbint_LookupName + out: struct wbint_LookupName + type : * + type : SID_NAME_USE_NONE (0) + sid : * + sid : S-0-0 + result : NT_STATUS_UNSUCCESSFUL +[2013/11/05 16:52:53.186358, 4, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1341(child_handler) + Finished processing child request 59 +[2013/11/05 16:52:53.186402, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1358(child_handler) + Writing 3512 bytes to parent diff -Nuar samba.0/log.winbindd samba.1/log.winbindd --- samba.0/log.winbindd 2013-11-05 16:52:48.804000000 +0100 +++ samba.1/log.winbindd 2013-11-05 16:52:53.188000000 +0100 @@ -1903,3 +1903,83 @@ idmap config ARW2008R2 : range = not defined [2013/11/05 16:52:41.026253, 2, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_util.c:237(add_trusted_domain) Added domain ARW2008R2 (null) S-1-5-21-2993504088-2269847352-917328378 +[2013/11/05 16:52:48.817759, 6, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:870(new_connection) + accepted socket 19 +[2013/11/05 16:52:48.818033, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:720(process_request) + process_request: request fn INTERFACE_VERSION +[2013/11/05 16:52:48.818094, 3, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_misc.c:395(winbindd_interface_version) + [ 7316]: request interface version +[2013/11/05 16:52:48.818181, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:816(winbind_client_response_written) + winbind_client_response_written[7316:INTERFACE_VERSION]: delivered response to client +[2013/11/05 16:52:48.818271, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:720(process_request) + process_request: request fn WINBINDD_PRIV_PIPE_DIR +[2013/11/05 16:52:48.818339, 3, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_misc.c:428(winbindd_priv_pipe_dir) + [ 7316]: request location of privileged pipe +[2013/11/05 16:52:48.818429, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:816(winbind_client_response_written) + winbind_client_response_written[7316:WINBINDD_PRIV_PIPE_DIR]: delivered response to client +[2013/11/05 16:52:48.818535, 6, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:870(new_connection) + accepted socket 21 +[2013/11/05 16:52:48.818629, 6, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:918(winbind_client_request_read) + closing socket 19, client exited +[2013/11/05 16:52:48.818717, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:720(process_request) + process_request: request fn INTERFACE_VERSION +[2013/11/05 16:52:48.818759, 3, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_misc.c:395(winbindd_interface_version) + [ 7316]: request interface version +[2013/11/05 16:52:48.818812, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:816(winbind_client_response_written) + winbind_client_response_written[7316:INTERFACE_VERSION]: delivered response to client +[2013/11/05 16:52:48.818889, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:720(process_request) + process_request: request fn INFO +[2013/11/05 16:52:48.818933, 3, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_misc.c:383(winbindd_info) + [ 7316]: request misc info +[2013/11/05 16:52:48.819030, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:816(winbind_client_response_written) + winbind_client_response_written[7316:INFO]: delivered response to client +[2013/11/05 16:52:48.819118, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:720(process_request) + process_request: request fn NETBIOS_NAME +[2013/11/05 16:52:48.819160, 3, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_misc.c:416(winbindd_netbios_name) + [ 7316]: request netbios name +[2013/11/05 16:52:48.819223, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:816(winbind_client_response_written) + winbind_client_response_written[7316:NETBIOS_NAME]: delivered response to client +[2013/11/05 16:52:48.819302, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:720(process_request) + process_request: request fn DOMAIN_NAME +[2013/11/05 16:52:48.819342, 3, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_misc.c:405(winbindd_domain_name) + [ 7316]: request domain name +[2013/11/05 16:52:48.819394, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:816(winbind_client_response_written) + winbind_client_response_written[7316:DOMAIN_NAME]: delivered response to client +[2013/11/05 16:52:48.819478, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:720(process_request) + process_request: request fn DOMAIN_INFO +[2013/11/05 16:52:48.819519, 3, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_misc.c:237(winbindd_domain_info) + [ 7316]: domain_info [AR32I8] +[2013/11/05 16:52:48.819580, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:816(winbind_client_response_written) + winbind_client_response_written[7316:DOMAIN_INFO]: delivered response to client +[2013/11/05 16:52:48.819694, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:693(process_request) + process_request: Handling async request 7316:LOOKUPNAME +[2013/11/05 16:52:48.819766, 3, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_lookupname.c:69(winbindd_lookupname_send) + lookupname ARW2008R2+Administrator +[2013/11/05 16:52:48.820015, 1, pid=7311, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) + wbint_LookupName: struct wbint_LookupName + in: struct wbint_LookupName + domain : * + domain : 'ARW2008R2' + name : * + name : 'ADMINISTRATOR' + flags : 0x00000000 (0) +[2013/11/05 16:52:48.820343, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1380(fork_domain_child) + fork_domain_child called for domain 'ARW2008R2' +[2013/11/05 16:52:48.821662, 10, pid=7317, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1436(fork_domain_child) + Child process 7317 +[2013/11/05 16:52:53.186773, 1, pid=7311, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) + wbint_LookupName: struct wbint_LookupName + out: struct wbint_LookupName + type : * + type : SID_NAME_USE_NONE (0) + sid : * + sid : S-0-0 + result : NT_STATUS_UNSUCCESSFUL +[2013/11/05 16:52:53.187237, 5, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_lookupname.c:104(winbindd_lookupname_recv) + Could not convert sid S-0-0: NT_STATUS_UNSUCCESSFUL +[2013/11/05 16:52:53.187376, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:755(wb_request_done) + wb_request_done[7316:LOOKUPNAME]: NT_STATUS_UNSUCCESSFUL +[2013/11/05 16:52:53.190756, 10, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:816(winbind_client_response_written) + winbind_client_response_written[7316:LOOKUPNAME]: delivered response to client +[2013/11/05 16:52:53.191126, 6, pid=7311, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:918(winbind_client_request_read) + closing socket 21, client exited