2013-08-01T17:11:46.127029+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:11:46.130513+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Manager.StartUnit() on /org/freedesktop/systemd1 2013-08-01T17:11:46.138508+02:00 otto systemd[1]: Trying to enqueue job postfix.service/start/replace 2013-08-01T17:11:46.139266+02:00 otto systemd[1]: Installed new job postfix.service/start as 260 2013-08-01T17:11:46.140297+02:00 otto systemd[1]: Enqueued job postfix.service/start as 260 2013-08-01T17:11:46.140899+02:00 otto systemd[1]: Starting Postfix Mail Transport Agent... 2013-08-01T17:11:46.141454+02:00 otto systemd[1]: About to execute /bin/echo 'Starting mail service (Postfix)' 2013-08-01T17:11:46.152239+02:00 otto systemd[1]: Forked /bin/echo as 2052 2013-08-01T17:11:46.154386+02:00 otto systemd[1]: postfix.service changed failed -> start-pre 2013-08-01T17:11:46.156959+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Manager.GetUnit() on /org/freedesktop/systemd1 2013-08-01T17:11:46.157727+02:00 otto systemd[1]: Received SIGCHLD from PID 2052 (echo). 2013-08-01T17:11:46.159553+02:00 otto systemd[1]: Got SIGCHLD for process 2052 (echo) 2013-08-01T17:11:46.160366+02:00 otto systemd[1]: Child 2052 died (code=exited, status=0/SUCCESS) 2013-08-01T17:11:46.162110+02:00 otto systemd[1]: Child 2052 belongs to postfix.service 2013-08-01T17:11:46.162943+02:00 otto systemd[1]: postfix.service: control process exited, code=exited status=0 2013-08-01T17:11:46.164124+02:00 otto systemd[1]: postfix.service running next control command for state start-pre 2013-08-01T17:11:46.166870+02:00 otto systemd[1]: About to execute /etc/postfix/system/config_postfix_marco 2013-08-01T17:11:46.167659+02:00 otto systemd[1]: Forked /etc/postfix/system/config_postfix_marco as 2055 2013-08-01T17:11:46.168693+02:00 otto echo[2052]: Starting mail service (Postfix) 2013-08-01T17:11:46.196534+02:00 otto systemd[1]: Received SIGCHLD from PID 2055 (config_postfix_). 2013-08-01T17:11:46.197508+02:00 otto systemd[1]: Got SIGCHLD for process 2055 (config_postfix_) 2013-08-01T17:11:46.197938+02:00 otto systemd[1]: Child 2055 died (code=exited, status=0/SUCCESS) 2013-08-01T17:11:46.207229+02:00 otto systemd[1]: Child 2055 belongs to postfix.service 2013-08-01T17:11:46.208112+02:00 otto systemd[1]: postfix.service: control process exited, code=exited status=0 2013-08-01T17:11:46.209598+02:00 otto systemd[1]: postfix.service running next control command for state start-pre 2013-08-01T17:11:46.210223+02:00 otto systemd[1]: About to execute /etc/postfix/system/update_chroot 2013-08-01T17:11:46.211970+02:00 otto systemd[1]: Forked /etc/postfix/system/update_chroot as 2062 2013-08-01T17:11:46.213722+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:11:46.214389+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:11:46.214906+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:11:46.216035+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:11:46.218959+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:11:46.219614+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:11:46.223506+02:00 otto systemd[1]: Received SIGCHLD from PID 2062 (update_chroot). 2013-08-01T17:11:46.224494+02:00 otto systemd[1]: Got SIGCHLD for process 2062 (update_chroot) 2013-08-01T17:11:46.224508+02:00 otto systemd[1]: Child 2062 died (code=exited, status=0/SUCCESS) 2013-08-01T17:11:46.225066+02:00 otto systemd[1]: Child 2062 belongs to postfix.service 2013-08-01T17:11:46.234128+02:00 otto systemd[1]: postfix.service: control process exited, code=exited status=0 2013-08-01T17:11:46.235798+02:00 otto systemd[1]: postfix.service running next control command for state start-pre 2013-08-01T17:11:46.236418+02:00 otto systemd[1]: About to execute /etc/postfix/system/update_postmaps 2013-08-01T17:11:46.237860+02:00 otto systemd[1]: Forked /etc/postfix/system/update_postmaps as 2065 2013-08-01T17:11:46.238984+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:11:46.239935+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:11:46.240981+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:11:46.241982+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:11:46.243049+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:11:46.244261+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:11:46.251516+02:00 otto systemd[1]: Received SIGCHLD from PID 2065 (update_postmaps). 2013-08-01T17:11:46.251551+02:00 otto systemd[1]: Got SIGCHLD for process 2065 (update_postmaps) 2013-08-01T17:11:46.252494+02:00 otto systemd[1]: Child 2065 died (code=exited, status=0/SUCCESS) 2013-08-01T17:11:46.252881+02:00 otto systemd[1]: Child 2065 belongs to postfix.service 2013-08-01T17:11:46.253423+02:00 otto systemd[1]: postfix.service: control process exited, code=exited status=0 2013-08-01T17:11:46.264263+02:00 otto systemd[1]: postfix.service got final SIGCHLD for state start-pre 2013-08-01T17:11:46.265075+02:00 otto systemd[1]: About to execute /usr/sbin/postfix start 2013-08-01T17:11:46.266800+02:00 otto systemd[1]: Forked /usr/sbin/postfix as 2067 2013-08-01T17:11:46.268312+02:00 otto systemd[1]: postfix.service changed start-pre -> start 2013-08-01T17:11:46.269877+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:11:46.271005+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:11:46.273068+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:11:46.273799+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:11:46.274532+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:11:46.275182+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:11:48.595274+02:00 otto systemd[1]: Received SIGCHLD from PID 2067 (postfix-script). 2013-08-01T17:11:48.601516+02:00 otto systemd[1]: Got SIGCHLD for process 2067 (postfix-script) 2013-08-01T17:11:48.602523+02:00 otto systemd[1]: Child 2067 died (code=exited, status=0/SUCCESS) 2013-08-01T17:11:48.602760+02:00 otto systemd[1]: Child 2067 belongs to postfix.service 2013-08-01T17:11:48.603437+02:00 otto systemd[1]: postfix.service: control process exited, code=exited status=0 2013-08-01T17:11:48.604196+02:00 otto systemd[1]: postfix.service got final SIGCHLD for state start 2013-08-01T17:11:48.605004+02:00 otto systemd[1]: About to execute /etc/postfix/system/wait_qmgr 60 2013-08-01T17:11:48.606431+02:00 otto systemd[1]: Forked /etc/postfix/system/wait_qmgr as 2139 2013-08-01T17:11:48.608615+02:00 otto systemd[1]: postfix.service changed start -> start-post 2013-08-01T17:11:48.613113+02:00 otto systemd[1]: Received SIGCHLD from PID 2139 (wait_qmgr). 2013-08-01T17:11:48.620508+02:00 otto systemd[1]: Got SIGCHLD for process 2139 (wait_qmgr) 2013-08-01T17:11:48.620540+02:00 otto systemd[1]: Child 2139 died (code=exited, status=0/SUCCESS) 2013-08-01T17:11:48.620545+02:00 otto systemd[1]: Child 2139 belongs to postfix.service 2013-08-01T17:11:48.621388+02:00 otto systemd[1]: postfix.service: control process exited, code=exited status=0 2013-08-01T17:11:48.622169+02:00 otto systemd[1]: postfix.service running next control command for state start-post 2013-08-01T17:11:48.622930+02:00 otto systemd[1]: About to execute /etc/postfix/system/cond_slp register 2013-08-01T17:11:48.624309+02:00 otto systemd[1]: Forked /etc/postfix/system/cond_slp as 2141 2013-08-01T17:11:48.627010+02:00 otto systemd[1]: Got SIGCHLD for process 2140 (wait_qmgr) 2013-08-01T17:11:48.629203+02:00 otto systemd[1]: Child 2140 died (code=killed, status=9/KILL) 2013-08-01T17:11:48.631938+02:00 otto systemd[1]: Received SIGCHLD from PID 2140 (n/a). 2013-08-01T17:11:48.632700+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:11:48.633459+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:11:48.634227+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:11:48.641514+02:00 otto systemd[1]: Received SIGCHLD from PID 2141 (cond_slp). 2013-08-01T17:11:48.641779+02:00 otto systemd[1]: Got SIGCHLD for process 2141 (cond_slp) 2013-08-01T17:11:48.642508+02:00 otto systemd[1]: Child 2141 died (code=exited, status=0/SUCCESS) 2013-08-01T17:11:48.643286+02:00 otto systemd[1]: Child 2141 belongs to postfix.service 2013-08-01T17:11:48.645150+02:00 otto systemd[1]: postfix.service: control process exited, code=exited status=0 2013-08-01T17:11:48.646770+02:00 otto systemd[1]: postfix.service got final SIGCHLD for state start-post 2013-08-01T17:11:48.647456+02:00 otto systemd[1]: PID 3152 read from file /var/spool/postfix/pid/master.pid does not exist. 2013-08-01T17:11:48.648296+02:00 otto systemd[1]: Setting watch for postfix.service's PID file /var/spool/postfix/pid/master.pid 2013-08-01T17:11:48.649054+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:11:48.649816+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:11:48.650645+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:12:01.289811+02:00 otto /usr/sbin/cron[2146]: pam_unix(crond:session): session opened for user root by (uid=0) 2013-08-01T17:12:01.291508+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.NameOwnerChanged() on /org/freedesktop/DBus 2013-08-01T17:12:01.308251+02:00 otto /USR/SBIN/CRON[2147]: (root) CMD (. /etc/profile; . /etc/marco; [ :$UNAME = :$MAIN ] || [ -f /etc/.local ] || exit 0; user=cron $u/lib/Xup -az all >/dev/null 2>&1 || :) 2013-08-01T17:12:01.308287+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.NameOwnerChanged() on /org/freedesktop/DBus 2013-08-01T17:12:01.322283+02:00 otto /USR/SBIN/CRON[2146]: pam_unix(crond:session): session closed for user root 2013-08-01T17:12:01.325640+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.NameOwnerChanged() on /org/freedesktop/DBus 2013-08-01T17:12:01.326539+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.NameOwnerChanged() on /org/freedesktop/DBus 2013-08-01T17:12:01.328177+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:12:01.329806+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:12:01.330794+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:12:01.332550+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:12:01.334423+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:12:01.335431+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:12:01.337524+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:12:01.338815+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:12:01.339676+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:13:18.614132+02:00 otto systemd[1]: postfix.service operation timed out. Stopping. 2013-08-01T17:13:18.622523+02:00 otto systemd[1]: About to execute /usr/sbin/postfix stop 2013-08-01T17:13:18.622553+02:00 otto systemd[1]: Forked /usr/sbin/postfix as 2161 2013-08-01T17:13:18.624080+02:00 otto systemd[1]: Stopping watch for postfix.service's PID file /var/spool/postfix/pid/master.pid 2013-08-01T17:13:18.625076+02:00 otto systemd[1]: postfix.service changed start-post -> stop 2013-08-01T17:13:18.651504+02:00 otto systemd[1]: Received SIGCHLD from PID 2142 (pickup). 2013-08-01T17:13:18.651520+02:00 otto systemd[1]: Got SIGCHLD for process 2138 (master) 2013-08-01T17:13:18.651905+02:00 otto systemd[1]: Child 2138 died (code=killed, status=15/TERM) 2013-08-01T17:13:18.652562+02:00 otto systemd[1]: Got SIGCHLD for process 2142 (pickup) 2013-08-01T17:13:18.653304+02:00 otto systemd[1]: Child 2142 died (code=killed, status=15/TERM) 2013-08-01T17:13:18.654010+02:00 otto systemd[1]: Got SIGCHLD for process 2143 (qmgr) 2013-08-01T17:13:18.655137+02:00 otto systemd[1]: Child 2143 died (code=killed, status=15/TERM) 2013-08-01T17:13:18.659243+02:00 otto systemd[1]: Received SIGCHLD from PID 2161 (postfix-script). 2013-08-01T17:13:18.660055+02:00 otto systemd[1]: Got SIGCHLD for process 2161 (postfix-script) 2013-08-01T17:13:18.660756+02:00 otto systemd[1]: Child 2161 died (code=exited, status=0/SUCCESS) 2013-08-01T17:13:18.661356+02:00 otto systemd[1]: Child 2161 belongs to postfix.service 2013-08-01T17:13:18.669090+02:00 otto systemd[1]: postfix.service: control process exited, code=exited status=0 2013-08-01T17:13:18.671097+02:00 otto systemd[1]: postfix.service got final SIGCHLD for state stop 2013-08-01T17:13:18.674412+02:00 otto systemd[1]: About to execute /etc/postfix/system/cond_slp deregister 2013-08-01T17:13:18.675347+02:00 otto systemd[1]: Forked /etc/postfix/system/cond_slp as 2172 2013-08-01T17:13:18.677170+02:00 otto systemd[1]: postfix.service changed stop -> stop-post 2013-08-01T17:13:18.677944+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:13:18.679051+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:13:18.681990+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:13:18.682749+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:13:18.683601+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:13:18.684514+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:13:18.686713+02:00 otto systemd[1]: Received SIGCHLD from PID 2172 (cond_slp). 2013-08-01T17:13:18.687589+02:00 otto systemd[1]: Got SIGCHLD for process 2172 (cond_slp) 2013-08-01T17:13:18.689221+02:00 otto systemd[1]: Child 2172 died (code=exited, status=0/SUCCESS) 2013-08-01T17:13:18.690248+02:00 otto systemd[1]: Child 2172 belongs to postfix.service 2013-08-01T17:13:18.691926+02:00 otto systemd[1]: postfix.service: control process exited, code=exited status=0 2013-08-01T17:13:18.695747+02:00 otto systemd[1]: postfix.service got final SIGCHLD for state stop-post 2013-08-01T17:13:18.696619+02:00 otto systemd[1]: postfix.service changed stop-post -> failed 2013-08-01T17:13:18.697436+02:00 otto systemd[1]: Job postfix.service/start finished, result=failed 2013-08-01T17:13:18.698333+02:00 otto systemd[1]: Failed to start Postfix Mail Transport Agent. 2013-08-01T17:13:18.699308+02:00 otto systemd[1]: Unit postfix.service entered failed state 2013-08-01T17:13:18.700112+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:13:18.700834+02:00 otto systemd[1]: Accepted connection on private bus. 2013-08-01T17:13:18.701526+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent 2013-08-01T17:13:18.702288+02:00 otto systemd[1]: postfix.service: cgroup is empty 2013-08-01T17:13:18.703037+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local 2013-08-01T17:13:18.703759+02:00 otto systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent