-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1
Hi Julien,
>> Sending of email is also possible. But there is one problem left.
>> When I send an email in Outlook the mail "hangs" in the outgoing
>> folder for about 20 minutes. I see a connection to postfix from
>> OpenChange but it last 20 minutes, before OpenChange sends rcpt
>> to, mail from and data. If OpenChange sends this information the
>> email is successfully processed by postfix.
>>
>> Does anyone has a suggestion where to look for the "hang"?
>
> Hi Sebastian,
>
> If you could capture a wireshark trace between Outlook and
> OpenChange - and disable encrypted data option if defined - along
> with the postfix log, this shall be very useful identifying the
> root cause of the bug.
I captured the communication between OpenChange and Postfix and found
out, that it's not the server (postfix) who is waiting for the client
(OpenChange). After the TCP handshake the client (OpenChange) ist
waiting for the 220 <servername> message from the smtp server.
I now started an strace on the postfix smtpd process. After the tcp
handshare the postfix smtpd process tries to get information from the
/var/lib/samba/winbindd_privileged/pipe pipe. But there's no response
on the pipe for the server.
lstat("/var/lib/samba/winbindd_privileged", {st_mode=S_IFDIR|0750,
st_size=4096, ...}) = 0
lstat("/var/lib/samba/winbindd_privileged/pipe",
{st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0
socket(PF_FILE, SOCK_STREAM, 0) = 11
fcntl(11, F_GETFL) = 0x2 (flags O_RDWR)
fcntl(11, F_SETFL, O_RDWR|O_NONBLOCK) = 0
fcntl(11, F_GETFD) = 0
fcntl(11, F_SETFD, FD_CLOEXEC) = 0
connect(11, {sa_family=AF_FILE,
path="/var/lib/samba/winbindd_privileged/pipe"}, 110) = 0
close(10) = 0
poll([{fd=11, events=POLLIN|POLLHUP}], 1, 0) = 0 (Timeout)
write(11,
"0\10\0\0\6\0\0\0\0\0\0\0>H\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"...,
2096) = 2096
poll([{fd=11, events=POLLIN|POLLHUP}], 1, 5000) = 0 (Timeout)
poll([{fd=11, events=POLLIN|POLLHUP}], 1, 5000) = 0 (Timeout)
poll([{fd=11, events=POLLIN|POLLHUP}], 1, 5000) = 0 (Timeout)
poll([{fd=11, events=POLLIN|POLLHUP}], 1, 5000) = 0 (Timeout)
poll([{fd=11, events=POLLIN|POLLHUP}], 1, 5000) = 0 (Timeout)
poll([{fd=11, events=POLLIN|POLLHUP}], 1, 5000) = 0 (Timeout)
poll([{fd=11, events=POLLIN|POLLHUP}], 1, 5000) = 0 (Timeout)
close(11) = 0
lstat("/var/run/samba/winbindd", {st_mode=S_IFDIR|0755, st_size=60,
...}) = 0
lstat("/var/run/samba/winbindd/pipe", {st_mode=S_IFSOCK|0777,
st_size=0, ...}) = 0
socket(PF_FILE, SOCK_STREAM, 0) = 10
fcntl(10, F_GETFL) = 0x2 (flags O_RDWR)
fcntl(10, F_SETFL, O_RDWR|O_NONBLOCK) = 0
fcntl(10, F_GETFD) = 0
fcntl(10, F_SETFD, FD_CLOEXEC) = 0
connect(10, {sa_family=AF_FILE, path="/var/run/samba/winbindd/pipe"},
110) = 0
poll([{fd=10, events=POLLIN|POLLHUP}], 1, 0) = 0 (Timeout)
write(10,
"0\10\0\0\0\0\0\0\0\0\0\0>H\0\0\0\10\0\0\0\0\0\0\0\0\0\0\0\0\0\0"...,
2096) = 2096
poll([{fd=10, events=POLLIN|POLLHUP}], 1, 5000) = 1 ([{fd=10,
revents=POLLIN}])
read(10,
"\250\r\0\0\2\0\0\0\33\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"...,
3496) = 3496
poll([{fd=10, events=POLLIN|POLLHUP}], 1, 0) = 0 (Timeout)
write(10,
"0\10\0\0/\0\0\0\0\0\0\0>H\0\0\0\10\0\0\0\0\0\0\0\0\0\0\0\0\0\0"...,
2096) = 2096
poll([{fd=10, events=POLLIN|POLLHUP}], 1, 5000) = 1 ([{fd=10,
revents=POLLIN}])
read(10,
"\313\r\0\0\2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"...,
3496) = 3496
poll([{fd=10, events=POLLIN|POLLHUP}], 1, 5000) = 1 ([{fd=10,
revents=POLLIN}])
read(10, "/var/lib/samba/winbindd_privileg"..., 35) = 35
On the Samba4 / OpenChange side the communication looks like this.
[2013/11/08 21:03:59, 5]
../source4/winbind/wb_samba3_cmd.c:1223(wbsrv_samba3_getgroups)
wbsrv_samba3_getgroups called
[2013/11/08 21:03:59, 5]
../source4/winbind/wb_cmd_getgroups.c:60(wb_cmd_getgroups_send)
wb_cmd_getgroups_send called
[2013/11/08 21:03:59, 5]
../source4/winbind/wb_cmd_getpwnam.c:52(wb_cmd_getpwnam_send)
wb_cmd_getpwnam_send called
[2013/11/08 21:03:59, 5]
../source4/winbind/wb_name2domain.c:46(wb_name2domain_send)
wb_name2domain_send called
[2013/11/08 21:03:59, 5]
../source4/winbind/wb_sid2domain.c:256(wb_sid2domain_send)
wb_sid2domain_send called
[2013/11/08 21:03:59, 5]
../source4/winbind/wb_sid2domain.c:69(_wb_sid2domain_send)
wb_sid2domain_send called
[2013/11/08 21:04:04, 4]
../source4/dsdb/repl/drepl_notify.c:463(dreplsrv_notify_schedule)
dreplsrv_notify_schedule(5) scheduled for: Fri Nov 8 21:04:10 2013 CET
[2013/11/08 21:04:09, 4]
../source4/dsdb/repl/drepl_notify.c:463(dreplsrv_notify_schedule)
dreplsrv_notify_schedule(5) scheduled for: Fri Nov 8 21:04:15 2013 CET
[2013/11/08 21:04:14, 4]
../source4/dsdb/repl/drepl_notify.c:463(dreplsrv_notify_schedule)
dreplsrv_notify_schedule(5) scheduled for: Fri Nov 8 21:04:20 2013 CET
[2013/11/08 21:04:19, 4]
../source4/dsdb/repl/drepl_notify.c:463(dreplsrv_notify_schedule)
dreplsrv_notify_schedule(5) scheduled for: Fri Nov 8 21:04:25 2013 CET
[2013/11/08 21:04:24, 4]
../source4/dsdb/repl/drepl_notify.c:463(dreplsrv_notify_schedule)
dreplsrv_notify_schedule(5) scheduled for: Fri Nov 8 21:04:30 2013 CET
[2013/11/08 21:04:29, 4]
../source4/dsdb/repl/drepl_notify.c:463(dreplsrv_notify_schedule)
dreplsrv_notify_schedule(5) scheduled for: Fri Nov 8 21:04:35 2013 CET
[2013/11/08 21:04:34, 4]
../source4/dsdb/repl/drepl_notify.c:463(dreplsrv_notify_schedule)
dreplsrv_notify_schedule(5) scheduled for: Fri Nov 8 21:04:40 2013 CET
[2013/11/08 21:04:34, 3]
../source4/smbd/service_stream.c:63(stream_terminate_connection)
Terminating connection - 'wbsrv_call_loop:
tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED'
[2013/11/08 21:04:34, 5]
../source4/lib/messaging/messaging.c:554(imessaging_cleanup)
imessaging: cleaning up /var/lib/samba/private/smbd.tmp/msg/msg.0.37
[2013/11/08 21:04:34, 3]
../source4/smbd/process_single.c:104(single_terminate)
single_terminate: reason[wbsrv_call_loop:
tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED]
[2013/11/08 21:04:34, 3]
../source4/smbd/service_stream.c:63(stream_terminate_connection)
Terminating connection - 'wbsrv_call_loop:
tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED'
[2013/11/08 21:04:34, 5]
../source4/lib/messaging/messaging.c:554(imessaging_cleanup)
imessaging: cleaning up /var/lib/samba/private/smbd.tmp/msg/msg.0.35
[2013/11/08 21:04:34, 3]
../source4/smbd/process_single.c:104(single_terminate)
single_terminate: reason[wbsrv_call_loop:
tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED]
It looks like a problem on the winbind site of samba 4.
Any hints how to solve this problem or a place, where i might get help?
Best regards
Sebastian
- --
*Sebastian Hempel*
Veilchenweg 4 · 95195 Röslau · Germany
eMail: [email protected]
Google+: http://gplus.to/ithempel
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.11 (GNU/Linux)
Comment: Using GnuPG with Thunderbird - http://www.enigmail.net/
iEYEARECAAYFAlJ9SLUACgkQItHbzPF+Jku53wCfQj1h0anqEYbuVxz0qD/Q3iXk
xZMAoPxM9q/HoT89yhCnlOqGBq31MzX/
=rjND
-----END PGP SIGNATURE-----
_______________________________________________
devel mailing list
[email protected]
http://mailman.openchange.org/listinfo/devel
