Hi Folks,
I have done run successfully example.com domain with external IMAP/STMP
server. Now I move to production by changing the domain name by using
http://wiki.sogo.nu/ZEGReconfiguration documentation.
All process went well, SOGo web is working fine, but outlook keeps
saying that server is in offline (Outlook cannot logon). Only logs I can
find from Outlook anywhere mode is from Apache:
-------------
[Mon Sep 26 04:56:10.246795 2016] [wsgi:error] [pid 2773] RPCProxy started
[Mon Sep 26 04:56:10.247144 2016] [wsgi:error] [pid 2773]
INFO:openchange.web.auth.NTLMAuthHandler:client did not pass auth cookie
[Mon Sep 26 04:56:10.253964 2016] [wsgi:error] [pid 2773]
INFO:openchange.web.auth.NTLMAuthHandler:acquiring lock
/var/cache/ntlmauthhandler/ntlm-127.0.0.1.lock (2773)
[Mon Sep 26 04:56:10.275789 2016] [wsgi:error] [pid 3176]
INFO:openchange.web.auth.NTLMAuthHandler:NTLMAuthHandler daemon spawned
with pid 3176
[Mon Sep 26 04:56:10.781187 2016] [wsgi:error] [pid 2773] RPCProxy started
[Mon Sep 26 04:56:10.781549 2016] [wsgi:error] [pid 2773]
INFO:openchange.web.auth.NTLMAuthHandler:acquiring lock
/var/cache/ntlmauthhandler/ntlm-127.0.0.1.lock (2773)
[Mon Sep 26 04:56:10.888812 2016] [wsgi:error] [pid 2770] RPCProxy started
[Mon Sep 26 04:56:10.889136 2016] [wsgi:error] [pid 2770]
INFO:openchange.web.auth.NTLMAuthHandler:client did not pass auth cookie
[Mon Sep 26 04:56:10.889622 2016] [wsgi:error] [pid 2770]
INFO:openchange.web.auth.NTLMAuthHandler:acquiring lock
/var/cache/ntlmauthhandler/ntlm-127.0.0.1.lock (2770)
[Mon Sep 26 04:56:10.895183 2016] [wsgi:error] [pid 2773] [client
192.168.0.96:49253] [2773:RPC_IN_DATA:49253] WARNING: reattempting to
connect to OUT channel... (1/10)
[Mon Sep 26 04:56:10.895538 2016] [wsgi:error] [pid 2770] RPCProxy started
[Mon Sep 26 04:56:10.895764 2016] [wsgi:error] [pid 2770]
INFO:openchange.web.auth.NTLMAuthHandler:acquiring lock
/var/cache/ntlmauthhandler/ntlm-127.0.0.1.lock (2770)
[Mon Sep 26 04:56:11.940787 2016] [wsgi:error] [pid 2770] [client
192.168.0.96:49254] mod_wsgi (pid=2770): Exception occurred processing
WSGI script '/usr/lib/openchange/web/rpcproxy/rpcproxy.wsgi'.
[Mon Sep 26 04:56:11.940944 2016] [wsgi:error] [pid 2770] [client
192.168.0.96:49254] Traceback (most recent call last):
[Mon Sep 26 04:56:11.941038 2016] [wsgi:error] [pid 2770] [client
192.168.0.96:49254] File
"/usr/lib/openchange/web/rpcproxy/rpcproxy/channels.py", line 590, in
sequence
[Mon Sep 26 04:56:11.941381 2016] [wsgi:error] [pid 2770] [client
192.168.0.96:49254] for data in self._runloop():
[Mon Sep 26 04:56:11.941609 2016] [wsgi:error] [pid 2773] [client
192.168.0.96:49253] [2773:RPC_IN_DATA:49253] ERROR: client connection closed
[Mon Sep 26 04:56:11.941705 2016] [wsgi:error] [pid 2770] [client
192.168.0.96:49254] File
"/usr/lib/openchange/web/rpcproxy/rpcproxy/channels.py", line 538, in
_runloop
[Mon Sep 26 04:56:11.941796 2016] [wsgi:error] [pid 2770] [client
192.168.0.96:49254] = self._process_server_event(unix_fd, oc_fd, data)
[Mon Sep 26 04:56:11.942174 2016] [wsgi:error] [pid 2770] [client
192.168.0.96:49254] File
"/usr/lib/openchange/web/rpcproxy/rpcproxy/channels.py", line 479, in
_process_server_event
[Mon Sep 26 04:56:11.942267 2016] [wsgi:error] [pid 2770] [client
192.168.0.96:49254] oc_packet = RPCPacket.from_file(self.oc_conn,
self.logger)
[Mon Sep 26 04:56:11.942372 2016] [wsgi:error] [pid 2770] [client
192.168.0.96:49254] File
"/usr/lib/python2.7/dist-packages/openchange/utils/packets.py", line
206, in from_file
[Mon Sep 26 04:56:12.043416 2016] [wsgi:error] [pid 2770] [client
192.168.0.96:49254] values = unpack_from("<bbbblhhl", header_data)
[Mon Sep 26 04:56:12.043609 2016] [wsgi:error] [pid 2770] [client
192.168.0.96:49254] error: unpack_from requires a buffer of at least 16
bytes
[Mon Sep 26 04:56:34.799223 2016] [wsgi:error] [pid 2772] RPCProxy started
[Mon Sep 26 04:56:34.799604 2016] [wsgi:error] [pid 2772]
INFO:openchange.web.auth.NTLMAuthHandler:client did not pass auth cookie
[Mon Sep 26 04:56:34.800097 2016] [wsgi:error] [pid 2772]
INFO:openchange.web.auth.NTLMAuthHandler:acquiring lock
/var/cache/ntlmauthhandler/ntlm-127.0.0.1.lock (2772)
[Mon Sep 26 04:56:34.804048 2016] [wsgi:error] [pid 2772] RPCProxy started
[Mon Sep 26 04:56:34.804289 2016] [wsgi:error] [pid 2772]
INFO:openchange.web.auth.NTLMAuthHandler:acquiring lock
/var/cache/ntlmauthhandler/ntlm-127.0.0.1.lock (2772)
[Mon Sep 26 04:56:34.909127 2016] [wsgi:error] [pid 2771] RPCProxy started
[Mon Sep 26 04:56:34.909443 2016] [wsgi:error] [pid 2771]
INFO:openchange.web.auth.NTLMAuthHandler:client did not pass auth cookie
[Mon Sep 26 04:56:34.909928 2016] [wsgi:error] [pid 2771]
INFO:openchange.web.auth.NTLMAuthHandler:acquiring lock
/var/cache/ntlmauthhandler/ntlm-127.0.0.1.lock (2771)
[Mon Sep 26 04:56:34.914132 2016] [wsgi:error] [pid 2772] [client
192.168.0.96:49257] [2772:RPC_IN_DATA:49257] WARNING: reattempting to
connect to OUT channel... (1/10)
[Mon Sep 26 04:56:34.914470 2016] [wsgi:error] [pid 2771] RPCProxy started
[Mon Sep 26 04:56:34.914682 2016] [wsgi:error] [pid 2771]
INFO:openchange.web.auth.NTLMAuthHandler:acquiring lock
/var/cache/ntlmauthhandler/ntlm-127.0.0.1.lock (2771)
[Mon Sep 26 04:56:35.960882 2016] [wsgi:error] [pid 2771] [client
192.168.0.96:49258] mod_wsgi (pid=2771): Exception occurred processing
WSGI script '/usr/lib/openchange/web/rpcproxy/rpcproxy.wsgi'.
[Mon Sep 26 04:56:35.961056 2016] [wsgi:error] [pid 2771] [client
192.168.0.96:49258] Traceback (most recent call last):
[Mon Sep 26 04:56:35.961157 2016] [wsgi:error] [pid 2771] [client
192.168.0.96:49258] File
"/usr/lib/openchange/web/rpcproxy/rpcproxy/channels.py", line 590, in
sequence
[Mon Sep 26 04:56:35.961437 2016] [wsgi:error] [pid 2772] [client
192.168.0.96:49257] [2772:RPC_IN_DATA:49257] ERROR: client connection closed
[Mon Sep 26 04:56:35.961983 2016] [wsgi:error] [pid 2771] [client
192.168.0.96:49258] for data in self._runloop():
[Mon Sep 26 04:56:35.962066 2016] [wsgi:error] [pid 2771] [client
192.168.0.96:49258] File
"/usr/lib/openchange/web/rpcproxy/rpcproxy/channels.py", line 538, in
_runloop
[Mon Sep 26 04:56:35.962166 2016] [wsgi:error] [pid 2771] [client
192.168.0.96:49258] = self._process_server_event(unix_fd, oc_fd, data)
[Mon Sep 26 04:56:35.962239 2016] [wsgi:error] [pid 2771] [client
192.168.0.96:49258] File
"/usr/lib/openchange/web/rpcproxy/rpcproxy/channels.py", line 479, in
_process_server_event
[Mon Sep 26 04:56:35.962320 2016] [wsgi:error] [pid 2771] [client
192.168.0.96:49258] oc_packet = RPCPacket.from_file(self.oc_conn,
self.logger)
[Mon Sep 26 04:56:35.962393 2016] [wsgi:error] [pid 2771] [client
192.168.0.96:49258] File
"/usr/lib/python2.7/dist-packages/openchange/utils/packets.py", line
206, in from_file
[Mon Sep 26 04:56:35.962634 2016] [wsgi:error] [pid 2771] [client
192.168.0.96:49258] values = unpack_from("<bbbblhhl", header_data)
[Mon Sep 26 04:56:35.962745 2016] [wsgi:error] [pid 2771] [client
192.168.0.96:49258] error: unpack_from requires a buffer of at least 16
bytes
-------------
Also in syslog I see:
-------------
Sep 26 04:47:22 eslmail samba[2940]: [2016/09/26 04:47:22.277159, 0]
../lib/util/util_runcmd.c:328(samba_runcmd_io_handler)
Sep 26 04:47:22 eslmail samba[2940]: /usr/sbin/winbindd: Failed to
exec child - No such file or directory
Sep 26 04:47:22 eslmail samba[2940]: [2016/09/26 04:47:22.282139, 0]
../source4/winbind/winbindd.c:47(winbindd_done)
Sep 26 04:47:22 eslmail samba[2940]: winbindd daemon died with exit
status 255
Sep 26 04:47:22 eslmail samba[2940]: [2016/09/26 04:47:22.282857, 0]
../source4/smbd/service_task.c:35(task_server_terminate)
Sep 26 04:47:22 eslmail samba[2940]: task_server_terminate: [winbindd
child process exited]
Sep 26 04:47:22 eslmail samba[2940]: [2016/09/26 04:47:22.297236, 0]
../source4/smbd/server.c:211(samba_terminate)
Sep 26 04:47:22 eslmail samba[2940]: samba_terminate: winbindd child
process exited
Sep 26 04:47:23 eslmail samba[2933]: [2016/09/26 04:47:23.381252, 0]
libmapi/oc_log.c:52(oc_logv)
Sep 26 04:47:23 eslmail samba[2933]: MAPIPROXY server mode enabled
Sep 26 04:47:23 eslmail samba[2933]: [2016/09/26 04:47:23.402149, 0]
libmapi/oc_log.c:52(oc_logv)
Sep 26 04:47:23 eslmail samba[2933]:
mapiproxy/libmapiproxy/dcesrv_mapiproxy_server.c:187(mapiproxy_server_load):
MAPIPROXY proxy mode disabled
Sep 26 04:47:23 eslmail samba[2933]: [2016/09/26 04:47:23.402575, 0]
libmapi/oc_log.c:54(oc_logv)
Sep 26 04:47:23 eslmail samba[2933]:
mapiproxy/servers/default/nspi/dcesrv_exchange_nsp.c:1609(dcesrv_exchange_nsp_init):
dcesrv_exchange_nsp_init
Sep 26 04:47:23 eslmail samba[2933]: [2016/09/26 04:47:23.411348, 0]
libmapi/oc_log.c:54(oc_logv)
Sep 26 04:47:23 eslmail samba[2933]: Using MySQL backend for
openchangedb: mysql://openchange-user:openchange123@localhost/openchange
Sep 26 04:47:24 eslmail smbd[2932]: [2016/09/26 04:47:24.255341, 0]
../lib/util/become_daemon.c:124(daemon_ready)
Sep 26 04:47:24 eslmail smbd[2932]: STATUS=daemon 'smbd' finished
starting up and ready to serve connections
-------------
Do you have any idea where to look for the problem?
For http://wiki.sogo.nu/ZEGReconfiguration these notes I have found that
/etc/ocsmanager/ocsmanager.ini needs to updated too. But even with this
update, I have no luck with getting outlook working with new domain name.
Thanks in advance,
Madars
--
[email protected]
https://inverse.ca/sogo/lists
